[91.909838] Initializing watchdogs
[91.910077]   /dev/watchdog0
[91.948893] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.963791] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.970986] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.977463] [001/133] (960s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[92.195632] Starting subtest: psr-rgb101010-draw-mmap-cpu
[92.202157] Subtest psr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[92.341835] [002/133] (960s left) kms_plane_cursor (primary)
[92.520115] Starting subtest: primary
[92.525393] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[95.198369] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.677s)
[95.267831] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[97.680438] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.429s)
[97.749550] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[100.163734] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.432s)
[100.472764] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64
[102.939763] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.500s)
[102.996466] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128
[105.391234] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.414s)
[105.440107] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256
[107.859138] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.432s)
[108.133568] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[110.616340] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.514s)
[110.664342] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[113.082438] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.433s)
[113.131120] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[115.550067] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.433s)
[115.786539] Subtest primary: SUCCESS (23.265s)
[116.067531] [003/133] (936s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[116.278062] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[116.280174] Starting dynamic subtest: pipe-A-HDMI-A-1
[116.299860] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.004s)
[116.330594] Starting dynamic subtest: pipe-A-HDMI-A-2
[116.360611] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[116.393646] Starting dynamic subtest: pipe-B-HDMI-A-1
[116.425231] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[116.459525] Starting dynamic subtest: pipe-B-HDMI-A-2
[116.489486] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.003s)
[116.510090] Starting dynamic subtest: pipe-C-HDMI-A-1
[116.524885] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.004s)
[116.541662] Starting dynamic subtest: pipe-C-HDMI-A-2
[116.558490] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.004s)
[116.561499] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.045s)
[117.404461] [004/133] (935s left) gem_pipe_control_store_loop (reused-buffer)
[117.525277] Starting subtest: reused-buffer
[119.682081] Subtest reused-buffer: SUCCESS (2.157s)
[119.834545] [005/133] (932s left) prime_busy (hang)
[119.947162] Starting subtest: hang
[119.948821] Starting dynamic subtest: rcs0
[126.640118] Dynamic subtest rcs0: SUCCESS (6.688s)
[126.662343] Starting dynamic subtest: bcs0
[133.614223] Dynamic subtest bcs0: SUCCESS (6.974s)
[133.632494] Starting dynamic subtest: vcs0
[140.652164] Dynamic subtest vcs0: SUCCESS (7.039s)
[140.673766] Starting dynamic subtest: vecs0
[147.630454] Dynamic subtest vecs0: SUCCESS (6.975s)
[147.632395] Subtest hang: SUCCESS (27.681s)
[147.772727] [006/133] (905s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[147.944229] Starting subtest: plane-lut1d-pre-ctm3x4
[149.628309] Subtest plane-lut1d-pre-ctm3x4: SKIP (1.675s)
[149.940713] [007/133] (903s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[150.155111] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[151.927284] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.770s)
[152.177188] [008/133] (900s left) i915_pm_rps (basic-api)
[152.289939] Starting subtest: basic-api
[152.301156] Subtest basic-api: SUCCESS (0.010s)
[152.425845] [009/133] (900s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[152.620103] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[152.625120] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[152.761294] [010/133] (900s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[152.966301] Starting subtest: fbc-farfromfence-mmap-gtt
[156.012071] Subtest fbc-farfromfence-mmap-gtt: SUCCESS (3.044s)
[156.242239] [011/133] (897s left) kms_addfb_basic (addfb25-modifier-no-flag)
[156.428311] Starting subtest: addfb25-modifier-no-flag
[156.430106] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[156.559283] [012/133] (896s left) gem_exercise_blt (fast-copy)
[156.671781] Starting subtest: fast-copy
[156.673429] Starting dynamic subtest: linear-smem-smem
[156.723506] Dynamic subtest linear-smem-smem: SUCCESS (0.053s)
[156.747086] Starting dynamic subtest: ymajor-smem-smem
[156.766845] Dynamic subtest ymajor-smem-smem: SUCCESS (0.042s)
[156.787947] Starting dynamic subtest: yfmajor-smem-smem
[156.811309] Dynamic subtest yfmajor-smem-smem: SUCCESS (0.040s)
[156.828313] Starting dynamic subtest: tile64-smem-smem
[156.846566] Dynamic subtest tile64-smem-smem: SUCCESS (0.039s)
[156.853936] Subtest fast-copy: SUCCESS (0.179s)
[156.993782] [013/133] (896s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[157.188255] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[157.192579] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[157.326346] [014/133] (896s left) kms_big_fb (linear-64bpp-rotate-0)
[157.535873] Starting subtest: linear-64bpp-rotate-0
[163.026061] Subtest linear-64bpp-rotate-0: SUCCESS (5.489s)
[163.560581] [015/133] (890s left) gem_exec_capture (capture-recoverable)
[163.675632] Starting subtest: capture-recoverable
[163.679188] Subtest capture-recoverable: SKIP (0.000s)
[163.811546] [016/133] (889s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[163.928262] Starting subtest: yf-tiled-to-vebox-y-tiled
[163.931445] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[164.179727] [017/133] (889s left) kms_plane (pixel-format-yf-tiled-modifier)
[164.365267] Starting subtest: pixel-format-yf-tiled-modifier
[164.575293] Starting dynamic subtest: pipe-A-plane-0
[165.275832] Dynamic subtest pipe-A-plane-0: SUCCESS (0.700s)
[165.293753] Starting dynamic subtest: pipe-A-plane-1
[165.992609] Dynamic subtest pipe-A-plane-1: SUCCESS (0.716s)
[166.009743] Starting dynamic subtest: pipe-A-plane-2
[166.441392] Dynamic subtest pipe-A-plane-2: SUCCESS (0.449s)
[166.460898] Starting dynamic subtest: pipe-A-plane-3
[166.893111] Dynamic subtest pipe-A-plane-3: SUCCESS (0.450s)
[166.912315] Starting dynamic subtest: pipe-A-plane-4
[166.919038] Dynamic subtest pipe-A-plane-4: SKIP (0.000s)
[167.292225] Starting dynamic subtest: pipe-B-plane-0
[167.925847] Dynamic subtest pipe-B-plane-0: SUCCESS (0.649s)
[167.944572] Starting dynamic subtest: pipe-B-plane-1
[168.609634] Dynamic subtest pipe-B-plane-1: SUCCESS (0.682s)
[168.627177] Starting dynamic subtest: pipe-B-plane-2
[169.075675] Dynamic subtest pipe-B-plane-2: SUCCESS (0.465s)
[169.093523] Starting dynamic subtest: pipe-B-plane-3
[169.525805] Dynamic subtest pipe-B-plane-3: SUCCESS (0.449s)
[169.546036] Starting dynamic subtest: pipe-B-plane-4
[169.551610] Dynamic subtest pipe-B-plane-4: SKIP (0.000s)
[169.741505] Subtest pixel-format-yf-tiled-modifier: SUCCESS (5.383s)
[170.056199] [018/133] (883s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[170.263129] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[172.747381] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SUCCESS (2.481s)
[172.943743] [019/133] (881s left) gem_exec_reloc (basic-write-wc)
[173.058802] Starting subtest: basic-write-wc
[173.077424] Subtest basic-write-wc: SUCCESS (0.018s)
[173.291223] [020/133] (880s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[173.399370] Starting subtest: multi-wait-available-submitted-signaled
[173.404283] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.005s)
[173.542660] [021/133] (880s left) gem_wait (await)
[173.676278] Starting subtest: await
[173.680196] Starting dynamic subtest: all-engines
[174.797205] Dynamic subtest all-engines: SUCCESS (1.116s)
[174.817394] Starting dynamic subtest: rcs0
[175.830047] Dynamic subtest rcs0: SUCCESS (1.031s)
[175.850772] Starting dynamic subtest: bcs0
[176.870188] Dynamic subtest bcs0: SUCCESS (1.039s)
[176.889390] Starting dynamic subtest: vcs0
[177.898395] Dynamic subtest vcs0: SUCCESS (1.029s)
[177.920761] Starting dynamic subtest: vecs0
[178.929052] Dynamic subtest vecs0: SUCCESS (1.029s)
[178.932208] Subtest await: SUCCESS (5.254s)
[179.213629] [FACT gem_wait (await)] new: kernel.kmod_is_loaded.vgem: true
[179.219533] [022/133] (875s left) kms_psr (psr-cursor-blt)
[179.404355] Subtest psr-cursor-blt: SKIP (0.000s)
[179.506504] [023/133] (874s left) gem_softpin (noreloc-interruptible)
[179.626044] Starting subtest: noreloc-interruptible
[185.150247] Subtest noreloc-interruptible: SUCCESS (5.517s)
[185.319577] [024/133] (869s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[185.421863] Starting subtest: etime-single-wait-for-submit-unsubmitted
[185.531083] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[185.685092] [025/133] (868s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[185.869713] Starting subtest: short-flip-after-cursor-toggle
[187.192397] Subtest short-flip-after-cursor-toggle: SUCCESS (1.328s)
[187.492211] [026/133] (867s left) gem_eio (create-ext)
[187.634823] Starting subtest: create-ext
[187.690784] Subtest create-ext: SUCCESS (0.060s)
[187.940513] [027/133] (866s left) i915_drm_fdinfo (busy-idle)
[188.054064] Starting subtest: busy-idle
[188.057167] Starting dynamic subtest: rcs0
[188.686182] Dynamic subtest rcs0: SUCCESS (0.628s)
[188.711566] Starting dynamic subtest: bcs0
[189.322196] Dynamic subtest bcs0: SUCCESS (0.638s)
[189.346686] Starting dynamic subtest: vcs0
[189.951924] Dynamic subtest vcs0: SUCCESS (0.626s)
[189.971458] Starting dynamic subtest: vecs0
[190.584121] Dynamic subtest vecs0: SUCCESS (0.629s)
[190.585903] Subtest busy-idle: SUCCESS (2.531s)
[190.727493] [028/133] (864s left) kms_cursor_crc (cursor-sliding-max-size)
[190.944131] Starting subtest: cursor-sliding-max-size
[190.950771] Subtest cursor-sliding-max-size: SKIP (0.001s)
[191.090963] [029/133] (863s left) kms_vblank (ts-continuation-suspend)
[191.277939] Starting subtest: ts-continuation-suspend
[191.281306] Starting dynamic subtest: pipe-A-HDMI-A-1