[46.275897] Initializing watchdogs
[46.275960]   /dev/watchdog0
[46.292827] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[46.298460] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[46.301522] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[46.303427] [001/133] (960s left) gem_ccs (suspend-resume)
[46.345647] Starting subtest: suspend-resume
[46.347073] Subtest suspend-resume: SKIP (0.000s)
[46.431818] [002/133] (960s left) kms_atomic (plane-immutable-zpos)
[46.518180] Starting subtest: plane-immutable-zpos
[46.519590] Starting dynamic subtest: pipe-A-HDMI-A-1
[46.880303] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.361s)
[46.881031] Subtest plane-immutable-zpos: SUCCESS (0.363s)
[47.029091] [003/133] (959s left) gem_busy (close-race)
[47.077602] Starting subtest: close-race
[69.112071] Subtest close-race: SUCCESS (22.034s)
[69.491525] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[69.495989] [004/133] (937s left) gem_exec_schedule (lateslice)
[69.599292] Starting subtest: lateslice
[69.600030] Starting dynamic subtest: rcs0
[69.613930] Dynamic subtest rcs0: SUCCESS (0.015s)
[69.619923] Starting dynamic subtest: bcs0
[69.627991] Dynamic subtest bcs0: SUCCESS (0.014s)
[69.634492] Starting dynamic subtest: vcs0
[69.642905] Dynamic subtest vcs0: SUCCESS (0.015s)
[69.649791] Starting dynamic subtest: vcs1
[69.659104] Dynamic subtest vcs1: SUCCESS (0.016s)
[69.665904] Starting dynamic subtest: vecs0
[69.674035] Dynamic subtest vecs0: SUCCESS (0.015s)
[69.674873] Subtest lateslice: SUCCESS (0.075s)
[69.974426] [005/133] (936s left) kms_color_pipeline (plane-lut1d-lut1d)
[70.065744] Starting subtest: plane-lut1d-lut1d
[70.084194] Starting dynamic subtest: pipe-A-HDMI-A-1
[70.250988] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.166s)
[70.378426] Starting dynamic subtest: pipe-B-HDMI-A-1
[70.528516] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.157s)
[70.678111] Starting dynamic subtest: pipe-C-HDMI-A-1
[70.821061] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.148s)
[70.961995] Starting dynamic subtest: pipe-D-HDMI-A-1
[71.105433] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.148s)
[71.154075] Subtest plane-lut1d-lut1d: SUCCESS (1.088s)
[71.273427] [006/133] (935s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping)
[71.363624] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping
[71.367560] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[71.450671] [007/133] (935s left) kms_psr (pr-cursor-mmap-cpu)
[71.534376] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[71.576150] [008/133] (935s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[71.613444] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[71.614161] Starting dynamic subtest: smem
[71.688947] Dynamic subtest smem: SUCCESS (0.075s)
[71.689674] Subtest mixed-tiled-to-yf-tiled-ccs: SUCCESS (0.076s)
[71.887655] [009/133] (935s left) i915_drm_fdinfo (basics)
[71.922142] Starting subtest: basics
[71.922854] Subtest basics: SUCCESS (0.000s)
[71.999265] [010/133] (935s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[72.031202] Starting subtest: etime-multi-wait-all-available-unsubmitted
[72.141892] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.103s)
[72.220613] [011/133] (934s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[72.309169] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[72.310528] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[72.438855] [012/133] (934s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[72.526466] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[72.527819] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[72.629256] [013/133] (934s left) vgem_basic (unload)
[72.659598] Starting subtest: unload
[101.683302] Subtest unload: SUCCESS (28.990s)
[101.765550] [014/133] (905s left) prime_self_import (basic-llseek-size)
[101.796170] Starting subtest: basic-llseek-size
[101.798534] Subtest basic-llseek-size: SUCCESS (0.002s)
[101.905370] [015/133] (905s left) i915_pm_rpm (debugfs-read)
[103.314765] Starting subtest: debugfs-read
[104.517219] Subtest debugfs-read: SUCCESS (1.202s)
[104.698418] [016/133] (902s left) kms_cursor_crc (cursor-onscreen-256x85)
[104.787674] Starting subtest: cursor-onscreen-256x85
[104.792469] Starting dynamic subtest: pipe-A-HDMI-A-1
[105.650530] Dynamic subtest pipe-A-HDMI-A-1: FAIL (0.691s)
[105.656979] Starting dynamic subtest: pipe-D-HDMI-A-1
[106.780194] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.262s)
[106.780902] Subtest cursor-onscreen-256x85: FAIL (1.993s)
[106.922487] [017/133] (900s left) kms_scaling_modes (scaling-mode-full)
[107.010269] Starting subtest: scaling-mode-full
[107.014262] Subtest scaling-mode-full: SKIP (0.002s)
[107.100109] [018/133] (900s left) sw_sync (alloc_timeline)
[107.131580] Starting subtest: alloc_timeline
[107.132287] Subtest alloc_timeline: SUCCESS (0.000s)
[107.189395] [019/133] (900s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[107.272441] Starting subtest: plane-upscale-20x20-with-modifiers
[107.273130] Starting dynamic subtest: pipe-A
[107.669041] Dynamic subtest pipe-A: SUCCESS (0.397s)
[107.681792] Starting dynamic subtest: pipe-B
[108.102807] Dynamic subtest pipe-B: SUCCESS (0.433s)
[108.116859] Starting dynamic subtest: pipe-C
[108.544510] Dynamic subtest pipe-C: SUCCESS (0.441s)
[108.550579] Starting dynamic subtest: pipe-D
[108.981105] Dynamic subtest pipe-D: SUCCESS (0.436s)
[108.981841] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (1.709s)
[109.166751] [020/133] (898s left) prime_udl
[109.236885] [021/133] (898s left) gem_exec_whisper (basic-sync-all)
[109.278305] Starting subtest: basic-sync-all
[110.955280] Subtest basic-sync-all: SUCCESS (1.676s)
[111.136553] [022/133] (896s left) syncobj_timeline (signal-array)
[111.168294] Starting subtest: signal-array
[111.169036] Subtest signal-array: SUCCESS (0.000s)
[111.241721] [023/133] (896s left) kms_properties (plane-properties-legacy)
[111.330587] Starting subtest: plane-properties-legacy
[111.331332] Starting dynamic subtest: pipe-A-HDMI-A-1
[111.572840] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.241s)
[111.579539] Starting dynamic subtest: pipe-B-HDMI-A-1
[111.860793] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.287s)
[111.868551] Starting dynamic subtest: pipe-C-HDMI-A-1
[112.148956] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.287s)
[112.155951] Starting dynamic subtest: pipe-D-HDMI-A-1
[112.439999] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.290s)
[112.440728] Subtest plane-properties-legacy: SUCCESS (1.110s)
[112.611952] [024/133] (895s left) gem_exec_balancer (full-pulse)
[112.654905] Starting subtest: full-pulse
[114.287560] Subtest full-pulse: SUCCESS (1.632s)
[114.476354] [025/133] (893s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[114.561796] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[114.562520] Starting dynamic subtest: pipe-A-HDMI-A-1
[114.569404] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[114.577671] Starting dynamic subtest: pipe-B-HDMI-A-1
[114.584501] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[114.592671] Starting dynamic subtest: pipe-C-HDMI-A-1
[114.599448] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[114.607409] Starting dynamic subtest: pipe-D-HDMI-A-1
[114.614445] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[114.615840] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[114.803294] [026/133] (892s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[114.890534] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[114.891899] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[114.999278] [027/133] (892s left) kms_psr (fbc-pr-sprite-render)
[115.082463] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[115.121534] [028/133] (892s left) gem_readwrite (new-obj)
[115.153419] Starting subtest: new-obj
[115.154772] Subtest new-obj: SUCCESS (0.000s)
[115.247190] [029/133] (892s left) kms_vblank (wait-forked-busy)
[115.330795] Starting subtest: wait-forked-busy
[115.331489] Starting dynamic subtest: pipe-A-HDMI-A-1
[116.591083] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.260s)
[116.600527] Starting dynamic subtest: pipe-D-HDMI-A-1
[117.850113] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.255s)
[117.850866] Subtest wait-forked-busy: SUCCESS (2.520s)
[118.000383] [030/133] (889s left) kms_busy (extended-pageflip-hang-newfb)
[118.092536] Starting subtest: extended-pageflip-hang-newfb
[118.093327] Starting dynamic subtest: pipe-A
[131.765983] Dynamic subtest pipe-A: SUCCESS (13.672s)
[131.775181] Starting dynamic subtest: pipe-D
[145.578712] Dynamic subtest pipe-D: SUCCESS (13.810s)
[145.580486] Subtest extended-pageflip-hang-newfb: SUCCESS (27.488s)
[145.730039] [031/133] (862s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[145.819049] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[145.863592] [032/133] (862s left) gem_exec_whisper (basic-forked)
[145.906928] Starting subtest: basic-forked
[147.528336] Subtest basic-forked: SUCCESS (1.622s)
[147.725452] [033/133] (860s left) kms_force_connector_basic (force-connector-state)
[147.848641] Starting subtest: force-connector-state
[148.189109] Subtest force-connector-state: SUCCESS (0.340s)
[148.539867] [034/133] (859s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[148.625705] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[148.626381] Starting dynamic subtest: pipe-A-valid-mode
[149.629169] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.002s)
[149.629878] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SUCCESS (1.004s)
[149.792036] [035/133] (858s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[149.891991] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[149.893349] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[150.003681] [036/133] (858s left) kms_vblank (query-busy-hang)
[150.087202] Starting subtest: query-busy-hang
[150.089030] Starting dynamic subtest: pipe-A-HDMI-A-1
[163.466829] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (13.378s)
[163.476265] Starting dynamic subtest: pipe-D-HDMI-A-1
[177.794481] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (14.324s)
[177.795162] Subtest query-busy-hang: SUCCESS (27.708s)
[177.972882] [037/133] (830s left) kms_getfb (getfb-handle-protection)
[178.062692] Starting subtest: getfb-handle-protection
[178.078165] Subtest getfb-handle-protection: SUCCESS (0.016s)
[178.195705] [038/133] (830s left) perf (unprivileged-single-ctx-counters)
[178.289717] Starting subtest: unprivileged-single-ctx-counters
[178.291028] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[178.329242] [039/133] (829s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[178.416588] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[178.417931] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[178.537215] [040/133] (829s left) kms_atomic_transition (plane-all-transition-fencing)
[178.621641] Starting subtest: plane-all-transition-fencing
[178.622311] Starting dynamic subtest: pipe-A-HDMI-A-1
[180.570698] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.948s)
[180.646622] Starting dynamic subtest: pipe-B-HDMI-A-1
[182.697694] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.055s)
[182.745442] Subtest plane-all-transition-fencing: SUCCESS (4.124s)
[182.889793] [041/133] (825s left) gem_ctx_param (basic)
[182.933115] Starting subtest: basic
[182.933885] Subtest basic: SUCCESS (0.000s)
[182.997629] [042/133] (825s left) syncobj_timeline (host-signal-points)
[183.030693] Starting subtest: host-signal-points
[183.036450] Subtest host-signal-points: SUCCESS (0.006s)
[183.127984] [043/133] (825s left) gem_exec_basic (basic)
[183.164949] Starting subtest: basic
[183.165664] Starting dynamic subtest: rcs0-smem
[183.166344] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[183.174242] Starting dynamic subtest: bcs0-smem
[183.174931] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[183.181558] Starting dynamic subtest: vcs0-smem
[183.182251] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[183.188428] Starting dynamic subtest: vcs1-smem
[183.189103] Dynamic subtest vcs1-smem: SUCCESS (0.000s)
[183.195358] Starting dynamic subtest: vecs0-smem
[183.196017] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[183.196677] Subtest basic: SUCCESS (0.007s)
[183.370627] [044/133] (825s left) gem_eio (wait-1us)
[183.413479] Starting subtest: wait-1us
[183.569610] Subtest wait-1us: SUCCESS (0.155s)
[183.709061] [045/133] (824s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[183.796896] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[183.798228] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[183.902624] [046/133] (824s left) i915_suspend (basic-s3-without-i915)
[183.932945] Starting subtest: basic-s3-without-i915