[93.178160] Initializing watchdogs
[93.178331]   /dev/watchdog0
[93.220724] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[93.235825] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[93.243285] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[93.255152] [001/156] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[93.891070] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[94.202904] [002/156] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[94.444679] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[94.451354] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[94.595856] [003/156] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[94.794048] Starting subtest: short-busy-flip-before-cursor-toggle
[94.800899] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[94.931894] [004/156] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[95.147113] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[95.155263] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[95.282079] [005/156] (958s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[95.487824] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[95.497074] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[103.124880] Dynamic subtest AB-HDMI-A1-HDMI-A2: FAIL (6.829s)
[103.146191] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[113.107217] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.775s)
[113.128862] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[123.804113] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.692s)
[123.810038] Subtest 2x-flip-vs-expired-vblank-interruptible: FAIL (28.316s)
[124.455148] [006/156] (929s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[124.675861] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[124.684445] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[124.822191] [007/156] (929s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[125.036200] Starting subtest: fbc-farfromfence-mmap-gtt
[128.020925] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (2.993s)
[128.273661] [008/156] (926s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[128.481609] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[128.488269] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[128.624509] [009/156] (925s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[128.846645] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[128.854392] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[128.993957] [010/156] (925s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[129.204852] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[129.212152] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[129.344325] [011/156] (925s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[129.554374] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[129.563682] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[129.694309] [012/156] (925s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[129.959292] Starting subtest: x-tiled-16bpp-rotate-90
[130.315458] Subtest x-tiled-16bpp-rotate-90: SKIP (0.356s)
[130.594389] [013/156] (924s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[130.786231] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[130.790907] Starting dynamic subtest: pipe-A
[131.634324] Dynamic subtest pipe-A: SUCCESS (0.849s)
[131.658762] Starting dynamic subtest: pipe-B
[132.543638] Dynamic subtest pipe-B: SUCCESS (0.910s)
[132.568636] Starting dynamic subtest: pipe-C
[133.405077] Dynamic subtest pipe-C: SUCCESS (0.863s)
[133.408249] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.624s)
[133.955870] [014/156] (921s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[134.148099] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[134.151898] Starting dynamic subtest: pipe-A-HDMI-A-1
[134.195646] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[134.233806] Starting dynamic subtest: pipe-A-HDMI-A-2
[134.286186] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[134.322464] Starting dynamic subtest: pipe-B-HDMI-A-1
[134.354043] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[134.375018] Starting dynamic subtest: pipe-B-HDMI-A-2
[134.408790] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[134.429452] Starting dynamic subtest: pipe-C-HDMI-A-1
[134.462469] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[134.482861] Starting dynamic subtest: pipe-C-HDMI-A-2
[134.516446] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.003s)
[134.522761] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.033s)
[135.256389] [015/156] (919s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[135.475682] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[135.484749] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[135.609301] [016/156] (919s left) gem_create (create-ext-set-pat)
[135.847477] Starting subtest: create-ext-set-pat
[135.856218] Subtest create-ext-set-pat: SKIP (0.000s)
[135.990836] [017/156] (919s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[136.104110] Starting subtest: etime-single-wait-for-submit-unsubmitted
[136.210146] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[136.357430] [018/156] (919s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[136.570586] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[136.578097] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[136.709688] [019/156] (918s left) gem_ctx_param (set-priority-invalid-size)
[136.821178] Starting subtest: set-priority-invalid-size
[136.824691] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[136.987954] [020/156] (918s left) kms_psr (psr2-primary-render)
[137.189531] Subtest psr2-primary-render: SKIP (0.000s)
[137.297802] [021/156] (918s left) gem_userptr_blits (create-destroy-sync)
[137.460308] Starting subtest: create-destroy-sync
[142.534994] Subtest create-destroy-sync: SUCCESS (5.069s)
[142.737369] [022/156] (913s left) i915_query (query-garbage)
[142.850320] Starting subtest: query-garbage
[142.854198] Subtest query-garbage: SUCCESS (0.000s)
[142.989311] [023/156] (912s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[143.115283] Starting subtest: y-tiled-to-vebox-x-tiled
[143.123308] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[143.372945] [024/156] (912s left) gem_exec_reloc (basic-gtt-wc)
[143.493706] Starting subtest: basic-gtt-wc
[143.517146] Subtest basic-gtt-wc: SUCCESS (0.022s)
[143.757130] [025/156] (912s left) i915_pm_rpm (gem-pread)
[145.591858] Starting subtest: gem-pread
[146.266912] Subtest gem-pread: SUCCESS (0.668s)
[146.640852] [026/156] (909s left) kms_psr (psr2-basic)
[146.839877] Subtest psr2-basic: SKIP (0.000s)
[146.949999] [027/156] (909s left) gem_tiled_partial_pwrite_pread (reads)
[147.078094] Starting subtest: reads
[148.502904] Subtest reads: SUCCESS (1.426s)
[148.701204] [028/156] (907s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[148.816754] Starting subtest: single-wait-for-submit-available-submitted
[148.820609] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.002s)
[148.956445] [029/156] (907s left) syncobj_wait (multi-wait-submitted)
[149.062545] Starting subtest: multi-wait-submitted
[149.234638] Subtest multi-wait-submitted: SUCCESS (0.106s)
[149.688820] [030/156] (907s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[150.438060] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[150.576978] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[150.776536] [031/156] (906s left) gem_exec_parallel (basic)
[150.910603] Starting subtest: basic
[150.915162] Starting dynamic subtest: rcs0
[154.140939] Dynamic subtest rcs0: SUCCESS (3.224s)
[154.165355] Starting dynamic subtest: bcs0
[157.559315] Dynamic subtest bcs0: SUCCESS (3.423s)
[157.584513] Starting dynamic subtest: vcs0
[160.834716] Dynamic subtest vcs0: SUCCESS (3.274s)
[160.868359] Starting dynamic subtest: vecs0
[164.379268] Dynamic subtest vecs0: SUCCESS (3.540s)
[164.384642] Subtest basic: SUCCESS (13.467s)
[164.642495] [032/156] (892s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[164.859476] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[166.896651] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.046s)
[167.106529] [033/156] (890s left) syncobj_wait (single-wait-for-submit-submitted)
[167.220939] Starting subtest: single-wait-for-submit-submitted
[167.329294] Subtest single-wait-for-submit-submitted: SUCCESS (0.109s)
[167.472058] [034/156] (889s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[167.684812] Starting subtest: fbcpsr-rgb565-draw-blt
[167.691666] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[167.823724] [035/156] (889s left) perf_pmu (busy-accuracy-98)
[167.947243] Starting subtest: busy-accuracy-98
[167.952195] Starting dynamic subtest: rcs0
[171.005806] Dynamic subtest rcs0: SUCCESS (3.056s)
[171.030232] Starting dynamic subtest: bcs0
[174.062370] Dynamic subtest bcs0: SUCCESS (3.054s)
[174.086886] Starting dynamic subtest: vcs0
[177.115536] Dynamic subtest vcs0: SUCCESS (3.053s)
[177.138980] Starting dynamic subtest: vecs0
[180.169379] Dynamic subtest vecs0: SUCCESS (3.050s)
[180.172905] Subtest busy-accuracy-98: SUCCESS (12.218s)
[180.309102] [036/156] (877s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[180.487353] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[180.610246] [037/156] (876s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[180.800695] Starting subtest: cursorB-vs-flipB-toggle
[192.082896] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.284s)
[192.453967] [038/156] (865s left) syncobj_wait (wait-all-delayed-signal)
[192.594031] Starting subtest: wait-all-delayed-signal
[192.700829] Subtest wait-all-delayed-signal: SUCCESS (0.106s)
[192.868777] [039/156] (864s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[193.090942] Starting subtest: psr-rgb565-draw-mmap-wc
[193.097613] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[193.230593] [040/156] (864s left) gem_wait (busy)
[193.383350] Starting subtest: busy
[193.387413] Starting dynamic subtest: all-engines
[193.910211] Dynamic subtest all-engines: SUCCESS (0.532s)
[193.939424] Starting dynamic subtest: rcs0
[194.432465] Dynamic subtest rcs0: SUCCESS (0.514s)
[194.456927] Starting dynamic subtest: bcs0
[194.944612] Dynamic subtest bcs0: SUCCESS (0.515s)
[194.971577] Starting dynamic subtest: vcs0
[195.464062] Dynamic subtest vcs0: SUCCESS (0.515s)
[195.489155] Starting dynamic subtest: vecs0
[195.982335] Dynamic subtest vecs0: SUCCESS (0.514s)
[195.987381] Subtest busy: SUCCESS (2.601s)
[196.266203] [041/156] (861s left) gem_close_race (basic-threads)
[196.407830] Starting subtest: basic-threads
[197.642659] Subtest basic-threads: SUCCESS (1.235s)
[197.900851] [042/156] (860s left) kms_lease (possible-crtcs-filtering)
[198.095742] Starting subtest: possible-crtcs-filtering
[198.126103] Subtest possible-crtcs-filtering: SUCCESS (0.027s)
[198.267795] [043/156] (859s left) prime_busy (hang)
[198.387616] Starting subtest: hang
[198.390917] Starting dynamic subtest: rcs0
[204.657595] Dynamic subtest rcs0: SUCCESS (6.262s)
[204.686195] Starting dynamic subtest: bcs0
[211.630117] Dynamic subtest bcs0: SUCCESS (6.971s)
[211.655878] Starting dynamic subtest: vcs0
[218.669589] Dynamic subtest vcs0: SUCCESS (7.038s)
[218.699093] Starting dynamic subtest: vecs0
[225.642229] Dynamic subtest vecs0: SUCCESS (6.974s)
[225.645790] Subtest hang: SUCCESS (27.257s)
[225.782872] [044/156] (832s left) kms_getfb (getfb2-handle-zero)
[225.980529] Starting subtest: getfb2-handle-zero
[225.984096] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[226.136084] [045/156] (832s left) gem_close (basic)
[226.263389] Starting subtest: basic
[226.267205] Subtest basic: SUCCESS (0.000s)
[226.429243] [046/156] (832s left) gem_ccs (ctrl-surf-copy)
[226.546581] Subtest ctrl-surf-copy: SKIP (0.000s)
[226.659764] [047/156] (831s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[226.868072] Starting subtest: fbc-indfb-scaledprimary
[228.088802] Subtest fbc-indfb-scaledprimary: SUCCESS (1.229s)
[228.385557] [048/156] (830s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[228.586063] Starting subtest: crc-primary-suspend-y-tiled-ccs
[228.590415] Starting dynamic subtest: pipe-A-HDMI-A-1
[244.832027] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.561s)
[244.899229] Starting dynamic subtest: pipe-A-HDMI-A-2