[86.587773] Initializing watchdogs
[86.587993]   /dev/watchdog0
[86.626427] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[86.641221] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[86.648598] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[86.657087] [001/131] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[86.880325] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[89.869917] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SUCCESS (2.990s)
[90.099166] [002/131] (957s left) kms_setmode (clone-exclusive-crtc)
[90.222159] Starting subtest: clone-exclusive-crtc
[90.761945] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2
[91.029076] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.264s)
[91.502667] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2
[91.755142] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.263s)
[92.161117] Subtest clone-exclusive-crtc: SUCCESS (1.946s)
[92.488105] [003/131] (954s left) kms_cursor_crc (cursor-dpms)
[92.682838] Starting subtest: cursor-dpms
[92.686161] Starting dynamic subtest: pipe-A-HDMI-A-1
[94.413395] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.725s)
[94.445494] Starting dynamic subtest: pipe-C-HDMI-A-1
[95.769223] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.356s)
[95.772422] Subtest cursor-dpms: SUCCESS (3.087s)
[96.108445] [004/131] (951s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[96.299971] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[96.303159] Starting dynamic subtest: pipe-A-valid-mode
[97.272405] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.968s)
[97.276933] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SUCCESS (0.971s)
[97.600833] [005/131] (949s left) kms_addfb_basic (basic-x-tiled-legacy)
[97.711548] Starting subtest: basic-x-tiled-legacy
[97.715678] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[97.988918] [006/131] (949s left) api_intel_bb (lot-of-buffers)
[98.097642] Starting subtest: lot-of-buffers
[103.775064] Subtest lot-of-buffers: SUCCESS (5.679s)
[104.180183] [007/131] (943s left) kms_tiled_display (basic-test-pattern)
[104.437232] Starting subtest: basic-test-pattern
[106.185881] Subtest basic-test-pattern: FAIL (0.921s)
[106.267433] [008/131] (941s left) gem_wait (busy)
[106.414570] Starting subtest: busy
[106.417594] Starting dynamic subtest: all-engines
[106.939584] Dynamic subtest all-engines: SUCCESS (0.528s)
[106.957882] Starting dynamic subtest: rcs0
[107.459323] Dynamic subtest rcs0: SUCCESS (0.517s)
[107.477376] Starting dynamic subtest: bcs0
[107.978304] Dynamic subtest bcs0: SUCCESS (0.513s)
[107.995872] Starting dynamic subtest: vcs0
[108.488484] Dynamic subtest vcs0: SUCCESS (0.515s)
[108.506562] Starting dynamic subtest: vecs0
[109.010468] Dynamic subtest vecs0: SUCCESS (0.515s)
[109.014963] Subtest busy: SUCCESS (2.593s)
[109.330916] [009/131] (938s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[109.463126] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[109.517183] Starting dynamic subtest: pipe-A-valid-mode
[110.405031] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.934s)
[110.408989] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (0.938s)
[110.706144] [010/131] (937s left) gem_madvise (dontneed-before-exec)
[110.810959] Starting subtest: dontneed-before-exec
[110.818732] Subtest dontneed-before-exec: SUCCESS (0.010s)
[110.989668] [011/131] (937s left) gem_gpgpu_fill (offset-16x16)
[111.099732] Starting subtest: offset-16x16
[111.111946] Subtest offset-16x16: SUCCESS (0.011s)
[111.284223] [012/131] (936s left) gem_exec_fence (nb-await)
[111.537329] Starting subtest: nb-await
[111.541527] Starting dynamic subtest: rcs0
[111.614661] Dynamic subtest rcs0: SUCCESS (0.075s)
[111.634092] Starting dynamic subtest: bcs0
[111.690352] Dynamic subtest bcs0: SUCCESS (0.074s)
[111.708730] Starting dynamic subtest: vcs0
[111.762380] Dynamic subtest vcs0: SUCCESS (0.070s)
[111.781833] Starting dynamic subtest: vecs0
[111.829746] Dynamic subtest vecs0: SUCCESS (0.068s)
[111.833263] Subtest nb-await: SUCCESS (0.294s)
[112.627541] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true
[112.634247] [013/131] (935s left) gem_exec_schedule (lateslice)
[112.767328] Starting subtest: lateslice
[112.770577] Starting dynamic subtest: rcs0
[112.797116] Dynamic subtest rcs0: SUCCESS (0.031s)
[112.816561] Starting dynamic subtest: bcs0
[112.836750] Dynamic subtest bcs0: SUCCESS (0.037s)
[112.855588] Starting dynamic subtest: vcs0
[112.870084] Dynamic subtest vcs0: SUCCESS (0.030s)
[112.889281] Starting dynamic subtest: vecs0
[112.903840] Dynamic subtest vecs0: SUCCESS (0.032s)
[112.907946] Subtest lateslice: SUCCESS (0.137s)
[113.331284] [014/131] (935s left) perf (gen12-unprivileged-single-ctx-counters)
[113.578458] Starting subtest: gen12-unprivileged-single-ctx-counters
[113.586285] Subtest gen12-unprivileged-single-ctx-counters: SKIP (0.000s)
[113.686466] [015/131] (934s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[114.199226] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[114.481383] [016/131] (934s left) api_intel_allocator (reloc-allocator)
[114.594181] Starting subtest: reloc-allocator
[114.597353] Starting dynamic subtest: basic
[114.605670] Dynamic subtest basic: SUCCESS (0.009s)
[114.627897] Starting dynamic subtest: parallel-one
[114.631810] Dynamic subtest parallel-one: SUCCESS (0.027s)
[114.662340] Starting dynamic subtest: print
[114.665567] Dynamic subtest print: SUCCESS (0.000s)
[114.696176] Starting dynamic subtest: reuse
[114.700402] Dynamic subtest reuse: SUCCESS (0.000s)
[114.719631] Starting dynamic subtest: fork-reopen-allocator
[114.723913] Dynamic subtest fork-reopen-allocator: SUCCESS (0.063s)
[114.728090] Subtest reloc-allocator: SUCCESS (0.105s)
[114.896725] [017/131] (933s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[115.053196] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt
[115.059614] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[115.240783] [018/131] (933s left) kms_flip (flip-vs-rmfb-interruptible)
[115.393444] Starting subtest: flip-vs-rmfb-interruptible
[115.396664] Starting dynamic subtest: A-HDMI-A1
[120.978631] Dynamic subtest A-HDMI-A1: SUCCESS (5.582s)
[120.998594] Starting dynamic subtest: B-HDMI-A1
[126.310420] Dynamic subtest B-HDMI-A1: SUCCESS (5.327s)
[126.330423] Starting dynamic subtest: C-HDMI-A1
[131.645961] Dynamic subtest C-HDMI-A1: SUCCESS (5.333s)
[131.666762] Starting dynamic subtest: A-HDMI-A2
[136.976814] Dynamic subtest A-HDMI-A2: SUCCESS (5.326s)
[136.997600] Starting dynamic subtest: B-HDMI-A2
[142.315982] Dynamic subtest B-HDMI-A2: SUCCESS (5.335s)
[142.336280] Starting dynamic subtest: C-HDMI-A2
[147.647945] Dynamic subtest C-HDMI-A2: SUCCESS (5.328s)
[147.651242] Subtest flip-vs-rmfb-interruptible: SUCCESS (32.257s)
[148.173799] [019/131] (900s left) gem_ccs (block-copy-uncompressed)
[148.292681] Subtest block-copy-uncompressed: SKIP (0.000s)
[148.419690] [020/131] (900s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[148.559209] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[148.562420] Starting dynamic subtest: pipe-A-HDMI-A-1
[148.717477] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.011s)
[148.767030] Starting dynamic subtest: pipe-A-HDMI-A-2
[148.906552] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.014s)
[148.926035] Starting dynamic subtest: pipe-B-HDMI-A-1
[149.055580] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.024s)
[149.076090] Starting dynamic subtest: pipe-B-HDMI-A-2
[149.237526] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.027s)
[149.257070] Starting dynamic subtest: pipe-C-HDMI-A-1
[149.396334] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.024s)
[149.415354] Starting dynamic subtest: pipe-C-HDMI-A-2
[149.557888] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.024s)
[149.564322] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.169s)
[149.682983] [021/131] (899s left) kms_psr (fbc-pr-sprite-plane-move)
[149.839255] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[149.947189] [022/131] (899s left) kms_debugfs (display-on-read-all)
[150.089591] Starting subtest: display-on-read-all
[150.255657] Subtest display-on-read-all: SUCCESS (0.169s)
[150.500631] [023/131] (898s left) gem_userptr_blits (dmabuf-sync)
[150.640163] Starting subtest: dmabuf-sync
[150.651614] Subtest dmabuf-sync: SKIP (0.007s)
[150.865970] [024/131] (898s left) gen9_exec_parse (bb-chained)
[151.000174] Starting subtest: bb-chained
[151.010352] Subtest bb-chained: SUCCESS (0.012s)
[151.316692] [025/131] (898s left) kms_cursor_edge_walk (128x128-right-edge)
[151.492415] Starting subtest: 128x128-right-edge
[151.499571] Starting dynamic subtest: pipe-A-HDMI-A-1
[155.274675] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.825s)
[155.316993] Starting dynamic subtest: pipe-C-HDMI-A-1
[158.713916] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.435s)
[158.718227] Subtest 128x128-right-edge: SUCCESS (7.270s)
[159.030503] [026/131] (890s left) kms_chamelium_color (ctm-0-75)
[159.164634] Subtest ctm-0-75: SKIP (0.000s)
[159.275164] [027/131] (890s left) gem_exec_params (secure-non-master)
[159.394693] Starting subtest: secure-non-master
[159.403135] Subtest secure-non-master: SKIP (0.000s)
[159.573932] [028/131] (890s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[159.731166] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[159.737646] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[159.909333] [029/131] (889s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[160.073813] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[160.081548] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[160.261445] [030/131] (889s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[160.424843] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[163.397337] Subtest fbc-rgb101010-draw-mmap-gtt: SUCCESS (2.976s)
[163.676686] [031/131] (886s left) kms_big_fb (yf-tiled-addfb)
[163.843578] Starting subtest: yf-tiled-addfb
[163.859936] Subtest yf-tiled-addfb: SUCCESS (0.000s)
[164.029532] [032/131] (886s left) gem_exec_schedule (u-semaphore-user)
[164.177124] Starting subtest: u-semaphore-user
[164.194852] Subtest u-semaphore-user: SUCCESS (0.018s)
[164.941789] [033/131] (885s left) kms_psr (psr-primary-blt)
[165.467354] Subtest psr-primary-blt: SKIP (0.000s)
[165.825886] [034/131] (885s left) gem_exec_whisper (basic-contexts-all)
[165.958395] Starting subtest: basic-contexts-all
[176.767334] Subtest basic-contexts-all: SUCCESS (10.805s)
[177.059631] [035/131] (873s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[177.216511] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[177.223730] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[177.396994] [036/131] (873s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[177.556511] Starting subtest: psr-shrfb-scaledprimary
[177.563969] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[177.748107] [037/131] (873s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[177.929121] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[178.029310] [038/131] (873s left) gem_exec_reloc (basic-wc-read-noreloc)
[178.154077] Starting subtest: basic-wc-read-noreloc
[178.158072] Subtest basic-wc-read-noreloc: SUCCESS (0.008s)
[178.363526] [039/131] (873s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[178.519227] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[178.525922] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[178.714137] [040/131] (872s left) syncobj_timeline (single-wait-all-available-signaled)
[178.826604] Starting subtest: single-wait-all-available-signaled
[178.830166] Subtest single-wait-all-available-signaled: SUCCESS (0.001s)
[179.010984] [041/131] (872s left) gem_mmap_gtt (basic-write-read)
[179.126144] Starting subtest: basic-write-read
[179.130191] Subtest basic-write-read: SUCCESS (0.010s)
[179.325404] [042/131] (872s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[179.483506] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[179.490074] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[179.663292] [043/131] (872s left) gem_exec_capture (pi)
[179.787903] Starting subtest: pi
[179.794207] Starting dynamic subtest: rcs0
[198.858210] Dynamic subtest rcs0: SUCCESS (19.070s)
[198.881081] Starting dynamic subtest: bcs0
[217.376961] Dynamic subtest bcs0: SUCCESS (18.519s)
[217.400395] Starting dynamic subtest: vcs0
[235.904294] Dynamic subtest vcs0: SUCCESS (18.523s)
[235.936118] Starting dynamic subtest: vecs0
[254.450237] Dynamic subtest vecs0: SUCCESS (18.539s)
[254.454185] Subtest pi: SUCCESS (74.667s)
[254.636662] [044/131] (797s left) kms_psr (pr-primary-page-flip)
[254.928169] Subtest pr-primary-page-flip: SKIP (0.000s)
[255.042496] [045/131] (797s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[255.165906] Starting subtest: saturated-hostile-nopreempt
[255.169555] Starting dynamic subtest: rcs0
[255.918335] Dynamic subtest rcs0: SUCCESS (0.748s)
[255.943265] Starting dynamic subtest: bcs0
[256.672677] Dynamic subtest bcs0: SUCCESS (0.751s)
[256.697952] Starting dynamic subtest: vcs0
[257.429589] Dynamic subtest vcs0: SUCCESS (0.750s)
[257.458676] Starting dynamic subtest: vecs0
[258.184881] Dynamic subtest vecs0: SUCCESS (0.752s)
[258.189934] Subtest saturated-hostile-nopreempt: SUCCESS (3.014s)
[258.382633] [046/131] (793s left) kms_addfb_basic (clobberred-modifier)
[258.497526] Starting subtest: clobberred-modifier
[258.501053] Subtest clobberred-modifier: SUCCESS (0.000s)
[258.717361] [047/131] (793s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[258.863688] Starting subtest: basic-flip-before-cursor-varying-size
[260.275568] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.416s)
[260.605348] [048/131] (791s left) gem_pxp (reject-modify-context-protection-off-1)
[263.259616] Starting subtest: reject-modify-context-protection-off-1
[263.266983] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[263.437304] [049/131] (789s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[263.571806] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[263.575399] Starting dynamic subtest: pipe-A-HDMI-A-1
[263.610685] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[263.634248] Starting dynamic subtest: pipe-A-HDMI-A-2
[263.669989] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[263.693843] Starting dynamic subtest: pipe-B-HDMI-A-1
[263.726439] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[263.749938] Starting dynamic subtest: pipe-B-HDMI-A-2
[263.783199] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s)
[263.805904] Starting dynamic subtest: pipe-C-HDMI-A-1
[263.837567] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[263.859664] Starting dynamic subtest: pipe-C-HDMI-A-2
[263.892606] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[263.899562] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.029s)
[264.527128] [050/131] (788s left) gem_ctx_persistence (engines-cleanup)
[264.650479] Starting subtest: engines-cleanup
[264.655120] Starting dynamic subtest: rcs0
[264.674730] Dynamic subtest rcs0: SUCCESS (0.019s)
[264.699144] Starting dynamic subtest: bcs0
[264.733990] Dynamic subtest bcs0: SUCCESS (0.060s)
[264.757735] Starting dynamic subtest: vcs0
[264.761968] Dynamic subtest vcs0: SUCCESS (0.023s)
[264.785316] Starting dynamic subtest: vecs0
[264.788758] Dynamic subtest vecs0: SUCCESS (0.025s)
[264.794075] Subtest engines-cleanup: SUCCESS (0.142s)
[264.990987] [051/131] (787s left) kms_setmode (invalid-clone-single-crtc)
[265.122608] Starting subtest: invalid-clone-single-crtc
[265.523118] Starting dynamic subtest: pipe-A-HDMI-A-1-HDMI-A-2
[265.561070] Dynamic subtest pipe-A-HDMI-A-1-HDMI-A-2: SUCCESS (0.032s)
[265.598673] Starting dynamic subtest: pipe-B-HDMI-A-1-HDMI-A-2
[265.614997] Dynamic subtest pipe-B-HDMI-A-1-HDMI-A-2: SUCCESS (0.035s)
[265.620844] Subtest invalid-clone-single-crtc: SUCCESS (0.525s)
[265.945978] [052/131] (786s left) gem_exec_reloc (basic-concurrent0)
[266.075404] Starting subtest: basic-concurrent0
[271.517026] Subtest basic-concurrent0: SUCCESS (5.445s)
[271.744171] [053/131] (781s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[271.903310] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[271.908941] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[272.077079] [054/131] (780s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[272.234193] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[274.765749] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SUCCESS (2.536s)
[275.020646] [055/131] (778s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[275.189864] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[275.197502] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[275.380527] [056/131] (777s left) gem_exec_reloc (basic-write-gtt)
[275.496182] Starting subtest: basic-write-gtt
[275.513746] Subtest basic-write-gtt: SUCCESS (0.017s)
[275.745862] [057/131] (777s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[275.911159] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[275.917901] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[276.092736] [058/131] (777s left) kms_flip (2x-flip-vs-suspend)
[276.240384] Starting subtest: 2x-flip-vs-suspend
[276.244286] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2