[92.599416] Initializing watchdogs
[92.600632]   /dev/watchdog0
[92.641535] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[92.656666] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[92.663603] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[92.668992] [001/156] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[93.303934] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[93.654534] [002/156] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[93.859226] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[93.862911] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[94.008342] [003/156] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[94.207406] Starting subtest: short-busy-flip-before-cursor-toggle
[94.209316] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[94.338600] [004/156] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[94.529498] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[94.534814] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[94.670095] [005/156] (958s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[94.880085] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[94.887287] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[105.783357] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.897s)
[105.805562] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[116.570688] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.784s)
[116.594333] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[121.526916] Dynamic subtest BC-HDMI-A1-HDMI-A2: FAIL (4.680s)
[121.527814] Subtest 2x-flip-vs-expired-vblank-interruptible: FAIL (26.380s)
[121.927647] [006/156] (931s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[122.133120] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[122.137354] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[122.240485] [007/156] (931s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[122.430786] Starting subtest: fbc-farfromfence-mmap-gtt
[125.426077] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (2.991s)
[125.646076] [008/156] (928s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[125.847502] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[125.849573] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[125.954839] [009/156] (927s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[126.159156] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[126.162092] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[126.270466] [010/156] (927s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[126.466488] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[126.470317] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[126.571491] [011/156] (927s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[126.780192] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[126.783303] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[126.889849] [012/156] (927s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[127.105274] Starting subtest: x-tiled-16bpp-rotate-90
[127.492214] Subtest x-tiled-16bpp-rotate-90: SKIP (0.384s)
[127.729573] [013/156] (926s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[127.923402] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[127.925141] Starting dynamic subtest: pipe-A
[128.807556] Dynamic subtest pipe-A: SUCCESS (0.889s)
[128.842992] Starting dynamic subtest: pipe-B
[129.720073] Dynamic subtest pipe-B: SUCCESS (0.912s)
[129.759228] Starting dynamic subtest: pipe-C
[130.649297] Dynamic subtest pipe-C: SUCCESS (0.930s)
[130.650679] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.733s)
[131.237066] [014/156] (922s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[131.433544] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[131.434524] Starting dynamic subtest: pipe-A-HDMI-A-1
[131.451188] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[131.488820] Starting dynamic subtest: pipe-A-HDMI-A-2
[131.519565] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[131.553596] Starting dynamic subtest: pipe-B-HDMI-A-1
[131.582293] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[131.616426] Starting dynamic subtest: pipe-B-HDMI-A-2
[131.646379] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.006s)
[131.670715] Starting dynamic subtest: pipe-C-HDMI-A-1
[131.679434] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.005s)
[131.698119] Starting dynamic subtest: pipe-C-HDMI-A-2
[131.705806] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.004s)
[131.708453] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.051s)
[132.512758] [015/156] (921s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[132.702192] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[132.704809] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[132.801591] [016/156] (921s left) gem_create (create-ext-set-pat)
[133.033537] Starting subtest: create-ext-set-pat
[133.036618] Subtest create-ext-set-pat: SKIP (0.000s)
[133.132143] [017/156] (921s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[133.235677] Starting subtest: etime-single-wait-for-submit-unsubmitted
[133.345181] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[133.434700] [018/156] (921s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[133.646173] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[133.648270] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[133.752404] [019/156] (920s left) gem_ctx_param (set-priority-invalid-size)
[133.857717] Starting subtest: set-priority-invalid-size
[133.858744] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[133.951475] [020/156] (920s left) kms_psr (psr2-primary-render)
[134.140443] Subtest psr2-primary-render: SKIP (0.000s)
[134.237386] [021/156] (920s left) gem_userptr_blits (create-destroy-sync)
[134.388981] Starting subtest: create-destroy-sync
[139.443132] Subtest create-destroy-sync: SUCCESS (5.048s)
[139.567450] [022/156] (915s left) i915_query (query-garbage)
[139.669932] Starting subtest: query-garbage
[139.674597] Subtest query-garbage: SUCCESS (0.001s)
[139.769212] [023/156] (915s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[139.888146] Starting subtest: y-tiled-to-vebox-x-tiled
[139.890713] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[140.107225] [024/156] (914s left) gem_exec_reloc (basic-gtt-wc)
[140.224685] Starting subtest: basic-gtt-wc
[140.247836] Subtest basic-gtt-wc: SUCCESS (0.023s)
[140.431741] [025/156] (914s left) i915_pm_rpm (gem-pread)
[142.351932] Starting subtest: gem-pread
[143.065276] Subtest gem-pread: SUCCESS (0.710s)
[143.397635] [026/156] (911s left) kms_psr (psr2-basic)
[143.586235] Subtest psr2-basic: SKIP (0.000s)
[143.691561] [027/156] (911s left) gem_tiled_partial_pwrite_pread (reads)
[143.812794] Starting subtest: reads
[145.256978] Subtest reads: SUCCESS (1.447s)
[145.369603] [028/156] (909s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[145.471092] Starting subtest: single-wait-for-submit-available-submitted
[145.473313] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.002s)
[145.570448] [029/156] (909s left) syncobj_wait (multi-wait-submitted)
[145.683112] Starting subtest: multi-wait-submitted
[145.793412] Subtest multi-wait-submitted: SUCCESS (0.108s)
[145.888168] [030/156] (909s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[146.098891] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[146.102271] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[146.204694] [031/156] (909s left) gem_exec_parallel (basic)
[146.332138] Starting subtest: basic
[146.336204] Starting dynamic subtest: rcs0
[149.628349] Dynamic subtest rcs0: SUCCESS (3.291s)
[149.655802] Starting dynamic subtest: bcs0
[153.128224] Dynamic subtest bcs0: SUCCESS (3.501s)
[153.155893] Starting dynamic subtest: vcs0
[156.404272] Dynamic subtest vcs0: SUCCESS (3.273s)
[156.432895] Starting dynamic subtest: vecs0
[159.644837] Dynamic subtest vecs0: SUCCESS (3.240s)
[159.646118] Subtest basic: SUCCESS (13.312s)
[159.874209] [032/156] (895s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[160.083261] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[162.151613] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.067s)
[162.318851] [033/156] (893s left) syncobj_wait (single-wait-for-submit-submitted)
[162.426167] Starting subtest: single-wait-for-submit-submitted
[162.535704] Subtest single-wait-for-submit-submitted: SUCCESS (0.109s)
[162.635338] [034/156] (893s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[162.827108] Starting subtest: fbcpsr-rgb565-draw-blt
[162.830183] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[162.934774] [035/156] (892s left) perf_pmu (busy-accuracy-98)
[163.051706] Starting subtest: busy-accuracy-98
[163.054107] Starting dynamic subtest: rcs0
[166.111185] Dynamic subtest rcs0: SUCCESS (3.057s)
[166.134986] Starting dynamic subtest: bcs0
[169.172692] Dynamic subtest bcs0: SUCCESS (3.059s)
[169.193594] Starting dynamic subtest: vcs0
[172.225189] Dynamic subtest vcs0: SUCCESS (3.051s)
[172.246437] Starting dynamic subtest: vecs0
[175.276841] Dynamic subtest vecs0: SUCCESS (3.052s)
[175.277712] Subtest busy-accuracy-98: SUCCESS (12.226s)
[175.388198] [036/156] (880s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[175.571220] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[175.659654] [037/156] (880s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[175.855068] Starting subtest: cursorB-vs-flipB-toggle
[187.197838] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.342s)
[187.522084] [038/156] (868s left) syncobj_wait (wait-all-delayed-signal)
[187.645074] Starting subtest: wait-all-delayed-signal
[187.748922] Subtest wait-all-delayed-signal: SUCCESS (0.105s)
[187.863984] [039/156] (868s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[188.083274] Starting subtest: psr-rgb565-draw-mmap-wc
[188.087240] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[188.200924] [040/156] (868s left) gem_wait (busy)
[188.347290] Starting subtest: busy
[188.351248] Starting dynamic subtest: all-engines
[188.871193] Dynamic subtest all-engines: SUCCESS (0.524s)
[188.893367] Starting dynamic subtest: rcs0
[189.383510] Dynamic subtest rcs0: SUCCESS (0.511s)
[189.408228] Starting dynamic subtest: bcs0
[189.899825] Dynamic subtest bcs0: SUCCESS (0.514s)
[189.923531] Starting dynamic subtest: vcs0
[190.417983] Dynamic subtest vcs0: SUCCESS (0.513s)
[190.440045] Starting dynamic subtest: vecs0
[190.930043] Dynamic subtest vecs0: SUCCESS (0.511s)
[190.932042] Subtest busy: SUCCESS (2.586s)
[191.177393] [041/156] (865s left) gem_close_race (basic-threads)
[191.313508] Starting subtest: basic-threads
[192.469264] Subtest basic-threads: SUCCESS (1.158s)
[192.678650] [042/156] (863s left) kms_lease (possible-crtcs-filtering)
[192.870446] Starting subtest: possible-crtcs-filtering
[192.877563] Subtest possible-crtcs-filtering: SUCCESS (0.012s)
[193.004583] [043/156] (863s left) prime_busy (hang)
[193.126736] Starting subtest: hang
[193.127734] Starting dynamic subtest: rcs0
[199.660582] Dynamic subtest rcs0: SUCCESS (6.531s)
[199.682758] Starting dynamic subtest: bcs0
[206.633213] Dynamic subtest bcs0: SUCCESS (6.972s)
[206.659421] Starting dynamic subtest: vcs0
[213.672285] Dynamic subtest vcs0: SUCCESS (7.036s)
[213.694883] Starting dynamic subtest: vecs0
[220.649451] Dynamic subtest vecs0: SUCCESS (6.974s)
[220.651932] Subtest hang: SUCCESS (27.525s)
[220.750118] [044/156] (835s left) kms_getfb (getfb2-handle-zero)
[220.935558] Starting subtest: getfb2-handle-zero
[220.937210] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[221.048987] [045/156] (835s left) gem_close (basic)
[221.168729] Starting subtest: basic
[221.170698] Subtest basic: SUCCESS (0.000s)
[221.267964] [046/156] (835s left) gem_ccs (ctrl-surf-copy)
[221.381401] Subtest ctrl-surf-copy: SKIP (0.000s)
[221.487754] [047/156] (835s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[221.683259] Starting subtest: fbc-indfb-scaledprimary
[222.983077] Subtest fbc-indfb-scaledprimary: SUCCESS (1.300s)
[223.275270] [048/156] (833s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[223.461037] Starting subtest: crc-primary-suspend-y-tiled-ccs
[223.464190] Starting dynamic subtest: pipe-A-HDMI-A-1