[91.575207] Initializing watchdogs
[91.575379]   /dev/watchdog0
[91.617334] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.632350] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.638647] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.648203] [001/155] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[92.279965] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[92.648626] [002/155] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[92.872483] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[92.879805] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[93.025504] [003/155] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[93.225692] Starting subtest: short-busy-flip-before-cursor-toggle
[93.232960] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.001s)
[93.359951] [004/155] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[93.572339] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[93.580037] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[93.712601] [005/155] (958s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[93.914142] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[93.924358] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[100.117732] Dynamic subtest AB-HDMI-A1-HDMI-A2: FAIL (5.334s)
[100.138800] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[110.015190] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.752s)
[110.039660] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[120.718628] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.696s)
[120.724596] Subtest 2x-flip-vs-expired-vblank-interruptible: FAIL (26.802s)
[121.334150] [006/155] (931s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[121.548091] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[121.554232] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[121.695681] [007/155] (931s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[121.905488] Starting subtest: fbc-farfromfence-mmap-gtt
[124.914630] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (3.013s)
[125.189119] [008/155] (927s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[125.396534] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[125.403577] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[125.540038] [009/155] (927s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[125.764321] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[125.771022] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[125.936585] [010/155] (927s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[126.152805] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[126.160258] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[126.290899] [011/155] (926s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[126.517068] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[126.524681] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[126.653917] [012/155] (926s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[126.868939] Starting subtest: x-tiled-16bpp-rotate-90
[127.360527] Subtest x-tiled-16bpp-rotate-90: SKIP (0.304s)
[127.876468] [013/155] (925s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[128.294859] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[128.364277] Starting dynamic subtest: pipe-A
[129.065913] Dynamic subtest pipe-A: SUCCESS (0.855s)
[129.088353] Starting dynamic subtest: pipe-B
[129.940488] Dynamic subtest pipe-B: SUCCESS (0.878s)
[129.969606] Starting dynamic subtest: pipe-C
[130.804345] Dynamic subtest pipe-C: SUCCESS (0.863s)
[130.810781] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.600s)
[131.327777] [014/155] (922s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[131.523638] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[131.527291] Starting dynamic subtest: pipe-A-HDMI-A-1
[131.579581] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[131.623538] Starting dynamic subtest: pipe-A-HDMI-A-2
[131.669131] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.002s)
[131.694525] Starting dynamic subtest: pipe-B-HDMI-A-1
[131.742568] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[131.763067] Starting dynamic subtest: pipe-B-HDMI-A-2
[131.798735] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[131.820893] Starting dynamic subtest: pipe-C-HDMI-A-1
[131.854670] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[131.875124] Starting dynamic subtest: pipe-C-HDMI-A-2
[131.907548] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.003s)
[131.913998] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.031s)
[132.581294] [015/155] (921s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[132.800269] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[132.808440] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[132.944364] [016/155] (920s left) gem_create (create-ext-set-pat)
[133.178515] Starting subtest: create-ext-set-pat
[133.187567] Subtest create-ext-set-pat: SKIP (0.000s)
[133.324495] [017/155] (920s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[133.438566] Starting subtest: etime-single-wait-for-submit-unsubmitted
[133.544135] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[133.691506] [018/155] (920s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[133.897368] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[133.904674] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[134.043076] [019/155] (920s left) gem_ctx_param (set-priority-invalid-size)
[134.159828] Starting subtest: set-priority-invalid-size
[134.163682] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[134.291102] [020/155] (920s left) kms_psr (psr2-primary-render)
[134.492277] Subtest psr2-primary-render: SKIP (0.000s)
[134.602690] [021/155] (919s left) gem_userptr_blits (create-destroy-sync)
[134.759240] Starting subtest: create-destroy-sync
[139.807638] Subtest create-destroy-sync: SUCCESS (5.048s)
[139.974876] [022/155] (914s left) i915_query (query-garbage)
[140.082425] Starting subtest: query-garbage
[140.086439] Subtest query-garbage: SUCCESS (0.000s)
[140.223372] [023/155] (914s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[140.346125] Starting subtest: y-tiled-to-vebox-x-tiled
[140.353602] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[140.592433] [024/155] (914s left) gem_exec_reloc (basic-gtt-wc)
[140.714041] Starting subtest: basic-gtt-wc
[140.743770] Subtest basic-gtt-wc: SUCCESS (0.027s)
[140.960843] [025/155] (913s left) i915_pm_rpm (gem-pread)
[142.798913] Starting subtest: gem-pread
[143.507532] Subtest gem-pread: SUCCESS (0.700s)
[143.869516] [026/155] (910s left) kms_psr (psr2-basic)
[144.074948] Subtest psr2-basic: SKIP (0.000s)
[144.182759] [027/155] (910s left) gem_tiled_partial_pwrite_pread (reads)
[144.313869] Starting subtest: reads
[145.790214] Subtest reads: SUCCESS (1.480s)
[145.970113] [028/155] (909s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[146.083379] Starting subtest: single-wait-for-submit-available-submitted
[146.086700] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.002s)
[146.226538] [029/155] (908s left) syncobj_wait (multi-wait-submitted)
[146.340926] Starting subtest: multi-wait-submitted
[146.447051] Subtest multi-wait-submitted: SUCCESS (0.107s)
[146.592482] [030/155] (908s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[146.799051] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[146.805653] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[146.943499] [031/155] (908s left) gem_exec_parallel (basic)
[147.077506] Starting subtest: basic
[147.081887] Starting dynamic subtest: rcs0
[150.350984] Dynamic subtest rcs0: SUCCESS (3.274s)
[150.378417] Starting dynamic subtest: bcs0
[153.742142] Dynamic subtest bcs0: SUCCESS (3.391s)
[153.766946] Starting dynamic subtest: vcs0
[156.980939] Dynamic subtest vcs0: SUCCESS (3.238s)
[157.005456] Starting dynamic subtest: vecs0
[160.633522] Dynamic subtest vecs0: SUCCESS (3.652s)
[160.637618] Subtest basic: SUCCESS (13.560s)
[160.895651] [032/155] (894s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[161.110047] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[163.166047] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.065s)
[163.394344] [033/155] (892s left) syncobj_wait (single-wait-for-submit-submitted)
[163.509268] Starting subtest: single-wait-for-submit-submitted
[163.616668] Subtest single-wait-for-submit-submitted: SUCCESS (0.109s)
[163.786545] [034/155] (891s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[163.997572] Starting subtest: fbcpsr-rgb565-draw-blt
[164.004838] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[164.143577] [035/155] (891s left) perf_pmu (busy-accuracy-98)
[164.266462] Starting subtest: busy-accuracy-98
[164.271495] Starting dynamic subtest: rcs0
[167.328248] Dynamic subtest rcs0: SUCCESS (3.056s)
[167.352733] Starting dynamic subtest: bcs0
[170.386250] Dynamic subtest bcs0: SUCCESS (3.055s)
[170.411768] Starting dynamic subtest: vcs0
[173.439298] Dynamic subtest vcs0: SUCCESS (3.052s)
[173.463773] Starting dynamic subtest: vecs0
[176.492415] Dynamic subtest vecs0: SUCCESS (3.051s)
[176.496940] Subtest busy-accuracy-98: SUCCESS (12.222s)
[176.642461] [036/155] (879s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[176.843483] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[176.951776] [037/155] (878s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[177.131500] Starting subtest: cursorB-vs-flipB-toggle
[188.508206] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.369s)
[188.846084] [038/155] (867s left) syncobj_wait (wait-all-delayed-signal)
[188.971760] Starting subtest: wait-all-delayed-signal
[189.077300] Subtest wait-all-delayed-signal: SUCCESS (0.105s)
[189.235754] [039/155] (866s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[189.439390] Starting subtest: psr-rgb565-draw-mmap-wc
[189.446150] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.001s)
[189.574053] [040/155] (866s left) gem_wait (busy)
[189.727784] Starting subtest: busy
[189.732749] Starting dynamic subtest: all-engines
[190.251543] Dynamic subtest all-engines: SUCCESS (0.526s)
[190.278075] Starting dynamic subtest: rcs0
[190.771123] Dynamic subtest rcs0: SUCCESS (0.513s)
[190.794704] Starting dynamic subtest: bcs0
[191.290106] Dynamic subtest bcs0: SUCCESS (0.519s)
[191.314411] Starting dynamic subtest: vcs0
[191.799713] Dynamic subtest vcs0: SUCCESS (0.513s)
[191.824156] Starting dynamic subtest: vecs0
[192.322432] Dynamic subtest vecs0: SUCCESS (0.513s)
[192.326371] Subtest busy: SUCCESS (2.595s)
[192.623750] [041/155] (863s left) gem_close_race (basic-threads)
[192.757872] Starting subtest: basic-threads
[193.912143] Subtest basic-threads: SUCCESS (1.155s)
[194.189853] [042/155] (862s left) kms_lease (possible-crtcs-filtering)
[194.372551] Starting subtest: possible-crtcs-filtering
[194.397975] Subtest possible-crtcs-filtering: SUCCESS (0.019s)
[194.559889] [043/155] (861s left) prime_busy (hang)
[194.682506] Starting subtest: hang
[194.685967] Starting dynamic subtest: rcs0
[201.648680] Dynamic subtest rcs0: SUCCESS (6.957s)
[201.676408] Starting dynamic subtest: bcs0
[208.619545] Dynamic subtest bcs0: SUCCESS (6.972s)
[208.650433] Starting dynamic subtest: vcs0
[215.657724] Dynamic subtest vcs0: SUCCESS (7.037s)
[215.684078] Starting dynamic subtest: vecs0
[222.637784] Dynamic subtest vecs0: SUCCESS (6.975s)
[222.641941] Subtest hang: SUCCESS (27.951s)
[222.772624] [044/155] (833s left) kms_getfb (getfb2-handle-zero)
[222.959024] Starting subtest: getfb2-handle-zero
[222.964103] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[223.111613] [045/155] (833s left) gem_close (basic)
[223.236809] Starting subtest: basic
[223.240631] Subtest basic: SUCCESS (0.001s)
[223.388726] [046/155] (833s left) gem_ccs (ctrl-surf-copy)
[223.509399] Subtest ctrl-surf-copy: SKIP (0.000s)
[223.616665] [047/155] (833s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[223.841096] Starting subtest: fbc-indfb-scaledprimary
[225.183569] Subtest fbc-indfb-scaledprimary: SUCCESS (1.281s)
[225.773273] [048/155] (831s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[226.266082] Starting subtest: crc-primary-suspend-y-tiled-ccs
[226.336544] Starting dynamic subtest: pipe-A-HDMI-A-1