[92.046955] Initializing watchdogs
[92.047164]   /dev/watchdog0
[92.086986] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[92.100745] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[92.109422] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[92.117394] [001/155] (960s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[92.690191] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[92.971752] [002/155] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[93.159284] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[93.163024] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[93.305675] [003/155] (959s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[93.481303] Starting subtest: short-busy-flip-before-cursor-toggle
[93.485012] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[93.605500] [004/155] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[93.789928] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[94.705417] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SUCCESS (0.914s)
[94.882866] [005/155] (958s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[95.073176] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[95.076617] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[105.846050] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.768s)
[105.868029] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[116.527694] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.678s)
[116.548452] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[127.194131] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.663s)
[127.196099] Subtest 2x-flip-vs-expired-vblank-interruptible: SUCCESS (32.122s)
[127.785468] [006/155] (925s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[127.971565] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[127.975448] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[128.119877] [007/155] (924s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[128.306296] Starting subtest: fbc-farfromfence-mmap-gtt
[131.699678] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (3.392s)
[131.947196] [008/155] (921s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[132.138140] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[132.141943] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[132.279301] [009/155] (920s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[132.465198] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[132.469110] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[132.615119] [010/155] (920s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[132.802896] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[132.806820] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[132.946628] [011/155] (920s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[133.135090] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[133.138736] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[133.280143] [012/155] (920s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[133.473844] Starting subtest: x-tiled-16bpp-rotate-90
[133.755692] Subtest x-tiled-16bpp-rotate-90: SKIP (0.280s)
[134.012778] [013/155] (919s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[134.183936] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[134.186368] Starting dynamic subtest: pipe-A
[134.982106] Dynamic subtest pipe-A: SUCCESS (0.800s)
[135.002138] Starting dynamic subtest: pipe-B
[135.808240] Dynamic subtest pipe-B: SUCCESS (0.825s)
[135.827510] Starting dynamic subtest: pipe-C
[136.632192] Dynamic subtest pipe-C: SUCCESS (0.823s)
[136.634259] Subtest plane-downscale-factor-0-5-with-modifiers: SUCCESS (2.452s)
[137.145193] [014/155] (916s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[137.326823] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[137.328902] Starting dynamic subtest: pipe-A-HDMI-A-1
[137.347573] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.003s)
[137.369126] Starting dynamic subtest: pipe-A-HDMI-A-2
[137.404099] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[137.425105] Starting dynamic subtest: pipe-B-HDMI-A-1
[137.456182] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[137.474040] Starting dynamic subtest: pipe-B-HDMI-A-2
[137.492959] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.003s)
[137.510915] Starting dynamic subtest: pipe-C-HDMI-A-1
[137.529301] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[137.547567] Starting dynamic subtest: pipe-C-HDMI-A-2
[137.565652] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[137.569159] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.030s)
[138.287806] [015/155] (915s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[138.475631] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-wc
[139.556362] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (1.079s)
[139.723590] [016/155] (914s left) gem_create (create-ext-set-pat)
[139.942156] Starting subtest: create-ext-set-pat
[139.946584] Subtest create-ext-set-pat: SKIP (0.000s)
[140.103890] [017/155] (913s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[140.206246] Starting subtest: etime-single-wait-for-submit-unsubmitted
[140.311077] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[140.439918] [018/155] (913s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[140.627914] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[140.631850] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[140.756776] [019/155] (913s left) gem_ctx_param (set-priority-invalid-size)
[140.859178] Starting subtest: set-priority-invalid-size
[140.861274] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[140.989387] [020/155] (913s left) kms_psr (psr2-primary-render)
[141.164286] Subtest psr2-primary-render: SKIP (0.000s)
[141.261829] [021/155] (913s left) gem_userptr_blits (create-destroy-sync)
[141.400411] Starting subtest: create-destroy-sync
[146.478255] Subtest create-destroy-sync: SUCCESS (5.075s)
[146.655296] [022/155] (907s left) i915_query (query-garbage)
[146.754462] Starting subtest: query-garbage
[146.756371] Subtest query-garbage: SUCCESS (0.000s)
[146.872276] [023/155] (907s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[146.985434] Starting subtest: y-tiled-to-vebox-x-tiled
[146.989345] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[147.222575] [024/155] (907s left) gem_exec_reloc (basic-gtt-wc)
[147.332665] Starting subtest: basic-gtt-wc
[147.356957] Subtest basic-gtt-wc: SUCCESS (0.021s)
[147.556311] [025/155] (907s left) i915_pm_rpm (gem-pread)
[149.428367] Starting subtest: gem-pread
[150.161592] Subtest gem-pread: SUCCESS (0.734s)
[150.470589] [026/155] (904s left) kms_psr (psr2-basic)
[150.650872] Subtest psr2-basic: SKIP (0.000s)
[150.756987] [027/155] (904s left) gem_tiled_partial_pwrite_pread (reads)
[150.865171] Starting subtest: reads
[152.070812] Subtest reads: SUCCESS (1.206s)
[152.218522] [028/155] (902s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[152.323251] Starting subtest: single-wait-for-submit-available-submitted
[152.325184] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.002s)
[152.467133] [029/155] (902s left) syncobj_wait (multi-wait-submitted)
[152.568474] Starting subtest: multi-wait-submitted
[152.678428] Subtest multi-wait-submitted: SUCCESS (0.107s)
[152.802712] [030/155] (902s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[153.017566] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[153.023090] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[153.136667] [031/155] (901s left) gem_exec_parallel (basic)
[153.260544] Starting subtest: basic
[153.262544] Starting dynamic subtest: rcs0
[156.199656] Dynamic subtest rcs0: SUCCESS (2.941s)
[156.221041] Starting dynamic subtest: bcs0
[159.630238] Dynamic subtest bcs0: SUCCESS (3.430s)
[159.650288] Starting dynamic subtest: vcs0
[162.950483] Dynamic subtest vcs0: SUCCESS (3.319s)
[162.971039] Starting dynamic subtest: vecs0
[165.862418] Dynamic subtest vecs0: SUCCESS (2.911s)
[165.864645] Subtest basic: SUCCESS (12.609s)
[166.100935] [032/155] (889s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[166.284420] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[168.717202] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SUCCESS (2.432s)
[168.929515] [033/155] (886s left) syncobj_wait (single-wait-for-submit-submitted)
[169.035633] Starting subtest: single-wait-for-submit-submitted
[169.142307] Subtest single-wait-for-submit-submitted: SUCCESS (0.107s)
[169.261666] [034/155] (886s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[169.448459] Starting subtest: fbcpsr-rgb565-draw-blt
[169.452205] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[169.582978] [035/155] (885s left) perf_pmu (busy-accuracy-98)
[169.691100] Starting subtest: busy-accuracy-98
[169.693100] Starting dynamic subtest: rcs0
[172.744550] Dynamic subtest rcs0: SUCCESS (3.051s)
[172.768317] Starting dynamic subtest: bcs0
[175.801676] Dynamic subtest bcs0: SUCCESS (3.053s)
[175.823585] Starting dynamic subtest: vcs0
[178.848765] Dynamic subtest vcs0: SUCCESS (3.047s)
[178.872563] Starting dynamic subtest: vecs0
[181.901110] Dynamic subtest vecs0: SUCCESS (3.050s)
[181.903679] Subtest busy-accuracy-98: SUCCESS (12.208s)
[182.030284] [036/155] (873s left) kms_dsc (dsc-fractional-bpp-with-bpc)
[182.200619] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.000s)
[182.306654] [037/155] (873s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[182.480842] Starting subtest: cursorB-vs-flipB-toggle
[193.749938] Subtest cursorB-vs-flipB-toggle: SUCCESS (11.271s)
[194.072650] [038/155] (861s left) syncobj_wait (wait-all-delayed-signal)
[194.185821] Starting subtest: wait-all-delayed-signal
[194.291917] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[194.435419] [039/155] (861s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[194.621950] Starting subtest: psr-rgb565-draw-mmap-wc
[194.626948] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[194.772731] [040/155] (861s left) gem_wait (busy)
[194.909122] Starting subtest: busy
[194.911312] Starting dynamic subtest: all-engines
[195.437876] Dynamic subtest all-engines: SUCCESS (0.530s)
[195.465843] Starting dynamic subtest: rcs0
[195.960174] Dynamic subtest rcs0: SUCCESS (0.521s)
[195.986670] Starting dynamic subtest: bcs0
[196.481673] Dynamic subtest bcs0: SUCCESS (0.518s)
[196.508365] Starting dynamic subtest: vcs0
[197.003113] Dynamic subtest vcs0: SUCCESS (0.519s)
[197.026057] Starting dynamic subtest: vecs0
[197.525652] Dynamic subtest vecs0: SUCCESS (0.520s)
[197.527763] Subtest busy: SUCCESS (2.621s)
[197.803738] [041/155] (858s left) gem_close_race (basic-threads)
[197.928023] Starting subtest: basic-threads
[199.219908] Subtest basic-threads: SUCCESS (1.292s)
[199.470116] [042/155] (856s left) kms_lease (possible-crtcs-filtering)
[199.640928] Starting subtest: possible-crtcs-filtering
[199.648105] Subtest possible-crtcs-filtering: SUCCESS (0.007s)
[199.806312] [043/155] (856s left) prime_busy (hang)
[199.918137] Starting subtest: hang
[199.920218] Starting dynamic subtest: rcs0
[206.689818] Dynamic subtest rcs0: SUCCESS (6.768s)
[206.712715] Starting dynamic subtest: bcs0
[213.665481] Dynamic subtest bcs0: SUCCESS (6.971s)
[213.687625] Starting dynamic subtest: vcs0
[220.704726] Dynamic subtest vcs0: SUCCESS (7.038s)
[220.728134] Starting dynamic subtest: vecs0
[227.680448] Dynamic subtest vecs0: SUCCESS (6.974s)
[227.682378] Subtest hang: SUCCESS (27.763s)
[227.815783] [044/155] (828s left) kms_getfb (getfb2-handle-zero)
[227.992168] Starting subtest: getfb2-handle-zero
[227.994191] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[228.152922] [045/155] (828s left) gem_close (basic)
[228.266905] Starting subtest: basic
[228.268963] Subtest basic: SUCCESS (0.000s)
[228.420060] [046/155] (827s left) gem_ccs (ctrl-surf-copy)
[228.527408] Subtest ctrl-surf-copy: SKIP (0.000s)
[228.627676] [047/155] (827s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[228.814590] Starting subtest: fbc-indfb-scaledprimary
[230.068068] Subtest fbc-indfb-scaledprimary: SUCCESS (1.252s)
[230.375985] [048/155] (826s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[230.549269] Starting subtest: crc-primary-suspend-y-tiled-ccs
[230.551351] Starting dynamic subtest: pipe-A-HDMI-A-1