[59.368545] Initializing watchdogs
[59.368648]   /dev/watchdog0
[59.381556] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.385433] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.387571] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.390757] [001/130] (960s left) kms_vblank (wait-busy-hang)
[59.556090] Starting subtest: wait-busy-hang
[59.558333] Starting dynamic subtest: pipe-A-eDP-1
[85.660628] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.216s)
[85.673510] Starting dynamic subtest: pipe-D-eDP-1
[110.692092] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.023s)
[110.694052] Subtest wait-busy-hang: SUCCESS (51.242s)
[111.479096] [002/130] (908s left) gem_render_linear_blits (basic)
[111.541507] Starting subtest: basic
[111.564931] Subtest basic: SUCCESS (0.024s)
[111.648854] [003/130] (908s left) gem_exec_flush (basic-wb-rw-before-default)
[111.718740] Starting subtest: basic-wb-rw-before-default
[117.193205] Subtest basic-wb-rw-before-default: SUCCESS (5.474s)
[117.372335] [004/130] (902s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[117.484843] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[118.753144] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.270s)
[119.060978] [005/130] (900s left) kms_color (invalid-ctm-matrix-sizes)
[119.159931] Starting subtest: invalid-ctm-matrix-sizes
[119.162343] Starting dynamic subtest: pipe-A
[119.164438] Dynamic subtest pipe-A: SUCCESS (0.002s)
[119.173392] Starting dynamic subtest: pipe-B
[119.175712] Dynamic subtest pipe-B: SUCCESS (0.003s)
[119.182233] Starting dynamic subtest: pipe-C
[119.184493] Dynamic subtest pipe-C: SUCCESS (0.003s)
[119.190996] Starting dynamic subtest: pipe-D
[119.192990] Dynamic subtest pipe-D: SUCCESS (0.004s)
[119.195053] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.012s)
[119.233452] [006/130] (900s left) gen7_exec_parse (basic-rejected)
[119.295322] Subtest basic-rejected: SKIP (0.000s)
[119.345391] [007/130] (900s left) gem_exec_fence (syncobj-channel)
[119.781636] Starting subtest: syncobj-channel
[120.803562] Subtest syncobj-channel: SUCCESS (1.019s)
[121.084350] [FACT gem_exec_fence (syncobj-channel)] new: kernel.kmod_is_loaded.vgem: true
[121.087397] [008/130] (898s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[121.199506] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[126.365223] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.167s)
[126.481208] [009/130] (893s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[126.585081] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[126.589644] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[127.662023] [010/130] (892s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[127.757042] Starting subtest: modeset-transition-nonblocking-fencing
[127.759233] Starting dynamic subtest: 1x-outputs
[138.288063] Dynamic subtest 1x-outputs: SUCCESS (10.528s)
[138.552181] Subtest modeset-transition-nonblocking-fencing: SUCCESS (10.794s)
[139.402453] [011/130] (880s left) gem_pxp (reject-modify-context-protection-off-3)
[139.530719] Starting subtest: reject-modify-context-protection-off-3
[139.533092] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[139.631882] [012/130] (880s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[140.025486] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[140.813080] [013/130] (879s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[141.016482] Starting subtest: cursor-rapid-movement-256x256
[141.021619] Starting dynamic subtest: pipe-A-eDP-1
[141.575223] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.554s)
[141.596859] Starting dynamic subtest: pipe-D-eDP-1
[142.736668] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.146s)
[142.739091] Subtest cursor-rapid-movement-256x256: SUCCESS (1.720s)
[143.579713] [014/130] (876s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[143.678857] Starting subtest: primary-x-tiled-reflect-x-180
[150.677767] Subtest primary-x-tiled-reflect-x-180: SUCCESS (6.998s)
[151.934617] [015/130] (868s left) gem_workarounds (reset)
[152.002349] Starting subtest: reset
[152.188748] Subtest reset: SUCCESS (0.187s)
[152.307030] [016/130] (867s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[152.414624] Starting subtest: x-tiled-64bpp-rotate-90
[152.434075] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[152.545043] [017/130] (867s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[152.646579] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[152.650658] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[152.759336] [018/130] (867s left) gem_fenced_exec_thrash (too-many-fences)
[152.822922] Subtest too-many-fences: SKIP (0.000s)
[152.888341] [019/130] (867s left) drm_mm (drm_mm)
[152.922601] Starting subtest: drm_mm
[152.940387] Starting dynamic subtest: drm_test_mm_init
[153.114710] Dynamic subtest drm_test_mm_init: SUCCESS (0.172s)
[153.121976] Starting dynamic subtest: drm_test_mm_debug
[153.234082] Dynamic subtest drm_test_mm_debug: SUCCESS (0.120s)
[153.241327] Starting dynamic subtest: drm_test_mm_align32
[153.366230] Dynamic subtest drm_test_mm_align32: SUCCESS (0.132s)
[153.375733] Starting dynamic subtest: drm_test_mm_align64
[153.478389] Dynamic subtest drm_test_mm_align64: SUCCESS (0.113s)
[153.487354] Starting dynamic subtest: drm_test_mm_lowest
[153.596401] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.116s)
[153.605117] Starting dynamic subtest: drm_test_mm_highest
[153.717491] Dynamic subtest drm_test_mm_highest: SUCCESS (0.122s)
[153.719517] Subtest drm_mm: SUCCESS (0.795s)
[153.908695] [020/130] (866s left) drm_buddy (drm_buddy)
[153.946371] Starting subtest: drm_buddy
[153.948864] Starting dynamic subtest: drm_test_buddy_alloc_limit
[154.065629] Dynamic subtest drm_test_buddy_alloc_limit: SUCCESS (0.116s)
[154.072992] Starting dynamic subtest: drm_test_buddy_alloc_optimistic
[154.183578] Dynamic subtest drm_test_buddy_alloc_optimistic: SUCCESS (0.117s)
[154.191087] Starting dynamic subtest: drm_test_buddy_alloc_pessimistic
[154.304571] Dynamic subtest drm_test_buddy_alloc_pessimistic: SUCCESS (0.121s)
[154.313414] Starting dynamic subtest: drm_test_buddy_alloc_pathological
[154.431907] Dynamic subtest drm_test_buddy_alloc_pathological: SUCCESS (0.127s)
[154.440520] Starting dynamic subtest: drm_test_buddy_alloc_contiguous
[154.557769] Dynamic subtest drm_test_buddy_alloc_contiguous: SUCCESS (0.127s)
[154.565277] Starting dynamic subtest: drm_test_buddy_alloc_clear
[154.710169] Dynamic subtest drm_test_buddy_alloc_clear: SUCCESS (0.151s)
[154.718782] Starting dynamic subtest: drm_test_buddy_alloc_range_bias
[154.829656] Dynamic subtest drm_test_buddy_alloc_range_bias: SUCCESS (0.119s)
[154.832095] Subtest drm_buddy: SUCCESS (0.885s)
[154.998479] [021/130] (865s left) kms_getfb (getfb-handle-zero)
[155.061989] Starting subtest: getfb-handle-zero
[155.064138] Subtest getfb-handle-zero: SUCCESS (0.000s)
[155.171213] [022/130] (865s left) kms_psr (fbc-psr2-basic)
[155.272830] Starting subtest: fbc-psr2-basic
[155.275162] Starting dynamic subtest: eDP-1
[155.279552] Dynamic subtest eDP-1: SKIP (0.000s)
[155.283595] Subtest fbc-psr2-basic: SKIP (0.001s)
[155.355114] [023/130] (865s left) api_intel_bb (intel-bb-blit-none)
[155.407365] Starting subtest: intel-bb-blit-none
[159.262052] Subtest intel-bb-blit-none: SUCCESS (3.855s)
[159.390885] [024/130] (861s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[159.498463] Starting subtest: x-tiled-16bpp-rotate-90
[159.517729] Subtest x-tiled-16bpp-rotate-90: SKIP (0.019s)
[159.622822] [025/130] (860s left) gem_exec_whisper (basic-sync-all)
[159.699861] Starting subtest: basic-sync-all
[166.958428] Subtest basic-sync-all: SUCCESS (7.259s)
[167.140215] [026/130] (853s left) kms_atomic (crtc-invalid-params-fence)
[167.245932] Starting subtest: crtc-invalid-params-fence
[167.248319] Starting dynamic subtest: pipe-A-eDP-1
[167.680487] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.432s)
[167.682494] Subtest crtc-invalid-params-fence: SUCCESS (0.433s)
[168.458968] [027/130] (852s left) kms_dp_link_training (uhbr-mst)
[168.555862] Starting subtest: uhbr-mst
[168.564539] Subtest uhbr-mst: SKIP (0.000s)
[168.623888] [028/130] (852s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[168.718062] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[168.720488] Starting dynamic subtest: pipe-A
[168.736277] Dynamic subtest pipe-A: SKIP (0.012s)
[168.744946] Starting dynamic subtest: pipe-B
[168.757401] Dynamic subtest pipe-B: SKIP (0.022s)
[168.764758] Starting dynamic subtest: pipe-C
[168.779646] Dynamic subtest pipe-C: SKIP (0.022s)
[168.786949] Starting dynamic subtest: pipe-D
[168.801099] Dynamic subtest pipe-D: SKIP (0.023s)
[168.804924] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.079s)
[168.896237] [029/130] (851s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[169.004545] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[169.008746] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[169.105228] [030/130] (851s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[169.207394] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[169.220409] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.011s)
[169.327677] [031/130] (851s left) kms_flip (dpms-off-confusion)
[169.429966] Starting subtest: dpms-off-confusion
[169.431664] Starting dynamic subtest: A-eDP1
[178.170163] Dynamic subtest A-eDP1: SUCCESS (8.741s)
[178.180118] Starting dynamic subtest: B-eDP1
[186.886813] Dynamic subtest B-eDP1: SUCCESS (8.716s)
[186.894586] Starting dynamic subtest: C-eDP1
[195.589711] Dynamic subtest C-eDP1: SUCCESS (8.702s)
[195.597416] Starting dynamic subtest: D-eDP1
[204.314031] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[204.316519] Subtest dpms-off-confusion: SUCCESS (34.890s)
[205.469024] [032/130] (815s left) kms_getfb (getfb-addfb-different-handles)
[205.529271] Starting subtest: getfb-addfb-different-handles
[205.531759] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[205.639378] [033/130] (815s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[205.702187] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[205.704319] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[205.812520] [034/130] (815s left) gem_exec_fence (basic-await)
[205.988478] Starting subtest: basic-await
[205.991114] Starting dynamic subtest: rcs0
[206.071994] Dynamic subtest rcs0: SUCCESS (0.084s)
[206.083102] Starting dynamic subtest: bcs0
[206.149711] Dynamic subtest bcs0: SUCCESS (0.077s)
[206.159845] Starting dynamic subtest: vcs0
[206.227085] Dynamic subtest vcs0: SUCCESS (0.077s)
[206.236533] Starting dynamic subtest: vcs1
[206.303876] Dynamic subtest vcs1: SUCCESS (0.076s)
[206.313124] Starting dynamic subtest: vecs0
[206.382030] Dynamic subtest vecs0: SUCCESS (0.078s)
[206.391493] Starting dynamic subtest: ccs0
[206.470717] Dynamic subtest ccs0: SUCCESS (0.088s)
[206.473500] Subtest basic-await: SUCCESS (0.483s)
[207.998934] [035/130] (812s left) kms_prop_blob (blob-prop-validate)
[208.047578] Starting subtest: blob-prop-validate
[208.050092] Subtest blob-prop-validate: SUCCESS (0.000s)
[208.158211] [036/130] (812s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[208.214617] Starting subtest: y-tiled-ccs-to-x-tiled
[208.219171] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[208.394299] [037/130] (812s left) gem_exec_schedule (u-semaphore-user)
[208.477970] Starting subtest: u-semaphore-user
[208.533260] Subtest u-semaphore-user: SUCCESS (0.061s)
[209.930307] [038/130] (811s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[210.038434] Starting subtest: yf-tiled-addfb-size-overflow
[210.042966] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[210.146194] [039/130] (810s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[210.245710] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[210.249773] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[210.343588] [040/130] (810s left) gem_softpin (allocator-evict)
[210.415294] Starting subtest: allocator-evict
[210.417667] Starting dynamic subtest: rcs0
[236.278179] Dynamic subtest rcs0: SUCCESS (25.864s)
[236.286878] Starting dynamic subtest: bcs0
[262.339271] Dynamic subtest bcs0: SUCCESS (26.059s)
[262.349889] Starting dynamic subtest: vcs0
[288.628505] Dynamic subtest vcs0: SUCCESS (26.287s)
[288.638894] Starting dynamic subtest: vcs1
[314.573841] Dynamic subtest vcs1: SUCCESS (25.941s)
[314.584719] Starting dynamic subtest: vecs0
[340.524262] Dynamic subtest vecs0: SUCCESS (25.948s)
[340.534239] Starting dynamic subtest: ccs0
[366.513092] Dynamic subtest ccs0: SUCCESS (25.986s)
[366.515859] Subtest allocator-evict: SUCCESS (156.088s)
[366.587317] [041/130] (654s left) gem_exec_parallel (basic)
[366.681774] Starting subtest: basic
[366.684115] Starting dynamic subtest: rcs0
[368.209170] Dynamic subtest rcs0: SUCCESS (1.527s)
[368.218227] Starting dynamic subtest: bcs0
[369.720043] Dynamic subtest bcs0: SUCCESS (1.510s)
[369.729907] Starting dynamic subtest: vcs0
[372.906338] Dynamic subtest vcs0: SUCCESS (3.184s)
[372.916719] Starting dynamic subtest: vcs1
[374.901035] Dynamic subtest vcs1: SUCCESS (1.994s)
[374.909691] Starting dynamic subtest: vecs0
[376.940362] Dynamic subtest vecs0: SUCCESS (2.039s)
[376.948871] Starting dynamic subtest: ccs0
[378.452073] Dynamic subtest ccs0: SUCCESS (1.512s)
[378.454324] Subtest basic: SUCCESS (11.770s)
[378.638167] [042/130] (642s left) gem_userptr_blits (sync-overlap)
[378.703875] Starting subtest: sync-overlap
[378.706312] Subtest sync-overlap: SUCCESS (0.000s)
[378.826113] [043/130] (642s left) kms_flip (bo-too-big-interruptible)
[378.941587] Starting subtest: bo-too-big-interruptible
[378.946169] Starting dynamic subtest: A-eDP1
[380.736401] Dynamic subtest A-eDP1: SUCCESS (1.792s)
[380.747577] Starting dynamic subtest: B-eDP1
[382.477794] Dynamic subtest B-eDP1: SUCCESS (1.740s)
[382.487779] Starting dynamic subtest: C-eDP1
[384.205780] Dynamic subtest C-eDP1: SUCCESS (1.727s)
[384.215044] Starting dynamic subtest: D-eDP1
[385.951319] Dynamic subtest D-eDP1: SUCCESS (1.745s)
[385.953686] Subtest bo-too-big-interruptible: SUCCESS (7.011s)
[387.184557] [044/130] (634s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[387.294442] Starting subtest: fbcpsr-rgb565-draw-pwrite
[392.127412] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (4.835s)
[392.246280] [045/130] (629s left) gem_pipe_control_store_loop (reused-buffer)
[392.317447] Starting subtest: reused-buffer
[394.471029] Subtest reused-buffer: SUCCESS (2.152s)
[394.585937] [046/130] (626s left) perf (low-oa-exponent-permissions)
[394.719871] Starting subtest: low-oa-exponent-permissions
[394.766094] Subtest low-oa-exponent-permissions: SUCCESS (0.046s)
[394.808962] [047/130] (626s left) kms_fbcon_fbt (psr)
[394.870503] Starting subtest: psr
[400.191250] Subtest psr: SUCCESS (5.318s)
[400.244946] [048/130] (621s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[400.640803] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[401.415491] [049/130] (620s left) gem_huc_copy (huc-copy)
[401.481725] Starting subtest: huc-copy
[401.486865] Subtest huc-copy: SUCCESS (0.003s)
[401.619856] [050/130] (619s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[401.724410] Starting subtest: fbc-rgb565-draw-blt
[405.008278] Subtest fbc-rgb565-draw-blt: SUCCESS (3.288s)
[405.138815] [051/130] (616s left) syncobj_wait (wait-any-snapshot)
[405.203408] Starting subtest: wait-any-snapshot
[405.285703] Subtest wait-any-snapshot: SUCCESS (0.081s)
[405.371051] [052/130] (616s left) gem_exec_balancer (full-late-pulse)
[405.443508] Starting subtest: full-late-pulse
[409.728655] Subtest full-late-pulse: SUCCESS (4.284s)
[410.042947] [053/130] (611s left) i915_query (engine-info-invalid)
[410.106101] Starting subtest: engine-info-invalid
[410.108350] Subtest engine-info-invalid: SUCCESS (0.000s)
[410.218122] [054/130] (611s left) gem_pread (snoop)
[410.272134] Subtest snoop: SKIP (0.000s)
[410.340306] [055/130] (611s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[410.448893] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[410.453156] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[410.552878] [056/130] (611s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[410.657015] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[410.660987] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[410.770294] [057/130] (610s left) i915_getparams_basic (basic-eu-total)
[410.827305] Starting subtest: basic-eu-total
[410.831449] Subtest basic-eu-total: SUCCESS (0.000s)
[410.918331] [058/130] (610s left) vgem_basic (mmap)
[410.964494] Starting subtest: mmap
[410.989394] Subtest mmap: SUCCESS (0.027s)
[411.043854] [059/130] (610s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[411.153271] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[431.123274] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (19.971s)
[431.270175] [060/130] (590s left) gem_eio (in-flight-contexts-immediate)
[431.512323] Starting subtest: in-flight-contexts-immediate
[442.941091] Subtest in-flight-contexts-immediate: SUCCESS (11.426s)
[443.057281] [061/130] (578s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[443.174589] Starting subtest: psr-rgb101010-draw-render
[448.333162] Subtest psr-rgb101010-draw-render: SUCCESS (5.162s)
[448.465718] [062/130] (573s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[448.579507] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[448.583964] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[448.689976] [063/130] (573s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[448.818535] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[448.822991] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[448.911201] [064/130] (573s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[449.011716] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[449.015878] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[449.109520] [065/130] (572s left) prime_busy (hang)
[449.179338] Starting subtest: hang
[449.181624] Starting dynamic subtest: rcs0
[475.456837] Dynamic subtest rcs0: SUCCESS (26.273s)
[475.467637] Starting dynamic subtest: bcs0
[482.611147] Dynamic subtest bcs0: SUCCESS (7.153s)
[482.621686] Starting dynamic subtest: vcs0
[489.593874] Dynamic subtest vcs0: SUCCESS (6.980s)
[489.603852] Starting dynamic subtest: vcs1
[496.567997] Dynamic subtest vcs1: SUCCESS (6.977s)
[496.576427] Starting dynamic subtest: vecs0
[503.599052] Dynamic subtest vecs0: SUCCESS (7.030s)
[503.607688] Starting dynamic subtest: ccs0
[517.438839] Dynamic subtest ccs0: SUCCESS (13.837s)
[517.441842] Subtest hang: SUCCESS (68.255s)
[517.563766] [066/130] (504s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[522.620186] Starting subtest: pr-cursor-plane-update-sf
[522.624927] Subtest pr-cursor-plane-update-sf: SKIP (0.002s)
[523.458587] [067/130] (498s left) perf (stress-open-close)
[523.541975] Starting subtest: stress-open-close
[523.544271] Starting dynamic subtest: 0-rcs0
[525.719319] Dynamic subtest 0-rcs0: SUCCESS (2.177s)
[525.734788] Starting dynamic subtest: 1-vcs1
[527.898584] Dynamic subtest 1-vcs1: SUCCESS (2.179s)
[527.900485] Subtest stress-open-close: SUCCESS (4.358s)
[527.943408] [068/130] (494s left) sysfs_heartbeat_interval (idempotent)
[528.015750] Starting subtest: idempotent
[528.017788] Starting dynamic subtest: vecs0
[528.020377] Dynamic subtest vecs0: SUCCESS (0.001s)
[528.029099] Starting dynamic subtest: ccs0
[528.031139] Dynamic subtest ccs0: SUCCESS (0.003s)
[528.038999] Starting dynamic subtest: vcs0
[528.040990] Dynamic subtest vcs0: SUCCESS (0.001s)
[528.050351] Starting dynamic subtest: bcs0
[528.051991] Dynamic subtest bcs0: SUCCESS (0.002s)
[528.059563] Starting dynamic subtest: rcs0
[528.061200] Dynamic subtest rcs0: SUCCESS (0.002s)
[528.068625] Starting dynamic subtest: vcs1
[528.070247] Dynamic subtest vcs1: SUCCESS (0.000s)
[528.071943] Subtest idempotent: SUCCESS (0.013s)
[528.140790] [069/130] (494s left) gem_exec_reloc (basic-write-cpu)
[528.211261] Subtest basic-write-cpu: SKIP (0.000s)
[528.288357] [070/130] (493s left) gem_reg_read (timestamp-moving)
[528.343044] Starting subtest: timestamp-moving
[529.344425] Subtest timestamp-moving: SUCCESS (1.001s)
[529.427165] [071/130] (492s left) kms_pm_dc (dc3co-vpb-simulation)
[530.543948] Starting subtest: dc3co-vpb-simulation
[539.290457] Subtest dc3co-vpb-simulation: SKIP (8.744s)
[539.393618] [072/130] (482s left) kms_pm_rpm (cursor)
[541.046246] Starting subtest: cursor
[541.052078] Subtest cursor: SKIP (0.000s)
[541.686346] [073/130] (480s left) kms_plane_alpha_blend (constant-alpha-min)
[541.785104] Starting subtest: constant-alpha-min
[542.201847] Starting dynamic subtest: pipe-A-eDP-1
[542.901445] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.696s)
[542.911086] Starting dynamic subtest: pipe-D-eDP-1
[544.337390] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.437s)
[544.339781] Subtest constant-alpha-min: SUCCESS (2.552s)
[545.124396] [074/130] (477s left) gem_exec_reloc (basic-concurrent16)
[545.194723] Subtest basic-concurrent16: SKIP (0.000s)
[545.263423] [075/130] (477s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[545.371166] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[545.375181] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[545.475809] [076/130] (476s left) kms_psr (fbc-psr-cursor-plane-onoff)
[545.567608] Starting subtest: fbc-psr-cursor-plane-onoff
[545.569920] Starting dynamic subtest: eDP-1
[545.574507] Dynamic subtest eDP-1: SKIP (0.000s)
[545.578993] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[545.635785] [077/130] (476s left) kms_vblank (ts-continuation-suspend)
[545.736266] Starting subtest: ts-continuation-suspend
[545.738535] Starting dynamic subtest: pipe-A-eDP-1
[562.852241] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.566s)
[562.869501] Starting dynamic subtest: pipe-D-eDP-1
[579.765001] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.143s)
[579.767231] Subtest ts-continuation-suspend: SUCCESS (3.713s)
[580.585607] [078/130] (472s left) gem_exec_schedule (preempt-contexts)
[580.662291] Starting subtest: preempt-contexts
[580.664484] Starting dynamic subtest: rcs0
[580.709646] Dynamic subtest rcs0: SUCCESS (0.054s)
[580.720193] Starting dynamic subtest: bcs0
[580.762597] Dynamic subtest bcs0: SUCCESS (0.054s)
[580.771984] Starting dynamic subtest: vcs0
[580.802769] Dynamic subtest vcs0: SUCCESS (0.041s)
[580.812081] Starting dynamic subtest: vcs1
[580.839873] Dynamic subtest vcs1: SUCCESS (0.038s)
[580.850222] Starting dynamic subtest: vecs0
[580.878420] Dynamic subtest vecs0: SUCCESS (0.040s)
[580.887686] Starting dynamic subtest: ccs0
[580.916315] Dynamic subtest ccs0: SUCCESS (0.037s)
[580.918548] Subtest preempt-contexts: SUCCESS (0.268s)
[582.403616] [079/130] (470s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[582.500486] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[582.502653] Starting dynamic subtest: pipe-A-eDP-1
[582.523979] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[582.533589] Starting dynamic subtest: pipe-B-eDP-1
[582.553575] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[582.562038] Starting dynamic subtest: pipe-C-eDP-1
[582.582405] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[582.590806] Starting dynamic subtest: pipe-D-eDP-1
[582.612245] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[582.616486] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[584.708030] [080/130] (468s left) kms_cursor_crc (cursor-onscreen-32x10)
[584.856180] Starting subtest: cursor-onscreen-32x10
[584.896981] Subtest cursor-onscreen-32x10: SKIP (0.037s)
[585.152289] [081/130] (467s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[585.219191] Starting subtest: etime-multi-wait-all-submitted-signaled
[585.324655] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[585.395832] [082/130] (467s left) gem_partial_pwrite_pread (write)
[585.447988] Subtest write: SKIP (0.000s)
[585.512445] [083/130] (467s left) gem_mmap_wc (pf-nonblock)
[585.566290] Subtest pf-nonblock: SKIP (0.000s)
[585.614357] [084/130] (467s left) gem_barrier_race (remote-request)
[585.681399] Starting subtest: remote-request
[585.683443] Starting dynamic subtest: rcs0
[590.874870] Dynamic subtest rcs0: SUCCESS (5.264s)
[590.878473] Subtest remote-request: SUCCESS (5.265s)
[591.011639] [085/130] (461s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[591.124810] Starting subtest: y-tiled-64bpp-rotate-270
[591.128665] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[591.237172] [086/130] (461s left) tools_test (sysfs_l3_parity)
[591.284512] Starting subtest: sysfs_l3_parity
[591.367748] Subtest sysfs_l3_parity: SKIP (0.091s)
[591.416996] [087/130] (461s left) kms_flip (2x-busy-flip)
[591.514014] Starting subtest: 2x-busy-flip
[591.525135] Subtest 2x-busy-flip: SKIP (0.011s)
[591.605923] [088/130] (461s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt)
[591.722706] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt
[594.907640] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (3.211s)
[595.042633] [089/130] (457s left) kms_tiled_display (basic-test-pattern)
[595.161054] Subtest basic-test-pattern: SKIP (0.000s)
[595.201400] [090/130] (457s left) kms_busy (basic-hang)
[595.301371] Starting subtest: basic-hang
[595.303381] Starting dynamic subtest: flip-pipe-A
[595.935826] Dynamic subtest flip-pipe-A: SUCCESS (0.634s)
[595.952731] Starting dynamic subtest: modeset-pipe-A
[599.295723] Dynamic subtest modeset-pipe-A: SUCCESS (3.374s)
[599.306208] Starting dynamic subtest: flip-pipe-D
[600.492662] Dynamic subtest flip-pipe-D: SUCCESS (1.198s)
[600.503180] Starting dynamic subtest: modeset-pipe-D
[603.885729] Dynamic subtest modeset-pipe-D: SUCCESS (3.403s)
[603.887905] Subtest basic-hang: SUCCESS (8.615s)
[604.680706] [091/130] (448s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[609.716215] Starting subtest: psr2-cursor-plane-update-sf
[609.719124] Starting dynamic subtest: pipe-A-eDP-1
[611.025324] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.306s)
[611.035343] Starting dynamic subtest: pipe-B-eDP-1
[612.348313] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.321s)
[612.350874] Subtest psr2-cursor-plane-update-sf: SUCCESS (2.632s)
[613.219249] [092/130] (439s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[613.324753] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[613.329439] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[613.431473] [093/130] (439s left) gem_ctx_persistence (engines-hostile)
[613.499017] Starting subtest: engines-hostile
[613.501387] Starting dynamic subtest: rcs0
[613.586290] Dynamic subtest rcs0: SUCCESS (0.087s)
[613.598520] Starting dynamic subtest: bcs0
[613.699434] Dynamic subtest bcs0: SUCCESS (0.111s)
[613.711525] Starting dynamic subtest: vcs0
[613.810200] Dynamic subtest vcs0: SUCCESS (0.109s)
[613.822246] Starting dynamic subtest: vcs1
[613.886398] Dynamic subtest vcs1: SUCCESS (0.074s)
[613.897986] Starting dynamic subtest: vecs0
[614.030161] Dynamic subtest vecs0: SUCCESS (0.142s)
[614.042268] Starting dynamic subtest: ccs0
[614.153630] Dynamic subtest ccs0: SUCCESS (0.121s)
[614.155977] Subtest engines-hostile: SUCCESS (0.655s)
[614.234048] [094/130] (438s left) gem_ctx_sseu (mmap-args)
[614.305210] Subtest mmap-args: SKIP (0.000s)
[614.375371] [095/130] (438s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[614.481080] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[614.483131] Starting dynamic subtest: pipe-A-eDP-1
[615.246789] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.755s)
[615.257740] Starting dynamic subtest: pipe-B-eDP-1
[617.063177] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.814s)
[617.078786] Starting dynamic subtest: pipe-C-eDP-1
[618.887651] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.822s)
[618.897841] Starting dynamic subtest: pipe-D-eDP-1
[620.725321] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.836s)
[620.727624] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.234s)
[621.823908] [096/130] (431s left) i915_query (query-topology-known-pci-ids)
[621.879615] Starting subtest: query-topology-known-pci-ids
[621.884311] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[621.978993] [097/130] (431s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[622.090110] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[622.094230] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[622.181622] [098/130] (431s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[622.278654] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[622.280776] Starting dynamic subtest: pipe-A-default-mode
[622.576914] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[622.581635] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.295s)
[623.359234] [099/130] (429s left) core_getversion (basic)
[623.402335] Starting subtest: basic
[623.406968] Subtest basic: SUCCESS (0.000s)
[623.458165] [100/130] (429s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[623.564754] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[623.569002] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[623.679280] [101/130] (429s left) gem_exec_flush (basic-uc-pro-default)
[623.759288] Starting subtest: basic-uc-pro-default
[629.248073] Subtest basic-uc-pro-default: SUCCESS (5.489s)
[629.430678] [102/130] (423s left) kms_psr (psr2-sprite-plane-onoff)
[629.544998] Starting subtest: psr2-sprite-plane-onoff
[629.547294] Starting dynamic subtest: eDP-1
[631.254518] Dynamic subtest eDP-1: SUCCESS (1.707s)
[631.256864] Subtest psr2-sprite-plane-onoff: SUCCESS (1.708s)
[632.110479] [103/130] (421s left) perf_pmu (busy-idle-no-semaphores)
[632.154771] Starting subtest: busy-idle-no-semaphores
[632.157311] Starting dynamic subtest: rcs0
[632.818600] Dynamic subtest rcs0: SUCCESS (0.657s)
[632.830234] Starting dynamic subtest: bcs0
[633.458692] Dynamic subtest bcs0: SUCCESS (0.646s)
[633.469747] Starting dynamic subtest: vcs0
[634.108227] Dynamic subtest vcs0: SUCCESS (0.648s)
[634.121022] Starting dynamic subtest: vcs1
[635.911048] Dynamic subtest vcs1: SUCCESS (1.801s)
[635.923440] Starting dynamic subtest: vecs0
[637.958950] Dynamic subtest vecs0: SUCCESS (2.047s)
[637.970319] Starting dynamic subtest: ccs0
[639.948042] Dynamic subtest ccs0: SUCCESS (1.987s)
[639.950946] Subtest busy-idle-no-semaphores: SUCCESS (7.795s)
[640.077836] [104/130] (413s left) kms_content_protection (dp-mst-lic-type-0)
[640.266750] Starting subtest: dp-mst-lic-type-0
[640.271272] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[640.408807] [105/130] (413s left) gem_ctx_isolation (dirty-create)
[640.469574] Starting subtest: dirty-create
[640.471941] Starting dynamic subtest: rcs0
[640.936861] Dynamic subtest rcs0: SUCCESS (0.467s)
[640.948867] Starting dynamic subtest: bcs0
[641.390447] Dynamic subtest bcs0: SUCCESS (0.453s)
[641.402373] Starting dynamic subtest: vcs0
[641.839934] Dynamic subtest vcs0: SUCCESS (0.449s)
[641.851156] Starting dynamic subtest: vcs1
[643.744341] Dynamic subtest vcs1: SUCCESS (1.904s)
[643.753891] Starting dynamic subtest: vecs0
[645.780319] Dynamic subtest vecs0: SUCCESS (2.036s)
[645.791992] Starting dynamic subtest: ccs0
[647.802895] Dynamic subtest ccs0: SUCCESS (2.022s)
[647.805522] Subtest dirty-create: SUCCESS (7.336s)
[648.052995] [106/130] (405s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[648.166602] Starting subtest: pipe-fbc-rte
[648.172976] Starting dynamic subtest: pipe-B-eDP-1
[652.527817] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.367s)
[652.536001] Subtest pipe-fbc-rte: SUCCESS (4.369s)
[653.733739] [107/130] (399s left) gem_create (create-valid-nonaligned)
[653.794550] Starting subtest: create-valid-nonaligned
[653.796591] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[653.979249] [108/130] (399s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[654.070819] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[654.075606] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[655.161882] [109/130] (398s left) vgem_basic (second-client)
[655.210875] Starting subtest: second-client
[655.230449] Subtest second-client: SUCCESS (0.021s)
[655.287582] [110/130] (398s left) kms_lease (lease-invalid-crtc)
[655.390936] Starting subtest: lease-invalid-crtc
[655.393292] Starting dynamic subtest: pipe-A-eDP-1
[655.395626] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[655.406158] Starting dynamic subtest: pipe-B-eDP-1
[655.408490] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[655.417691] Starting dynamic subtest: pipe-C-eDP-1
[655.419951] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[655.428647] Starting dynamic subtest: pipe-D-eDP-1
[655.430595] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[655.432490] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[655.465616] [111/130] (398s left) kms_psr (fbc-psr-sprite-render)
[655.566781] Starting subtest: fbc-psr-sprite-render
[655.569088] Starting dynamic subtest: eDP-1
[655.573190] Dynamic subtest eDP-1: SKIP (0.000s)
[655.577356] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[655.644790] [112/130] (398s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[655.750237] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[660.910151] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (5.165s)
[661.068495] [113/130] (392s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[661.170811] Starting subtest: addfb25-y-tiled-small-legacy
[661.174750] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[661.274690] [114/130] (392s left) gem_ctx_persistence (legacy-engines-persistence)
[661.322645] Starting subtest: legacy-engines-persistence
[661.324844] Starting dynamic subtest: render
[662.143382] Dynamic subtest render: SUCCESS (0.820s)
[662.156196] Starting dynamic subtest: bsd1
[662.956615] Dynamic subtest bsd1: SUCCESS (0.811s)
[662.968843] Starting dynamic subtest: bsd2
[663.737651] Dynamic subtest bsd2: SUCCESS (0.778s)
[663.750354] Starting dynamic subtest: blt
[664.519641] Dynamic subtest blt: SUCCESS (0.780s)
[664.531335] Starting dynamic subtest: vebox
[665.317793] Dynamic subtest vebox: SUCCESS (0.796s)
[665.320679] Subtest legacy-engines-persistence: SUCCESS (3.997s)
[665.395398] [115/130] (388s left) gem_render_copy (linear-to-vebox-y-tiled)
[665.459825] Starting subtest: linear-to-vebox-y-tiled
[665.464369] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[665.643230] [116/130] (388s left) gem_mmap_gtt (zero-extend)
[665.700394] Subtest zero-extend: SKIP (0.000s)
[665.753524] [117/130] (388s left) kms_rotation_crc (sprite-rotation-180)
[665.855694] Starting subtest: sprite-rotation-180
[670.751643] Subtest sprite-rotation-180: SUCCESS (4.897s)
[671.961981] [118/130] (382s left) gem_basic (bad-close)
[672.018895] Starting subtest: bad-close
[672.023116] Subtest bad-close: SUCCESS (0.000s)
[672.101099] [119/130] (381s left) kms_feature_discovery (display-1x)
[672.204616] Starting subtest: display-1x
[672.206397] Subtest display-1x: SUCCESS (0.000s)
[672.270929] [120/130] (381s left) kms_plane (pixel-format)
[672.361019] Starting subtest: pixel-format
[672.398618] Starting dynamic subtest: pipe-A-plane-0
[677.031317] Dynamic subtest pipe-A-plane-0: SUCCESS (4.633s)
[677.041386] Starting dynamic subtest: pipe-A-plane-3
[679.254690] Dynamic subtest pipe-A-plane-3: SUCCESS (2.222s)
[679.265616] Starting dynamic subtest: pipe-A-plane-5
[679.421532] Dynamic subtest pipe-A-plane-5: SUCCESS (0.166s)
[680.551304] Starting dynamic subtest: pipe-B-plane-0
[685.145278] Dynamic subtest pipe-B-plane-0: SUCCESS (4.600s)
[685.160079] Starting dynamic subtest: pipe-B-plane-3
[687.323884] Dynamic subtest pipe-B-plane-3: SUCCESS (2.177s)
[687.336100] Starting dynamic subtest: pipe-B-plane-5
[687.523940] Dynamic subtest pipe-B-plane-5: SUCCESS (0.199s)
[687.834002] Subtest pixel-format: SUCCESS (15.467s)
[688.617976] [121/130] (365s left) gem_spin_batch (resubmit-new)
[688.682113] Starting subtest: resubmit-new
[688.684612] Starting dynamic subtest: rcs0
[688.691967] Dynamic subtest rcs0: SUCCESS (0.010s)
[688.704601] Starting dynamic subtest: bcs0
[688.706690] Dynamic subtest bcs0: SUCCESS (0.005s)
[688.716076] Starting dynamic subtest: vcs0
[688.718410] Dynamic subtest vcs0: SUCCESS (0.004s)
[688.727525] Starting dynamic subtest: vcs1
[688.729494] Dynamic subtest vcs1: SUCCESS (0.004s)
[688.738208] Starting dynamic subtest: vecs0
[688.740205] Dynamic subtest vecs0: SUCCESS (0.004s)
[688.748852] Starting dynamic subtest: ccs0
[688.750766] Dynamic subtest ccs0: SUCCESS (0.004s)
[688.752661] Subtest resubmit-new: SUCCESS (0.034s)
[690.373018] [122/130] (363s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[690.477431] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[690.479792] Starting dynamic subtest: pipe-A-eDP-1
[690.500244] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[690.511521] Starting dynamic subtest: pipe-B-eDP-1
[690.530613] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[690.540162] Starting dynamic subtest: pipe-C-eDP-1
[690.559309] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[690.568709] Starting dynamic subtest: pipe-D-eDP-1
[690.588165] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[690.590359] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[692.679977] [123/130] (361s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[692.791052] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[692.795376] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[692.895540] [124/130] (361s left) sysfs_heartbeat_interval (precise)
[692.954303] Starting subtest: precise
[692.956767] Starting dynamic subtest: vecs0
[700.340734] Dynamic subtest vecs0: SUCCESS (7.384s)
[700.353486] Starting dynamic subtest: ccs0
[701.492433] Dynamic subtest ccs0: SUCCESS (1.148s)
[701.504839] Starting dynamic subtest: vcs0
[708.343741] Dynamic subtest vcs0: SUCCESS (6.848s)
[708.354420] Starting dynamic subtest: bcs0
[709.575548] Dynamic subtest bcs0: SUCCESS (1.231s)
[709.587525] Starting dynamic subtest: rcs0
[710.707906] Dynamic subtest rcs0: SUCCESS (1.133s)
[710.719986] Starting dynamic subtest: vcs1
[718.305664] Dynamic subtest vcs1: SUCCESS (7.597s)
[718.308165] Subtest precise: SUCCESS (25.350s)
[718.381828] [125/130] (335s left) kms_psr (psr2-sprite-mmap-cpu)
[718.488754] Starting subtest: psr2-sprite-mmap-cpu
[718.491102] Starting dynamic subtest: eDP-1
[720.153197] Dynamic subtest eDP-1: SUCCESS (1.665s)
[720.155391] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.666s)
[721.025462] [126/130] (333s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[721.077174] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[721.079130] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[721.190954] [127/130] (333s left) syncobj_timeline (invalid-transfer-non-existent-point)
[721.252238] Starting subtest: invalid-transfer-non-existent-point
[721.254578] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[721.349408] [128/130] (333s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[721.402336] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[721.404617] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[721.515131] [129/130] (332s left) kms_invalid_mode (int-max-clock)
[721.619908] Starting subtest: int-max-clock
[721.622320] Starting dynamic subtest: pipe-A-eDP-1
[721.903704] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[721.919775] Starting dynamic subtest: pipe-B-eDP-1
[721.922294] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[721.933872] Starting dynamic subtest: pipe-C-eDP-1
[721.935847] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[721.945326] Starting dynamic subtest: pipe-D-eDP-1
[721.947657] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[721.949871] Subtest int-max-clock: SUCCESS (0.292s)
[722.742982] [130/130] (331s left) i915_pm_rpm (debugfs-forcewake-user)
[724.499410] Starting subtest: debugfs-forcewake-user
[735.893274] Subtest debugfs-forcewake-user: SUCCESS (11.394s)
[736.246397] Closing watchdogs
Done.