[59.383825] Initializing watchdogs
[59.383874]   /dev/watchdog0
[59.398894] [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.403795] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.406261] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.409695] [001/156] (960s left) gem_mmap_wc (copy)
[59.503093] Subtest copy: SKIP (0.000s)
[59.566557] [002/156] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[59.679268] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[59.683414] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.791516] [003/156] (960s left) syncobj_eventfd (invalid-bad-flags)
[59.844400] Starting subtest: invalid-bad-flags
[59.846794] Subtest invalid-bad-flags: SUCCESS (0.000s)
[59.956987] [004/156] (960s left) dumb_buffer (map-invalid-size)
[60.015219] Starting subtest: map-invalid-size
[60.017811] Subtest map-invalid-size: SUCCESS (0.000s)
[60.125935] [005/156] (959s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[60.252511] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[60.256295] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[60.408371] [006/156] (959s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[60.503064] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[61.681122] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.169s)
[61.813445] [007/156] (958s left) gem_exec_whisper (basic-queues-priority)
[61.883333] Starting subtest: basic-queues-priority
[86.437686] Subtest basic-queues-priority: SUCCESS (24.451s)
[86.581678] [008/156] (933s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[86.688845] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[86.693372] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[86.795043] [009/156] (933s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[86.898437] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[86.903088] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[87.008435] [010/156] (933s left) gem_bad_reloc (negative-reloc-lut)
[87.070126] Subtest negative-reloc-lut: SKIP (0.000s)
[87.127408] [011/156] (933s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[87.234768] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[94.657560] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.424s)
[94.806080] [012/156] (925s left) syncobj_timeline (invalid-transfer-illegal-handle)
[94.857674] Starting subtest: invalid-transfer-illegal-handle
[94.859918] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[94.979623] [013/156] (925s left) i915_query (query-topology-garbage-items)
[95.036114] Starting subtest: query-topology-garbage-items
[95.038520] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[95.130717] [014/156] (925s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-pwrite)
[95.234346] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-pwrite
[95.238743] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[95.353709] [015/156] (925s left) kms_vblank (wait-forked-hang)
[95.450039] Starting subtest: wait-forked-hang
[95.451850] Starting dynamic subtest: pipe-A-eDP-1
[122.128080] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.674s)
[122.142229] Starting dynamic subtest: pipe-D-eDP-1
[137.050452] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.918s)
[137.052513] Subtest wait-forked-hang: SUCCESS (41.596s)
[137.911224] [016/156] (882s left) gem_userptr_blits (huge-split)
[137.975846] Starting subtest: huge-split
[137.980356] Subtest huge-split: SUCCESS (0.007s)
[138.110543] [017/156] (882s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[138.204140] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[138.208702] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[138.293936] [018/156] (882s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[138.392608] Starting subtest: plane-all-transition-nonblocking-fencing
[138.395071] Starting dynamic subtest: pipe-A-eDP-1
[140.885521] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[141.192216] Starting dynamic subtest: pipe-B-eDP-1
[144.449083] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.265s)
[144.751414] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.362s)
[145.584040] [019/156] (875s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[145.694869] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[145.699339] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[145.792089] [020/156] (874s left) kms_psr (fbc-psr2-cursor-render)
[145.891261] Starting subtest: fbc-psr2-cursor-render
[145.893430] Starting dynamic subtest: eDP-1
[145.897885] Dynamic subtest eDP-1: SKIP (0.000s)
[145.902314] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[145.962370] [021/156] (874s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[146.065145] Starting subtest: psr-rgb565-draw-mmap-cpu
[152.886512] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.827s)
[153.007107] [022/156] (867s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[153.102971] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[159.777153] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.678s)
[159.919155] [023/156] (860s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-onoff)
[160.027100] Starting subtest: hdr-1p-primscrn-spr-indfb-onoff
[160.031127] Subtest hdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[160.146074] [024/156] (860s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt)
[160.246799] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt
[160.251034] Subtest fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[160.342978] [025/156] (860s left) gem_ctx_persistence (userptr)
[160.397729] Starting subtest: userptr
[160.572873] Subtest userptr: SUCCESS (0.174s)
[160.652302] [026/156] (860s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[160.746487] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[160.772013] Starting dynamic subtest: pipe-A-plane-0
[161.049158] Dynamic subtest pipe-A-plane-0: SUCCESS (0.278s)
[161.058529] Starting dynamic subtest: pipe-A-plane-3
[161.271386] Dynamic subtest pipe-A-plane-3: SUCCESS (0.222s)
[162.399457] Starting dynamic subtest: pipe-B-plane-0
[162.680276] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[162.689065] Starting dynamic subtest: pipe-B-plane-3
[162.892075] Dynamic subtest pipe-B-plane-3: SUCCESS (0.211s)
[163.211564] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.465s)
[164.092722] [027/156] (856s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-blt)
[164.194903] Starting subtest: fbcpsrhdr-rgb101010-draw-blt
[164.198998] Subtest fbcpsrhdr-rgb101010-draw-blt: SKIP (0.000s)
[164.300343] [028/156] (856s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[164.427792] Starting subtest: short-flip-after-cursor-atomic-transitions
[165.402084] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.974s)
[166.224961] [029/156] (854s left) kms_color (legacy-gamma-reset)
[166.323834] Starting subtest: legacy-gamma-reset
[166.325702] Starting dynamic subtest: pipe-A-eDP-1
[166.708968] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.385s)
[166.720098] Starting dynamic subtest: pipe-B-eDP-1
[166.722382] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[166.731141] Starting dynamic subtest: pipe-C-eDP-1
[166.733563] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.014s)
[166.742194] Starting dynamic subtest: pipe-D-eDP-1
[166.747132] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[166.749509] Subtest legacy-gamma-reset: SUCCESS (0.424s)
[167.578168] [030/156] (853s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[167.679473] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[174.778129] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.106s)
[174.924347] [031/156] (846s left) gem_eio (throttle)
[175.145065] Starting subtest: throttle
[175.466164] Subtest throttle: SUCCESS (0.320s)
[175.584116] [032/156] (845s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[175.677172] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[175.681150] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[175.806734] [033/156] (845s left) kms_psr (pr-basic)
[175.909572] Starting subtest: pr-basic
[175.913342] Subtest pr-basic: SKIP (0.000s)
[175.984986] [034/156] (845s left) gem_exec_balancer (parallel-out-fence)
[176.254670] Starting subtest: parallel-out-fence
[177.397398] Subtest parallel-out-fence: SUCCESS (1.142s)
[177.490778] [035/156] (843s left) api_intel_bb (render)
[177.547800] Starting subtest: render
[177.550139] Starting dynamic subtest: render-none-512
[177.691028] Dynamic subtest render-none-512: SUCCESS (0.142s)
[177.703986] Starting dynamic subtest: render-none-1024
[178.068339] Dynamic subtest render-none-1024: SUCCESS (0.376s)
[178.081286] Starting dynamic subtest: render-x-512
[178.154325] Dynamic subtest render-x-512: SUCCESS (0.086s)
[178.164916] Starting dynamic subtest: render-x-1024
[178.528635] Dynamic subtest render-x-1024: SUCCESS (0.373s)
[178.540720] Starting dynamic subtest: render-y-512
[178.614619] Dynamic subtest render-y-512: SUCCESS (0.086s)
[178.626183] Starting dynamic subtest: render-y-1024
[179.216457] Dynamic subtest render-y-1024: SUCCESS (0.599s)
[179.219684] Subtest render: SUCCESS (1.668s)
[179.332565] [036/156] (841s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[179.446443] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[179.450645] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[179.546316] [037/156] (841s left) kms_async_flips (crc-atomic)
[179.646584] Starting subtest: crc-atomic
[180.028729] Starting dynamic subtest: pipe-A-eDP-1
[183.018323] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.966s)
[183.388705] Starting dynamic subtest: pipe-B-eDP-1
[186.339609] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.960s)
[186.726208] Starting dynamic subtest: pipe-C-eDP-1
[189.652187] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.936s)
[190.027576] Starting dynamic subtest: pipe-D-eDP-1
[192.901504] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.883s)
[192.903993] Subtest crc-atomic: SUCCESS (13.258s)
[194.064487] [038/156] (827s left) gem_close_race (basic-process)
[194.137046] Starting subtest: basic-process
[194.176586] Subtest basic-process: SUCCESS (0.040s)
[194.384390] [039/156] (827s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[194.443750] Starting subtest: single-wait-all-for-submit-available-signaled
[194.445676] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[194.557358] [040/156] (826s left) sw_sync (sync_multi_timeline_wait)
[194.592291] Starting subtest: sync_multi_timeline_wait
[194.594635] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[194.638135] [041/156] (826s left) kms_flip (flip-vs-expired-vblank-interruptible)
[194.729535] Starting subtest: flip-vs-expired-vblank-interruptible
[194.731778] Starting dynamic subtest: A-eDP1
[203.402578] Dynamic subtest A-eDP1: SUCCESS (8.638s)
[203.412129] Starting dynamic subtest: B-eDP1
[212.037135] Dynamic subtest B-eDP1: SUCCESS (8.629s)
[212.046805] Starting dynamic subtest: C-eDP1
[220.680507] Dynamic subtest C-eDP1: SUCCESS (8.630s)
[220.689888] Starting dynamic subtest: D-eDP1
[229.265076] Dynamic subtest D-eDP1: SUCCESS (8.627s)
[229.267544] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.537s)
[230.437285] [042/156] (791s left) gem_exec_schedule (preempt-hang)
[230.618110] Starting subtest: preempt-hang
[230.620671] Starting dynamic subtest: rcs0
[230.851461] Dynamic subtest rcs0: SUCCESS (0.232s)
[230.865314] Starting dynamic subtest: bcs0
[231.087456] Dynamic subtest bcs0: SUCCESS (0.236s)
[231.097622] Starting dynamic subtest: vcs0
[231.327197] Dynamic subtest vcs0: SUCCESS (0.237s)
[231.339725] Starting dynamic subtest: vcs1
[231.561069] Dynamic subtest vcs1: SUCCESS (0.232s)
[231.572165] Starting dynamic subtest: vecs0
[231.798187] Dynamic subtest vecs0: SUCCESS (0.234s)
[231.808572] Starting dynamic subtest: ccs0
[232.031987] Dynamic subtest ccs0: SUCCESS (0.232s)
[232.034202] Subtest preempt-hang: SUCCESS (1.415s)
[232.350284] [043/156] (789s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[232.537444] Starting subtest: cursor-rapid-movement-64x21
[232.587321] Subtest cursor-rapid-movement-64x21: SKIP (0.046s)
[232.835354] [044/156] (788s left) prime_mmap_kms (buffer-sharing)
[232.923436] Starting subtest: buffer-sharing
[233.367951] Subtest buffer-sharing: SUCCESS (0.444s)
[234.175365] [045/156] (787s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[234.238811] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[234.241228] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[234.339994] [046/156] (787s left) gem_exec_fence (syncobj-timeline-signal)
[234.914782] Starting subtest: syncobj-timeline-signal
[234.918232] Subtest syncobj-timeline-signal: SUCCESS (0.009s)
[236.377383] [FACT gem_exec_fence (syncobj-timeline-signal)] new: kernel.kmod_is_loaded.vgem: true
[236.381374] [047/156] (785s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[236.431392] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[236.435728] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[236.628481] [048/156] (785s left) kms_content_protection (atomic)
[236.787774] Starting subtest: atomic
[236.792327] Subtest atomic: SKIP (0.000s)
[236.914896] [049/156] (784s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[237.018882] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[237.023310] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[237.118449] [050/156] (784s left) gem_ctx_param (invalid-set-no-zeromap)
[237.178992] Starting subtest: invalid-set-no-zeromap
[237.181479] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[237.254511] [051/156] (784s left) perf_pmu (idle)
[237.295561] Starting subtest: idle
[237.297697] Starting dynamic subtest: rcs0
[237.848464] Dynamic subtest rcs0: SUCCESS (0.545s)
[237.860080] Starting dynamic subtest: bcs0
[238.362385] Dynamic subtest bcs0: SUCCESS (0.515s)
[238.372983] Starting dynamic subtest: vcs0
[238.878389] Dynamic subtest vcs0: SUCCESS (0.513s)
[238.888242] Starting dynamic subtest: vcs1
[239.398884] Dynamic subtest vcs1: SUCCESS (0.513s)
[239.415222] Starting dynamic subtest: vecs0
[239.912036] Dynamic subtest vecs0: SUCCESS (0.514s)
[239.925040] Starting dynamic subtest: ccs0
[240.438397] Dynamic subtest ccs0: SUCCESS (0.519s)
[240.441290] Subtest idle: SUCCESS (3.138s)
[240.527189] [052/156] (781s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[240.925650] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[241.784221] [053/156] (780s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[241.892784] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[241.897383] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[241.995244] [054/156] (780s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[242.102642] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[242.106909] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[242.186074] [055/156] (779s left) i915_hangman (engine-engine-hang)
[242.252414] Starting subtest: engine-engine-hang
[242.254669] Starting dynamic subtest: rcs0
[244.261290] Dynamic subtest rcs0: SUCCESS (2.008s)
[244.273610] Starting dynamic subtest: bcs0
[246.245501] Dynamic subtest bcs0: SUCCESS (1.982s)
[246.258540] Starting dynamic subtest: vcs0
[248.292432] Dynamic subtest vcs0: SUCCESS (2.044s)
[248.305695] Starting dynamic subtest: vcs1
[250.276737] Dynamic subtest vcs1: SUCCESS (1.981s)
[250.289866] Starting dynamic subtest: vecs0
[252.260869] Dynamic subtest vecs0: SUCCESS (1.981s)
[252.272755] Starting dynamic subtest: ccs0
[254.244827] Dynamic subtest ccs0: SUCCESS (1.979s)
[254.248050] Subtest engine-engine-hang: SUCCESS (11.993s)
[254.362238] [056/156] (767s left) gem_mmap_wc (write-read)
[254.414204] Subtest write-read: SKIP (0.000s)
[254.470142] [057/156] (767s left) gem_exec_reloc (basic-wc-read-noreloc)
[254.527501] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[254.595163] [058/156] (767s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[254.698206] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[254.702232] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[254.764737] [059/156] (767s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[254.867614] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[254.871632] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[254.967886] [060/156] (767s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-cpu)
[255.057792] Starting subtest: psrhdr-rgb101010-draw-mmap-cpu
[255.061962] Subtest psrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[255.179906] [061/156] (767s left) kms_plane_multiple (2x-tiling-y)
[255.278589] Starting subtest: 2x-tiling-y
[255.282130] Subtest 2x-tiling-y: SKIP (0.000s)
[255.348102] [062/156] (767s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[255.441423] Starting subtest: plane-upscale-20x20-with-rotation
[255.443403] Starting dynamic subtest: pipe-A
[255.666928] Dynamic subtest pipe-A: SUCCESS (0.227s)
[255.676502] Starting dynamic subtest: pipe-B
[256.984886] Dynamic subtest pipe-B: SUCCESS (1.317s)
[256.996090] Starting dynamic subtest: pipe-C
[258.301226] Dynamic subtest pipe-C: SUCCESS (1.316s)
[258.315681] Starting dynamic subtest: pipe-D
[259.642035] Dynamic subtest pipe-D: SUCCESS (1.340s)
[259.644229] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.203s)
[260.814731] [063/156] (761s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[260.914562] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[260.916904] Starting dynamic subtest: pipe-A-eDP-1
[260.940750] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[260.952901] Starting dynamic subtest: pipe-B-eDP-1
[260.974717] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[260.983629] Starting dynamic subtest: pipe-C-eDP-1
[261.005414] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[261.016249] Starting dynamic subtest: pipe-D-eDP-1
[261.037603] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[261.041948] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.007s)
[263.186246] [064/156] (759s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-msflip-blt)
[263.282722] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-msflip-blt
[263.287072] Subtest fbcpsrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[263.379794] [065/156] (759s left) kms_pm_rpm (fences-dpms)
[265.412308] Starting subtest: fences-dpms
[265.417153] Subtest fences-dpms: SKIP (0.000s)
[265.812215] [066/156] (756s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-plflip-blt)
[265.905626] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-plflip-blt
[265.910112] Subtest fbcpsrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[266.010924] [067/156] (756s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[266.099801] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[266.102124] Starting dynamic subtest: pipe-A
[267.205151] Dynamic subtest pipe-A: SUCCESS (1.107s)
[267.214790] Starting dynamic subtest: pipe-B
[269.444293] Dynamic subtest pipe-B: SUCCESS (2.238s)
[269.453991] Starting dynamic subtest: pipe-C
[270.606784] Dynamic subtest pipe-C: SUCCESS (1.161s)
[270.616513] Starting dynamic subtest: pipe-D
[271.844501] Dynamic subtest pipe-D: SUCCESS (1.237s)
[271.846934] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.746s)
[273.031642] [068/156] (749s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[273.170568] Starting subtest: cursorB-vs-flipB-varying-size
[273.175067] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[273.315345] [069/156] (749s left) gem_mmap_gtt (big-copy)
[273.371265] Subtest big-copy: SKIP (0.000s)
[273.439196] [070/156] (749s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[273.539597] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[273.543800] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[273.641105] [071/156] (749s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-msflip-blt)
[273.746101] Starting subtest: hdr-1p-primscrn-indfb-msflip-blt
[273.750607] Subtest hdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[273.846027] [072/156] (748s left) kms_invalid_mode (zero-clock)
[273.943494] Starting subtest: zero-clock
[273.945755] Starting dynamic subtest: pipe-A-eDP-1
[274.230888] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[274.241092] Starting dynamic subtest: pipe-B-eDP-1
[274.243078] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[274.252925] Starting dynamic subtest: pipe-C-eDP-1
[274.255297] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[274.264886] Starting dynamic subtest: pipe-D-eDP-1
[274.267380] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[274.269777] Subtest zero-clock: SUCCESS (0.294s)
[275.054598] [073/156] (747s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[275.164796] Starting subtest: 4-tiled-8bpp-rotate-0
[276.422916] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.258s)
[276.558360] [074/156] (746s left) gem_sync (basic-many-each)
[276.620883] Starting subtest: basic-many-each
[278.887480] Subtest basic-many-each: SUCCESS (2.261s)
[279.068411] [075/156] (743s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[279.192357] Starting subtest: cursor-vs-flip-varying-size
[310.501608] Subtest cursor-vs-flip-varying-size: SUCCESS (31.306s)
[311.388893] [076/156] (711s left) kms_pm_rpm (package-g7)
[313.431951] Starting subtest: package-g7
[313.437203] Subtest package-g7: SKIP (0.000s)
[313.824080] [077/156] (709s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[313.909429] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[313.911912] Starting dynamic subtest: pipe-A-eDP-1
[313.938750] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[313.949902] Starting dynamic subtest: pipe-B-eDP-1
[313.975692] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[313.984933] Starting dynamic subtest: pipe-C-eDP-1
[314.009900] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[314.019378] Starting dynamic subtest: pipe-D-eDP-1
[314.043846] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[314.047990] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[316.183464] [078/156] (706s left) gem_ctx_engines (execute-oneforall)
[316.252297] Starting subtest: execute-oneforall
[316.929677] Subtest execute-oneforall: SUCCESS (0.675s)
[318.389695] [079/156] (704s left) kms_psr (psr-sprite-mmap-gtt)
[318.491587] Starting subtest: psr-sprite-mmap-gtt
[318.493825] Starting dynamic subtest: eDP-1
[319.970959] Dynamic subtest eDP-1: SKIP (1.477s)
[319.976088] Subtest psr-sprite-mmap-gtt: SKIP (1.479s)
[320.150680] [080/156] (702s left) gem_exec_balancer (fairslice)
[320.215673] Starting subtest: fairslice
[323.156144] Subtest fairslice: SUCCESS (2.939s)
[323.480866] [081/156] (699s left) sysfs_timeslice_duration (invalid)
[323.548747] Starting subtest: invalid
[323.551061] Starting dynamic subtest: vecs0
[323.553378] Dynamic subtest vecs0: SUCCESS (0.000s)
[323.563179] Starting dynamic subtest: ccs0
[323.565127] Dynamic subtest ccs0: SUCCESS (0.000s)
[323.576420] Starting dynamic subtest: vcs0
[323.578285] Dynamic subtest vcs0: SUCCESS (0.000s)
[323.587715] Starting dynamic subtest: bcs0
[323.589589] Dynamic subtest bcs0: SUCCESS (0.000s)
[323.599959] Starting dynamic subtest: rcs0
[323.602065] Dynamic subtest rcs0: SUCCESS (0.000s)
[323.612713] Starting dynamic subtest: vcs1
[323.614565] Dynamic subtest vcs1: SUCCESS (0.000s)
[323.616436] Subtest invalid: SUCCESS (0.006s)
[323.665500] [082/156] (699s left) perf_pmu (busy-hang)
[323.702345] Starting subtest: busy-hang
[323.704633] Starting dynamic subtest: rcs0
[324.967418] Dynamic subtest rcs0: SUCCESS (1.263s)
[324.978820] Starting dynamic subtest: bcs0
[326.194261] Dynamic subtest bcs0: SUCCESS (1.229s)
[326.206169] Starting dynamic subtest: vcs0
[328.843823] Dynamic subtest vcs0: SUCCESS (2.644s)
[328.857224] Starting dynamic subtest: vcs1
[330.825779] Dynamic subtest vcs1: SUCCESS (1.980s)
[330.838120] Starting dynamic subtest: vecs0
[332.814516] Dynamic subtest vecs0: SUCCESS (1.988s)
[332.825333] Starting dynamic subtest: ccs0
[334.064711] Dynamic subtest ccs0: SUCCESS (1.250s)
[334.067410] Subtest busy-hang: SUCCESS (10.365s)
[334.165624] [083/156] (689s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[334.281099] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[341.421440] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.146s)
[341.535317] [084/156] (681s left) gem_exec_flush (basic-wb-rw-before-default)
[341.588695] Starting subtest: basic-wb-rw-before-default
[347.074387] Subtest basic-wb-rw-before-default: SUCCESS (5.459s)
[347.242561] [085/156] (676s left) kms_busy (extended-pageflip-hang-oldfb)
[347.343174] Starting subtest: extended-pageflip-hang-oldfb
[347.345436] Starting dynamic subtest: pipe-A
[347.756999] Dynamic subtest pipe-A: SUCCESS (0.412s)
[347.767300] Starting dynamic subtest: pipe-D
[348.863300] Dynamic subtest pipe-D: SUCCESS (1.106s)
[348.865935] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.523s)
[349.709778] [086/156] (673s left) kms_vblank (query-busy)
[349.811254] Starting subtest: query-busy
[349.813636] Starting dynamic subtest: pipe-A-eDP-1
[351.689884] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.878s)
[351.707198] Starting dynamic subtest: pipe-D-eDP-1
[354.279429] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.586s)
[354.281719] Subtest query-busy: SUCCESS (4.468s)
[355.082157] [087/156] (668s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[355.185204] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[355.189126] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[355.283578] [088/156] (668s left) api_intel_allocator (reopen)
[355.349536] Starting subtest: reopen
[355.351832] Subtest reopen: SUCCESS (0.003s)
[355.468561] [089/156] (668s left) syncobj_basic (illegal-fd-to-handle)
[355.514647] Starting subtest: illegal-fd-to-handle
[355.517085] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[355.635131] [090/156] (667s left) gem_pread (display)
[355.695701] Subtest display: SKIP (0.000s)
[355.759207] [091/156] (667s left) gem_ctx_sseu (invalid-args)
[355.817586] Subtest invalid-args: SKIP (0.000s)
[355.888402] [092/156] (667s left) gem_exec_params (invalid-batch-start-offset)
[355.944292] Starting subtest: invalid-batch-start-offset
[355.949357] Subtest invalid-batch-start-offset: SUCCESS (0.005s)
[356.062803] [093/156] (667s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[356.161035] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[357.318579] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.157s)
[357.453226] [094/156] (666s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[357.555482] Starting subtest: primary-yf-tiled-reflect-x-270
[357.559059] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[357.630606] [095/156] (666s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[357.691948] Starting subtest: etime-multi-wait-available-unsubmitted
[357.792488] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.101s)
[357.873568] [096/156] (665s left) prime_mmap (test_forked_cpu_write)
[357.929424] Starting subtest: test_forked_cpu_write
[357.931350] Starting dynamic subtest: test_forked_cpu_write-smem
[357.936746] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.007s)
[357.938845] Subtest test_forked_cpu_write: SUCCESS (0.008s)
[358.073813] [097/156] (665s left) gem_madvise (dontneed-after-mmap)
[358.108033] Starting subtest: dontneed-after-mmap
[358.134399] Subtest dontneed-after-mmap: SUCCESS (0.028s)
[358.246746] [098/156] (665s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[358.347472] Starting subtest: pr-cursor-plane-update-sf
[358.350748] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[358.419193] [099/156] (665s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[358.479734] Starting subtest: invalid-multi-wait-all-unsubmitted
[358.481602] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[358.591254] [100/156] (665s left) gem_exec_schedule (semaphore-noskip)
[358.659430] Starting subtest: semaphore-noskip
[359.092914] Subtest semaphore-noskip: SUCCESS (0.436s)
[359.442263] [101/156] (664s left) kms_big_fb (linear-16bpp-rotate-270)
[359.545532] Starting subtest: linear-16bpp-rotate-270
[359.565351] Subtest linear-16bpp-rotate-270: SKIP (0.019s)
[359.682402] [102/156] (664s left) kms_cursor_crc (cursor-offscreen-128x42)
[359.920252] Starting subtest: cursor-offscreen-128x42
[359.972283] Subtest cursor-offscreen-128x42: SKIP (0.047s)
[360.195158] [103/156] (663s left) kms_cursor_crc (cursor-suspend)
[360.323613] Starting subtest: cursor-suspend
[360.325984] Starting dynamic subtest: pipe-A-eDP-1
[392.861191] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.117s)
[392.879290] Starting dynamic subtest: pipe-D-eDP-1
[426.899388] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.741s)
[426.902136] Subtest cursor-suspend: SUCCESS (7.861s)
[427.730486] [104/156] (596s left) syncobj_wait (reset-multiple-signaled)
[427.794987] Starting subtest: reset-multiple-signaled
[427.797806] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[427.887998] [105/156] (596s left) gem_mmap_offset (blt-coherency)
[427.949489] Starting subtest: blt-coherency
[427.957537] Subtest blt-coherency: SUCCESS (0.010s)
[428.078446] [106/156] (596s left) gem_mmap_gtt (cpuset-medium-copy)
[428.133213] Subtest cpuset-medium-copy: SKIP (0.000s)
[428.197582] [107/156] (596s left) kms_psr (fbc-pr-primary-page-flip)
[428.284325] Starting subtest: fbc-pr-primary-page-flip
[428.290051] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[428.358832] [108/156] (595s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-move)
[428.461192] Starting subtest: psrhdr-1p-primscrn-spr-indfb-move
[428.466162] Subtest psrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[428.570709] [109/156] (595s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[428.667757] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[428.672128] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[428.767694] [110/156] (595s left) gem_basic (create-fd-close)
[428.823304] Starting subtest: create-fd-close
[428.829130] Subtest create-fd-close: SUCCESS (0.000s)
[428.946263] [111/156] (595s left) vgem_basic (unload)
[428.979171] Starting subtest: unload
[458.020071] Subtest unload: SUCCESS (28.929s)
[458.119080] [112/156] (566s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[458.224675] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[458.226999] Starting dynamic subtest: pipe-A-eDP-1
[458.250804] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[458.262333] Starting dynamic subtest: pipe-B-eDP-1
[458.288086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[458.298276] Starting dynamic subtest: pipe-C-eDP-1
[458.323089] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[458.333101] Starting dynamic subtest: pipe-D-eDP-1
[458.358795] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[458.363342] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[460.519927] [113/156] (564s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[460.628848] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[460.633532] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[460.721980] [114/156] (563s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[460.775056] Starting subtest: mixed-tiled-to-y-tiled-ccs
[460.780225] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[460.962313] [115/156] (563s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[461.067821] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[461.073087] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[461.162728] [116/156] (563s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[461.274175] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[461.279074] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[461.370114] [117/156] (563s left) gem_userptr_blits (dmabuf-unsync)
[461.429363] Subtest dmabuf-unsync: SKIP (0.000s)
[461.548514] [118/156] (563s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[461.662198] Starting subtest: short-busy-flip-before-cursor-toggle
[461.667363] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[461.810160] [119/156] (562s left) i915_pm_rpm (gem-mmap-type)
[464.147174] Starting subtest: gem-mmap-type
[464.149764] Starting dynamic subtest: wb-smem0
[467.365214] Dynamic subtest wb-smem0: SUCCESS (3.219s)
[467.376320] Starting dynamic subtest: wc-smem0
[470.572080] Dynamic subtest wc-smem0: SUCCESS (3.206s)
[470.584359] Starting dynamic subtest: uc-smem0
[473.455697] Dynamic subtest uc-smem0: SUCCESS (2.882s)
[473.458133] Subtest gem-mmap-type: SUCCESS (9.313s)
[473.576317] [120/156] (551s left) kms_psr (psr2-primary-mmap-gtt)
[473.674127] Starting subtest: psr2-primary-mmap-gtt
[473.676888] Starting dynamic subtest: eDP-1
[475.116766] Dynamic subtest eDP-1: SKIP (1.440s)
[475.122961] Subtest psr2-primary-mmap-gtt: SKIP (1.442s)
[475.250060] [121/156] (549s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[475.360787] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[475.365948] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[475.466588] [122/156] (549s left) gem_busy (close-race)
[475.528524] Starting subtest: close-race
[500.963314] Subtest close-race: SUCCESS (25.426s)
[501.344702] [123/156] (523s left) gem_softpin (allocator-fork)
[501.462767] Starting subtest: allocator-fork
[503.751315] Subtest allocator-fork: SUCCESS (2.234s)
[503.829857] [124/156] (521s left) kms_invalid_mode (bad-hsync-start)
[503.931834] Starting subtest: bad-hsync-start
[503.934266] Starting dynamic subtest: pipe-A-eDP-1
[504.219324] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[504.231338] Starting dynamic subtest: pipe-B-eDP-1
[504.233903] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[504.245320] Starting dynamic subtest: pipe-C-eDP-1
[504.247870] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[504.258899] Starting dynamic subtest: pipe-D-eDP-1
[504.261480] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[504.264073] Subtest bad-hsync-start: SUCCESS (0.297s)
[505.090320] [125/156] (519s left) kms_pipe_stress (stress-xrgb8888-untiled)
[505.188835] Starting subtest: stress-xrgb8888-untiled
[511.314680] Subtest stress-xrgb8888-untiled: SUCCESS (6.126s)
[511.377929] [126/156] (513s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[511.483328] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[511.487448] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[511.578889] [127/156] (513s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[511.674085] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[511.676692] Starting dynamic subtest: pipe-A-eDP-1
[511.746639] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.074s)
[512.057586] Starting dynamic subtest: pipe-B-eDP-1
[512.890093] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.845s)
[513.190150] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.518s)
[514.056113] [128/156] (511s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[514.149675] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[514.154886] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[514.243094] [129/156] (510s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[514.341832] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[514.347085] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[514.421435] [130/156] (510s left) i915_drm_fdinfo (busy-check-all)
[514.463520] Starting subtest: busy-check-all
[514.466070] Starting dynamic subtest: rcs0
[514.470434] Dynamic subtest rcs0: SKIP (0.004s)
[514.481530] Starting dynamic subtest: bcs0
[514.486054] Dynamic subtest bcs0: SKIP (0.000s)
[514.497381] Starting dynamic subtest: vcs0
[514.502255] Dynamic subtest vcs0: SKIP (0.000s)
[514.513264] Starting dynamic subtest: vcs1
[514.518080] Dynamic subtest vcs1: SKIP (0.000s)
[514.528894] Starting dynamic subtest: vecs0
[514.533541] Dynamic subtest vecs0: SKIP (0.000s)
[514.543924] Starting dynamic subtest: ccs0
[514.548853] Dynamic subtest ccs0: SKIP (0.000s)
[514.553985] Subtest busy-check-all: SKIP (0.010s)
[514.595044] [131/156] (510s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[514.728551] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[514.739572] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[514.815595] [132/156] (510s left) kms_addfb_basic (unused-pitches)
[514.862285] Starting subtest: unused-pitches
[514.864317] Subtest unused-pitches: SUCCESS (0.000s)
[514.998999] [133/156] (510s left) gem_pread (self)
[515.049332] Subtest self: SKIP (0.000s)
[515.112709] [134/156] (510s left) kms_psr (pr-primary-page-flip)
[515.199020] Starting subtest: pr-primary-page-flip
[515.203677] Subtest pr-primary-page-flip: SKIP (0.000s)
[515.273332] [135/156] (510s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[515.394919] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[524.902089] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.504s)
[525.788972] [136/156] (499s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-pgflip-blt)
[525.894203] Starting subtest: psrhdr-1p-primscrn-indfb-pgflip-blt
[525.899365] Subtest psrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[525.994628] [137/156] (499s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[526.100549] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[526.104703] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[526.183580] [138/156] (499s left) kms_chamelium_frames (dp-frame-dump)
[526.565506] Subtest dp-frame-dump: SKIP (0.000s)
[527.402694] [139/156] (498s left) gen7_exec_parse (cmd-crossing-page)
[527.463023] Subtest cmd-crossing-page: SKIP (0.000s)
[527.523882] [140/156] (498s left) gem_exec_whisper (basic-fds)
[527.585864] Starting subtest: basic-fds
[534.916369] Subtest basic-fds: SUCCESS (7.328s)
[535.102509] [141/156] (490s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-pwrite)
[535.214981] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-pwrite
[535.219598] Subtest hdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[535.312212] [142/156] (490s left) kms_async_flips (test-time-stamp-atomic)
[535.398208] Starting subtest: test-time-stamp-atomic
[535.768106] Starting dynamic subtest: pipe-A-eDP-1
[536.619348] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.849s)
[537.007824] Starting dynamic subtest: pipe-B-eDP-1
[537.775366] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.776s)
[538.160352] Starting dynamic subtest: pipe-C-eDP-1
[539.005102] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.853s)
[539.370312] Starting dynamic subtest: pipe-D-eDP-1
[540.144113] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.778s)
[540.147317] Subtest test-time-stamp-atomic: SUCCESS (4.747s)
[541.307268] [143/156] (484s left) kms_draw_crc (draw-method-mmap-cpu)
[541.399210] Starting subtest: draw-method-mmap-cpu
[541.401980] Starting dynamic subtest: xrgb8888-untiled
[541.667078] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[541.679618] Starting dynamic subtest: rgb565-untiled
[541.897045] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[541.911615] Starting dynamic subtest: xrgb2101010-untiled
[542.119159] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[542.130616] Starting dynamic subtest: xbgr16161616f-untiled
[542.351829] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[542.362554] Starting dynamic subtest: xrgb8888-xtiled
[542.496746] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.144s)
[542.507965] Starting dynamic subtest: rgb565-xtiled
[542.629923] Dynamic subtest rgb565-xtiled: SUCCESS (0.131s)
[542.640850] Starting dynamic subtest: xrgb2101010-xtiled
[542.752081] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[542.763352] Starting dynamic subtest: xbgr16161616f-xtiled
[542.885386] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[542.897853] Starting dynamic subtest: xrgb8888-4tiled
[543.030145] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[543.041733] Starting dynamic subtest: rgb565-4tiled
[543.174755] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[543.186943] Starting dynamic subtest: xrgb2101010-4tiled
[543.307712] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.132s)
[543.318957] Starting dynamic subtest: xbgr16161616f-4tiled
[543.463154] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.155s)
[543.465623] Subtest draw-method-mmap-cpu: SUCCESS (2.067s)
[543.557653] [144/156] (482s left) kms_flip (2x-flip-vs-dpms-on-nop)
[543.650125] Starting subtest: 2x-flip-vs-dpms-on-nop
[543.659035] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.011s)
[543.767590] [145/156] (482s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-render)
[543.863832] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-render
[543.867937] Subtest hdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[543.962805] [146/156] (482s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[544.056630] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[544.061920] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[545.243036] [147/156] (480s left) kms_lease (simple-lease)
[545.340060] Starting subtest: simple-lease
[545.342768] Starting dynamic subtest: pipe-A-eDP-1
[545.862472] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.523s)
[545.873946] Starting dynamic subtest: pipe-B-eDP-1
[547.294069] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.430s)
[547.305472] Starting dynamic subtest: pipe-C-eDP-1
[548.798843] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.503s)
[548.810356] Starting dynamic subtest: pipe-D-eDP-1
[550.208618] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.407s)
[550.211311] Subtest simple-lease: SUCCESS (4.870s)
[551.012662] [148/156] (475s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-blt)
[551.116788] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-blt
[551.121963] Subtest fbchdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[551.195691] [149/156] (474s left) gem_basic (create-close)
[551.251902] Starting subtest: create-close
[551.257113] Subtest create-close: SUCCESS (0.000s)
[551.362808] [150/156] (474s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[551.460152] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[551.465184] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[551.559162] [151/156] (474s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[551.656092] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[551.658195] Starting dynamic subtest: pipe-A-eDP-1
[552.088817] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.434s)
[552.105103] Starting dynamic subtest: pipe-B-eDP-1
[552.123218] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[552.133759] Starting dynamic subtest: pipe-C-eDP-1
[552.152576] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[552.163252] Starting dynamic subtest: pipe-D-eDP-1
[552.184019] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[552.186267] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.449s)
[554.389779] [152/156] (471s left) perf (create-destroy-userspace-config)
[554.535131] Starting subtest: create-destroy-userspace-config
[554.557155] Subtest create-destroy-userspace-config: SUCCESS (0.020s)
[554.607945] [153/156] (471s left) gem_userptr_blits (sync-overlap)
[554.681337] Starting subtest: sync-overlap
[554.684080] Subtest sync-overlap: SUCCESS (0.000s)
[554.797184] [154/156] (471s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[554.890970] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[559.042052] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.158s)
[560.191730] [155/156] (466s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[560.280163] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[560.285324] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[560.353099] [156/156] (466s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-pwrite)
[560.444932] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-pwrite
[560.449513] Subtest hdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[560.534475] Closing watchdogs
results: parsing output: 0/ for test: gem_mmap_wc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: syncobj_eventfd
results: parsing output: 3/ for test: dumb_buffer
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: gem_exec_whisper
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_bad_reloc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: i915_query
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_vblank
results: parsing output: 15/ for test: gem_userptr_blits
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: kms_atomic_transition
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_ctx_persistence
results: parsing output: 25/ for test: kms_plane
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: kms_color
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_eio
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_psr
results: parsing output: 33/ for test: gem_exec_balancer
results: parsing output: 34/ for test: api_intel_bb
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_async_flips
results: parsing output: 37/ for test: gem_close_race
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: sw_sync
results: parsing output: 40/ for test: kms_flip
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: kms_cursor_crc
results: parsing output: 43/ for test: prime_mmap_kms
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_fence
results: parsing output: 46/ for test: gem_render_copy
results: parsing output: 47/ for test: kms_content_protection
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_ctx_param
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_chamelium_hpd
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: i915_hangman
results: parsing output: 55/ for test: gem_mmap_wc
results: parsing output: 56/ for test: gem_exec_reloc
results: parsing output: 57/ for test: kms_psr2_sf
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_plane_multiple
results: parsing output: 61/ for test: kms_plane_scaling
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_pm_rpm
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_plane_scaling
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_invalid_mode
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: gem_sync
results: parsing output: 74/ for test: kms_cursor_legacy
results: parsing output: 75/ for test: kms_pm_rpm
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: gem_ctx_engines
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: gem_exec_balancer
results: parsing output: 80/ for test: sysfs_timeslice_duration
results: parsing output: 81/ for test: perf_pmu
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_exec_flush
results: parsing output: 84/ for test: kms_busy
results: parsing output: 85/ for test: kms_vblank
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: api_intel_allocator
results: parsing output: 88/ for test: syncobj_basic
results: parsing output: 89/ for test: gem_pread
results: parsing output: 90/ for test: gem_ctx_sseu
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: kms_rotation_crc
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: prime_mmap
results: parsing output: 96/ for test: gem_madvise
results: parsing output: 97/ for test: kms_psr2_sf
results: parsing output: 98/ for test: syncobj_timeline
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: syncobj_wait
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: gem_mmap_gtt
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_basic
results: parsing output: 110/ for test: vgem_basic
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_render_copy
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_userptr_blits
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: i915_pm_rpm
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_busy
results: parsing output: 122/ for test: gem_softpin
results: parsing output: 123/ for test: kms_invalid_mode
results: parsing output: 124/ for test: kms_pipe_stress
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_atomic_transition
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: i915_drm_fdinfo
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: kms_addfb_basic
results: parsing output: 132/ for test: gem_pread
results: parsing output: 133/ for test: kms_psr
results: parsing output: 134/ for test: kms_cursor_legacy
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_chamelium_frames
results: parsing output: 138/ for test: gen7_exec_parse
results: parsing output: 139/ for test: gem_exec_whisper
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_async_flips
results: parsing output: 142/ for test: kms_draw_crc
results: parsing output: 143/ for test: kms_flip
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_ccs
results: parsing output: 146/ for test: kms_lease
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: gem_basic
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_ccs
results: parsing output: 151/ for test: perf
results: parsing output: 152/ for test: gem_userptr_blits
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_flip_scaled_crc
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.