[62.790353] Initializing watchdogs
[62.790400]   /dev/watchdog0
[62.804738] [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]
[62.809497] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.812063] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.815521] [001/133] (960s left) sw_sync (timeline_closed)
[62.874713] Starting subtest: timeline_closed
[62.877251] Subtest timeline_closed: SUCCESS (0.000s)
[62.934976] [002/133] (960s left) gem_busy (close-race)
[63.004568] Starting subtest: close-race
[86.897818] Subtest close-race: SUCCESS (23.852s)
[87.266451] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[87.271370] [003/133] (936s left) i915_query (query-topology-garbage-items)
[87.379199] Starting subtest: query-topology-garbage-items
[87.381589] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[87.493847] [004/133] (935s left) gem_userptr_blits (dmabuf-unsync)
[87.552629] Subtest dmabuf-unsync: SKIP (0.000s)
[87.686227] [005/133] (935s left) perf (gen8-unprivileged-single-ctx-counters)
[87.852286] Starting subtest: gen8-unprivileged-single-ctx-counters
[87.856646] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[87.899522] [006/133] (935s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[88.005574] Starting subtest: flip-vs-dpms-off-vs-modeset
[88.007818] Starting dynamic subtest: A-eDP1
[91.619587] Dynamic subtest A-eDP1: SUCCESS (3.611s)
[91.630525] Starting dynamic subtest: B-eDP1
[94.833923] Dynamic subtest B-eDP1: SUCCESS (3.215s)
[94.844744] Starting dynamic subtest: C-eDP1
[98.023697] Dynamic subtest C-eDP1: SUCCESS (3.187s)
[98.031625] Starting dynamic subtest: D-eDP1
[101.118083] Dynamic subtest D-eDP1: SUCCESS (3.093s)
[101.120526] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.111s)
[101.930947] [007/133] (921s left) kms_feature_discovery (chamelium)
[102.033759] Starting subtest: chamelium
[102.037778] Subtest chamelium: SKIP (0.000s)
[102.106312] [008/133] (921s left) kms_atomic_transition (plane-all-modeset-transition)
[102.210205] Starting subtest: plane-all-modeset-transition
[102.214629] Subtest plane-all-modeset-transition: SKIP (0.000s)
[102.298051] [009/133] (921s left) gem_tiled_partial_pwrite_pread (reads)
[102.356209] Subtest reads: SKIP (0.000s)
[102.431574] [010/133] (921s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[102.535481] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[102.539611] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[102.604567] [011/133] (921s left) gem_mmap_offset (oob-read)
[102.655214] Starting subtest: oob-read
[102.657487] Subtest oob-read: SUCCESS (0.000s)
[102.749778] [012/133] (921s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[102.848975] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[102.851120] Starting dynamic subtest: pipe-A-eDP-1
[102.878558] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[102.886534] Starting dynamic subtest: pipe-B-eDP-1
[102.906068] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[102.913403] Starting dynamic subtest: pipe-C-eDP-1
[102.932816] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[102.940090] Starting dynamic subtest: pipe-D-eDP-1
[102.960389] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[102.964198] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[105.087336] [013/133] (918s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[105.148988] Starting subtest: multi-wait-available-submitted-signaled
[105.151337] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.001s)
[105.250680] [014/133] (918s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[105.347687] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[105.351690] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[105.416819] [015/133] (918s left) gem_mmap_wc (read)
[105.472306] Subtest read: SKIP (0.000s)
[105.535649] [016/133] (918s left) kms_big_fb (linear-16bpp-rotate-180)
[105.637947] Starting subtest: linear-16bpp-rotate-180
[107.060505] Subtest linear-16bpp-rotate-180: SUCCESS (1.424s)
[107.171100] [017/133] (916s left) kms_psr (fbc-psr2-sprite-render)
[107.259947] Starting subtest: fbc-psr2-sprite-render
[107.261540] Starting dynamic subtest: eDP-1
[107.264756] Dynamic subtest eDP-1: SKIP (0.000s)
[107.268130] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[107.366793] [018/133] (916s left) gem_eio (in-flight-external)
[107.593055] Starting subtest: in-flight-external
[108.950598] Subtest in-flight-external: SUCCESS (1.356s)
[109.084922] [019/133] (914s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[109.373585] Starting subtest: cursor-rapid-movement-256x256
[109.375770] Starting dynamic subtest: pipe-A-eDP-1
[109.940737] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[109.968122] Starting dynamic subtest: pipe-D-eDP-1
[111.116765] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.155s)
[111.119096] Subtest cursor-rapid-movement-256x256: SUCCESS (1.743s)
[111.982824] [020/133] (912s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[112.090239] Starting subtest: extended-modeset-hang-oldfb-with-reset
[112.092689] Starting dynamic subtest: pipe-A
[123.867308] Dynamic subtest pipe-A: SUCCESS (11.774s)
[123.886293] Starting dynamic subtest: pipe-D
[136.128892] Dynamic subtest pipe-D: SUCCESS (12.257s)
[136.131223] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.036s)
[136.932657] [021/133] (887s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[137.029199] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[137.031579] Starting dynamic subtest: pipe-A-eDP-1
[137.895981] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.860s)
[137.906090] Starting dynamic subtest: pipe-B-eDP-1
[139.538658] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.641s)
[139.547853] Starting dynamic subtest: pipe-C-eDP-1
[141.116044] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.574s)
[141.126182] Starting dynamic subtest: pipe-D-eDP-1
[142.605859] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.489s)
[142.607831] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.569s)
[143.749666] [022/133] (880s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[143.848872] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[143.853284] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[143.958982] [023/133] (880s left) kms_big_fb (yf-tiled-addfb)
[144.066079] Starting subtest: yf-tiled-addfb
[144.070238] Subtest yf-tiled-addfb: SKIP (0.000s)
[144.160291] [024/133] (880s left) gem_close_race (multigpu-basic-threads)
[144.230403] Starting subtest: multigpu-basic-threads
[144.234885] Subtest multigpu-basic-threads: SKIP (0.000s)
[144.447338] [025/133] (879s left) api_intel_allocator (two-level-inception-interruptible)
[144.500232] Starting subtest: two-level-inception-interruptible
[149.957033] Subtest two-level-inception-interruptible: SUCCESS (5.404s)
[150.024318] [026/133] (874s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[150.122718] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[157.749376] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.629s)
[157.895536] [027/133] (866s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[157.986470] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[157.988758] Starting dynamic subtest: pipe-A-eDP-1
[158.011496] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[158.022241] Starting dynamic subtest: pipe-B-eDP-1
[158.043605] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[158.052448] Starting dynamic subtest: pipe-C-eDP-1
[158.073733] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[158.083119] Starting dynamic subtest: pipe-D-eDP-1
[158.105294] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[158.109435] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[160.260849] [028/133] (864s left) gem_mmap_wc (copy)
[160.333902] Subtest copy: SKIP (0.000s)
[160.432290] [029/133] (864s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[160.550803] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[160.555700] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[160.711603] [030/133] (863s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[160.799085] Starting subtest: x-tiled-16bpp-rotate-90
[160.818511] Subtest x-tiled-16bpp-rotate-90: SKIP (0.019s)
[160.932433] [031/133] (863s left) kms_cursor_crc (cursor-sliding-256x85)
[161.200305] Starting subtest: cursor-sliding-256x85
[161.258462] Subtest cursor-sliding-256x85: SKIP (0.054s)
[161.429071] [032/133] (863s left) gem_exec_fence (syncobj-invalid-flags)
[161.903864] Starting subtest: syncobj-invalid-flags
[161.906749] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[162.983478] [033/133] (861s left) kms_flip (dpms-vs-vblank-race-interruptible)
[163.090348] Starting subtest: dpms-vs-vblank-race-interruptible
[163.092710] Starting dynamic subtest: A-eDP1
[166.973735] Dynamic subtest A-eDP1: SUCCESS (3.879s)
[166.982665] Starting dynamic subtest: B-eDP1
[170.480976] Dynamic subtest B-eDP1: SUCCESS (3.506s)
[170.490387] Starting dynamic subtest: C-eDP1
[175.114695] Dynamic subtest C-eDP1: SUCCESS (4.632s)
[175.123785] Starting dynamic subtest: D-eDP1
[179.747203] Dynamic subtest D-eDP1: SUCCESS (4.631s)
[179.753243] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (16.660s)
[180.623696] [034/133] (844s left) kms_addfb_basic (basic-y-tiled-legacy)
[180.685684] Starting subtest: basic-y-tiled-legacy
[180.690473] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[180.810506] [035/133] (844s left) gem_lmem_swapping (heavy-verify-multi)
[180.870427] Subtest heavy-verify-multi: SKIP (0.000s)
[180.922327] [036/133] (843s left) api_intel_bb (misplaced-blitter)
[180.973360] Starting subtest: misplaced-blitter
[180.978066] Subtest misplaced-blitter: SUCCESS (0.007s)
[181.109570] [037/133] (843s left) kms_vblank (query-idle-hang)
[181.196183] Starting subtest: query-idle-hang
[181.198455] Starting dynamic subtest: pipe-A-eDP-1
[207.636764] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.439s)
[207.651673] Starting dynamic subtest: pipe-D-eDP-1
[232.676627] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.037s)
[232.679072] Subtest query-idle-hang: SUCCESS (51.480s)
[233.482431] [038/133] (791s left) kms_fbcon_fbt (psr-suspend)
[233.549585] Starting subtest: psr-suspend
[282.366241] Subtest psr-suspend: SUCCESS (19.512s)
[282.414042] [039/133] (742s left) kms_cursor_crc (cursor-offscreen-128x42)
[282.655907] Starting subtest: cursor-offscreen-128x42
[282.703866] Subtest cursor-offscreen-128x42: SKIP (0.042s)
[282.933861] [040/133] (742s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[283.025909] Starting subtest: bad-pixel-format-y-tiled-ccs
[283.028731] Starting dynamic subtest: pipe-A-eDP-1
[283.055070] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[283.065920] Starting dynamic subtest: pipe-B-eDP-1
[283.092805] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[283.102687] Starting dynamic subtest: pipe-C-eDP-1
[283.128862] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[283.138141] Starting dynamic subtest: pipe-D-eDP-1
[283.166207] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[283.171685] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[285.340739] [041/133] (739s left) kms_atomic_interruptible (universal-setplane-cursor)
[285.435793] Starting subtest: universal-setplane-cursor
[285.438247] Starting dynamic subtest: pipe-A-eDP-1
[292.902862] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.385s)
[292.905420] Subtest universal-setplane-cursor: SUCCESS (7.386s)
[293.714682] [042/133] (731s left) gem_lmem_swapping (verify-ccs)
[293.763853] Subtest verify-ccs: SKIP (0.000s)
[293.810799] [043/133] (731s left) gem_workarounds (reset-context)
[293.874569] Starting subtest: reset-context
[294.034844] Subtest reset-context: SUCCESS (0.160s)
[294.159613] [044/133] (731s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[294.545518] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[295.319394] [045/133] (729s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[295.372185] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[295.375000] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[295.481850] [046/133] (729s left) gem_exec_reloc (basic-write-wc-noreloc)
[295.538772] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[295.610030] [047/133] (729s left) gem_userptr_blits (coherency-unsync)
[295.677343] Subtest coherency-unsync: SKIP (0.000s)
[295.799361] [048/133] (729s left) kms_universal_plane (universal-plane-functional)
[295.898987] Starting subtest: universal-plane-functional
[295.901175] Starting dynamic subtest: pipe-A-eDP-1
[299.834805] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.922s)
[299.846400] Starting dynamic subtest: pipe-B-eDP-1
[304.547997] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.701s)
[304.560219] Starting dynamic subtest: pipe-C-eDP-1
[308.864459] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.308s)
[308.875878] Starting dynamic subtest: pipe-D-eDP-1
[313.273027] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.407s)
[313.275830] Subtest universal-plane-functional: SUCCESS (17.344s)
[314.119917] [049/133] (711s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[314.234968] Starting subtest: 4-tiled-32bpp-rotate-180
[315.644714] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.412s)
[315.768467] [050/133] (709s left) gem_ctx_persistence (engines-persistence)
[315.836641] Starting subtest: engines-persistence
[315.839590] Starting dynamic subtest: rcs0
[316.600311] Dynamic subtest rcs0: SUCCESS (0.763s)
[316.623015] Starting dynamic subtest: bcs0
[317.362413] Dynamic subtest bcs0: SUCCESS (0.760s)
[317.375026] Starting dynamic subtest: vcs0
[318.108327] Dynamic subtest vcs0: SUCCESS (0.744s)
[318.120709] Starting dynamic subtest: vcs1
[318.893658] Dynamic subtest vcs1: SUCCESS (0.783s)
[318.906315] Starting dynamic subtest: vecs0
[319.663387] Dynamic subtest vecs0: SUCCESS (0.768s)
[319.676018] Starting dynamic subtest: ccs0
[320.418035] Dynamic subtest ccs0: SUCCESS (0.753s)
[320.421036] Subtest engines-persistence: SUCCESS (4.584s)
[320.532265] [051/133] (705s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[320.636742] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[327.540436] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.906s)
[327.689911] [052/133] (697s left) kms_color (ctm-negative)
[327.793799] Starting subtest: ctm-negative
[327.796740] Starting dynamic subtest: pipe-A-eDP-1
[328.505667] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s)
[328.515945] Starting dynamic subtest: pipe-B-eDP-1
[329.958086] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.451s)
[329.970712] Starting dynamic subtest: pipe-C-eDP-1
[331.304225] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.342s)
[331.313690] Starting dynamic subtest: pipe-D-eDP-1
[332.632989] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[332.635491] Subtest ctm-negative: SUCCESS (4.839s)
[333.442518] [053/133] (692s left) gem_exec_capture (capture)
[333.505257] Starting subtest: capture
[333.508011] Starting dynamic subtest: rcs0-smem
[334.334165] Dynamic subtest rcs0-smem: SUCCESS (0.826s)
[334.345837] Starting dynamic subtest: bcs0-smem
[335.657752] Dynamic subtest bcs0-smem: SUCCESS (1.322s)
[335.668214] Starting dynamic subtest: vcs0-smem
[336.469260] Dynamic subtest vcs0-smem: SUCCESS (0.810s)
[336.479478] Starting dynamic subtest: vcs1-smem
[337.780632] Dynamic subtest vcs1-smem: SUCCESS (1.310s)
[337.791361] Starting dynamic subtest: vecs0-smem
[338.674830] Dynamic subtest vecs0-smem: FAIL (0.828s)
[338.683970] Starting dynamic subtest: ccs0-smem
[339.927336] Dynamic subtest ccs0-smem: SUCCESS (1.318s)
[339.930257] Subtest capture: FAIL (6.419s)
[340.074616] [054/133] (685s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[340.176901] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[340.181216] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[340.276067] [055/133] (685s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[340.341272] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[340.346787] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[340.525514] [056/133] (685s left) perf (blocking)
[340.629544] Starting subtest: blocking
[340.631945] Starting dynamic subtest: 0-rcs0
[350.693608] Dynamic subtest 0-rcs0: SUCCESS (10.012s)
[350.706349] Starting dynamic subtest: 1-vcs1
[360.722078] Dynamic subtest 1-vcs1: SUCCESS (10.022s)
[360.725651] Subtest blocking: SUCCESS (20.037s)
[360.768240] [057/133] (665s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[360.897613] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[360.902344] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[360.990943] [058/133] (664s left) gem_exec_whisper (basic-normal)
[361.062711] Starting subtest: basic-normal
[366.234005] Subtest basic-normal: SUCCESS (5.150s)
[366.404773] [059/133] (659s left) prime_busy (before-wait)
[366.462000] Starting subtest: before-wait
[366.467855] Starting dynamic subtest: rcs0
[366.508035] Dynamic subtest rcs0: SUCCESS (0.042s)
[366.519900] Starting dynamic subtest: bcs0
[366.562616] Dynamic subtest bcs0: SUCCESS (0.054s)
[366.573294] Starting dynamic subtest: vcs0
[366.605819] Dynamic subtest vcs0: SUCCESS (0.043s)
[366.615726] Starting dynamic subtest: vcs1
[366.650715] Dynamic subtest vcs1: SUCCESS (0.044s)
[366.661991] Starting dynamic subtest: vecs0
[366.692536] Dynamic subtest vecs0: SUCCESS (0.041s)
[366.702784] Starting dynamic subtest: ccs0
[366.736994] Dynamic subtest ccs0: SUCCESS (0.044s)
[366.845451] Subtest before-wait: SUCCESS (0.381s)
[366.925334] [060/133] (659s left) gem_exec_balancer (bonded-sync)
[366.985807] Starting subtest: bonded-sync
[366.990992] Subtest bonded-sync: SKIP (0.000s)
[367.287802] [061/133] (658s left) gem_mmap_wc (write-read)
[367.357844] Subtest write-read: SKIP (0.000s)
[367.413162] [062/133] (658s left) sysfs_preempt_timeout (timeout)
[367.479015] Starting subtest: timeout
[367.481530] Starting dynamic subtest: vecs0
[376.754283] Dynamic subtest vecs0: SUCCESS (9.270s)
[376.767727] Starting dynamic subtest: ccs0
[378.081090] Dynamic subtest ccs0: SUCCESS (1.325s)
[378.101541] Starting dynamic subtest: vcs0
[387.766999] Dynamic subtest vcs0: SUCCESS (9.688s)
[387.777892] Starting dynamic subtest: bcs0
[389.074671] Dynamic subtest bcs0: SUCCESS (1.306s)
[389.085332] Starting dynamic subtest: rcs0
[390.335882] Dynamic subtest rcs0: SUCCESS (1.260s)
[390.346418] Starting dynamic subtest: vcs1
[399.729306] Dynamic subtest vcs1: SUCCESS (9.389s)
[399.732173] Subtest timeout: SUCCESS (32.250s)
[399.805553] [063/133] (626s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[399.912836] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[399.917185] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[400.011136] [064/133] (626s left) gem_spin_batch (spin-each)
[400.076327] Starting subtest: spin-each
[403.348933] Subtest spin-each: SUCCESS (3.263s)
[403.789777] [065/133] (622s left) dmabuf_sync_file (export-multiwait)
[403.843015] Starting subtest: export-multiwait
[403.845840] Subtest export-multiwait: SUCCESS (0.001s)
[403.898840] [066/133] (622s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[403.963994] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[403.966672] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[404.080303] [067/133] (622s left) gem_pread (exhaustion)
[404.144031] Subtest exhaustion: SKIP (0.000s)
[404.204934] [068/133] (622s left) kms_chamelium_hpd (vga-hpd-fast)
[404.596861] Subtest vga-hpd-fast: SKIP (0.000s)
[405.450819] [069/133] (620s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[405.543704] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[413.085287] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.544s)
[413.200347] [070/133] (613s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[413.316740] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[413.321738] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[413.411019] [071/133] (613s left) kms_vrr (flipline)
[413.499879] Starting subtest: flipline
[413.507495] Subtest flipline: SKIP (0.000s)
[413.585693] [072/133] (612s left) kms_setmode (invalid-clone-exclusive-crtc)
[413.653562] Starting subtest: invalid-clone-exclusive-crtc
[413.661840] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[413.755674] [073/133] (612s left) i915_hangman (detector)
[413.822832] Starting subtest: detector
[413.825271] Starting dynamic subtest: rcs0
[425.686416] Dynamic subtest rcs0: SUCCESS (11.863s)
[425.699375] Starting dynamic subtest: bcs0
[437.718221] Dynamic subtest bcs0: SUCCESS (12.026s)
[437.733302] Starting dynamic subtest: vcs0
[448.726877] Dynamic subtest vcs0: SUCCESS (11.003s)
[448.740883] Starting dynamic subtest: vcs1
[460.630947] Dynamic subtest vcs1: SUCCESS (11.898s)
[460.643921] Starting dynamic subtest: vecs0
[471.638352] Dynamic subtest vecs0: SUCCESS (11.002s)
[471.651333] Starting dynamic subtest: ccs0
[482.710345] Dynamic subtest ccs0: SUCCESS (11.066s)
[482.713181] Subtest detector: SUCCESS (68.875s)
[482.847907] [074/133] (543s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[482.946721] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[482.951698] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[483.020047] [075/133] (543s left) gem_exec_reloc (basic-wc-noreloc)
[483.079159] Subtest basic-wc-noreloc: SKIP (0.000s)
[483.137745] [076/133] (543s left) i915_query (query-topology-matches-eu-total)
[483.196201] Starting subtest: query-topology-matches-eu-total
[483.198562] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[483.294596] [077/133] (543s left) kms_setmode (invalid-clone-single-crtc)
[483.345991] Starting subtest: invalid-clone-single-crtc
[483.352344] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[483.439411] [078/133] (543s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[483.537155] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[483.541933] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[483.618336] [079/133] (543s left) kms_atomic (atomic-invalid-params)
[483.705919] Starting subtest: atomic-invalid-params
[483.708676] Starting dynamic subtest: pipe-A-eDP-1
[484.095793] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.391s)
[484.098689] Subtest atomic-invalid-params: SUCCESS (0.393s)
[484.922994] [080/133] (541s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[485.011594] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[485.016153] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[485.084765] [081/133] (541s left) prime_mmap (test_aperture_limit)
[485.140288] Starting subtest: test_aperture_limit
[485.142421] Starting dynamic subtest: test_aperture_limit-smem
[485.149571] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[485.153877] Subtest test_aperture_limit: SKIP (0.008s)
[485.220993] [082/133] (541s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[485.281661] Starting subtest: map-fixed-invalidate-overlap-busy
[485.285809] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[485.390638] [083/133] (541s left) kms_psr (fbc-psr2-cursor-plane-move)
[485.479924] Starting subtest: fbc-psr2-cursor-plane-move
[485.482800] Starting dynamic subtest: eDP-1
[485.487982] Dynamic subtest eDP-1: SKIP (0.000s)
[485.493275] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[485.553732] [084/133] (541s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[485.635460] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[485.637632] Starting dynamic subtest: pipe-A-eDP-1
[486.159532] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.500s)
[486.168785] Starting dynamic subtest: pipe-B-eDP-1
[487.568214] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.402s)
[487.579001] Starting dynamic subtest: pipe-C-eDP-1
[488.983838] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.415s)
[488.994138] Starting dynamic subtest: pipe-D-eDP-1
[490.378352] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.388s)
[490.380844] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.710s)
[491.575377] [085/133] (535s left) gem_exec_schedule (submit-early-slice)
[491.665885] Starting subtest: submit-early-slice
[491.668593] Starting dynamic subtest: rcs0
[491.702811] Dynamic subtest rcs0: SUCCESS (0.049s)
[491.715248] Starting dynamic subtest: bcs0
[491.744804] Dynamic subtest bcs0: SUCCESS (0.041s)
[491.755566] Starting dynamic subtest: vcs0
[491.792762] Dynamic subtest vcs0: SUCCESS (0.047s)
[491.803912] Starting dynamic subtest: vcs1
[491.837891] Dynamic subtest vcs1: SUCCESS (0.044s)
[491.853531] Starting dynamic subtest: vecs0
[491.883508] Dynamic subtest vecs0: SUCCESS (0.044s)
[491.896043] Starting dynamic subtest: ccs0
[491.929999] Dynamic subtest ccs0: SUCCESS (0.046s)
[491.932939] Subtest submit-early-slice: SUCCESS (0.277s)
[493.834935] [086/133] (533s left) gem_exec_fence (syncobj-unused-fence)
[494.282452] Starting subtest: syncobj-unused-fence
[494.292041] Subtest syncobj-unused-fence: SUCCESS (0.010s)
[495.816453] [087/133] (531s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[495.920943] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[499.935988] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.018s)
[501.080101] [088/133] (526s left) gem_wait (write-wait)
[501.144897] Starting subtest: write-wait
[501.148004] Starting dynamic subtest: all-engines
[502.239878] Dynamic subtest all-engines: SUCCESS (1.094s)
[502.256177] Starting dynamic subtest: rcs0
[503.298508] Dynamic subtest rcs0: SUCCESS (1.057s)
[503.318403] Starting dynamic subtest: bcs0
[504.360308] Dynamic subtest bcs0: SUCCESS (1.059s)
[504.387619] Starting dynamic subtest: vcs0
[505.420012] Dynamic subtest vcs0: SUCCESS (1.058s)
[505.436942] Starting dynamic subtest: vcs1
[506.478952] Dynamic subtest vcs1: SUCCESS (1.056s)
[506.495868] Starting dynamic subtest: vecs0
[507.536373] Dynamic subtest vecs0: SUCCESS (1.055s)
[507.553696] Starting dynamic subtest: ccs0
[508.596375] Dynamic subtest ccs0: SUCCESS (1.058s)
[508.599059] Subtest write-wait: SUCCESS (7.454s)
[508.808348] [089/133] (518s left) kms_getfb (getfb-handle-valid)
[508.904017] Starting subtest: getfb-handle-valid
[508.906778] Subtest getfb-handle-valid: SUCCESS (0.000s)
[509.019409] [090/133] (518s left) kms_psr (pr-cursor-mmap-gtt)
[509.108035] Starting subtest: pr-cursor-mmap-gtt
[509.112857] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[509.180712] [091/133] (518s left) syncobj_wait (wait-for-submit-complex)
[509.243305] Starting subtest: wait-for-submit-complex
[509.303034] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[509.377900] [092/133] (517s left) gem_basic (multigpu-create-close)
[509.438053] Starting subtest: multigpu-create-close
[509.442497] Subtest multigpu-create-close: SKIP (0.000s)
[509.530405] [093/133] (517s left) kms_pm_rpm (pc8-residency)
[511.068006] Starting subtest: pc8-residency
[511.073649] Subtest pc8-residency: SKIP (0.000s)
[511.811595] [094/133] (515s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[511.908855] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[515.462837] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.559s)
[516.611510] [095/133] (510s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[516.709939] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[517.422881] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.715s)
[517.582868] [096/133] (509s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[517.679781] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[517.684819] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[517.778133] [097/133] (509s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[517.883533] Starting subtest: compare-crc-sanitycheck-xr24
[517.885760] Starting dynamic subtest: pipe-A-eDP-1
[518.509864] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.626s)
[518.520575] Starting dynamic subtest: pipe-B-eDP-1
[519.788844] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.277s)
[519.799238] Starting dynamic subtest: pipe-C-eDP-1
[521.070260] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.282s)
[521.080803] Starting dynamic subtest: pipe-D-eDP-1
[522.295765] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.223s)
[522.298341] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.414s)
[523.135569] [098/133] (504s left) gem_render_copy (yf-tiled-ccs-to-linear)
[523.186828] Starting subtest: yf-tiled-ccs-to-linear
[523.192027] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[523.371557] [099/133] (504s left) kms_pm_backlight (fade-with-dpms)
[523.460039] Starting subtest: fade-with-dpms
[525.614528] Starting dynamic subtest: eDP-1
[528.117826] Dynamic subtest eDP-1: SUCCESS (2.504s)
[528.120674] Subtest fade-with-dpms: SUCCESS (4.660s)
[528.935354] [100/133] (498s left) i915_pm_rpm (gem-pread)
[530.865256] Starting subtest: gem-pread
[532.912014] Subtest gem-pread: SUCCESS (2.047s)
[532.998058] [101/133] (494s left) i915_drm_fdinfo (virtual-busy)
[533.042053] Starting subtest: virtual-busy
[533.046939] Subtest virtual-busy: SKIP (0.000s)
[533.114501] [102/133] (494s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[533.210876] Starting subtest: pr-cursor-plane-update-sf
[533.216000] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[533.279542] [103/133] (494s left) kms_lease (cursor-implicit-plane)
[533.369600] Starting subtest: cursor-implicit-plane
[533.371783] Starting dynamic subtest: pipe-A-eDP-1
[533.820459] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.451s)
[533.831037] Starting dynamic subtest: pipe-B-eDP-1
[534.979717] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.159s)
[534.990011] Starting dynamic subtest: pipe-C-eDP-1
[536.113831] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.129s)
[536.125686] Starting dynamic subtest: pipe-D-eDP-1
[537.231444] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.118s)
[537.234037] Subtest cursor-implicit-plane: SUCCESS (3.863s)
[538.091342] [104/133] (489s left) syncobj_timeline (wait-any-complex)
[538.155852] Starting subtest: wait-any-complex
[538.216578] Subtest wait-any-complex: SUCCESS (0.063s)
[538.298682] [105/133] (489s left) gem_ctx_persistence (legacy-engines-mixed-process)
[538.372447] Starting subtest: legacy-engines-mixed-process
[538.374546] Starting dynamic subtest: render
[539.709033] Dynamic subtest render: SUCCESS (1.338s)
[539.720209] Starting dynamic subtest: bsd1
[541.685492] Dynamic subtest bsd1: SUCCESS (1.975s)
[541.700600] Starting dynamic subtest: bsd2
[543.736703] Dynamic subtest bsd2: SUCCESS (2.050s)
[543.747033] Starting dynamic subtest: blt
[545.733668] Dynamic subtest blt: SUCCESS (1.994s)
[545.746171] Starting dynamic subtest: vebox
[547.713006] Dynamic subtest vebox: SUCCESS (1.979s)
[547.716711] Subtest legacy-engines-mixed-process: SUCCESS (9.345s)
[547.795740] [106/133] (480s left) kms_flip (plain-flip-ts-check)
[547.895741] Starting subtest: plain-flip-ts-check
[547.898293] Starting dynamic subtest: A-eDP1
[556.812001] Dynamic subtest A-eDP1: SUCCESS (8.883s)
[556.823947] Starting dynamic subtest: B-eDP1
[565.641044] Dynamic subtest B-eDP1: SUCCESS (8.825s)
[565.655550] Starting dynamic subtest: C-eDP1
[574.475866] Dynamic subtest C-eDP1: SUCCESS (8.820s)
[574.487850] Starting dynamic subtest: D-eDP1
[583.305982] Dynamic subtest D-eDP1: SUCCESS (8.826s)
[583.308812] Subtest plain-flip-ts-check: SUCCESS (35.369s)
[584.434166] [107/133] (443s left) kms_invalid_mode (bad-vsync-start)
[584.521655] Starting subtest: bad-vsync-start
[584.524174] Starting dynamic subtest: pipe-A-eDP-1
[584.812058] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s)
[584.822726] Starting dynamic subtest: pipe-B-eDP-1
[584.825418] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[584.835592] Starting dynamic subtest: pipe-C-eDP-1
[584.837891] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[584.847705] Starting dynamic subtest: pipe-D-eDP-1
[584.850129] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[584.852692] Subtest bad-vsync-start: SUCCESS (0.300s)
[585.662973] [108/133] (442s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[585.786806] Starting subtest: basic-flip-before-cursor-varying-size
[586.726918] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.937s)
[587.594823] [109/133] (440s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[587.709299] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[597.036016] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.324s)
[597.833581] [110/133] (430s left) gem_softpin (overlap)
[597.915558] Starting subtest: overlap
[598.903994] Subtest overlap: SUCCESS (0.987s)
[599.030566] [111/133] (429s left) kms_psr (psr2-sprite-mmap-gtt)
[599.133451] Starting subtest: psr2-sprite-mmap-gtt
[599.135798] Starting dynamic subtest: eDP-1
[600.549294] Dynamic subtest eDP-1: SKIP (1.413s)
[600.554864] Subtest psr2-sprite-mmap-gtt: SKIP (1.417s)
[600.720142] [112/133] (427s left) kms_frontbuffer_tracking (psr-suspend)
[600.821631] Starting subtest: psr-suspend
[638.861138] Subtest psr-suspend: SUCCESS (7.861s)
[638.967135] [113/133] (389s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[639.085120] Starting subtest: psr2-cursor-plane-update-sf
[639.087920] Starting dynamic subtest: pipe-A-eDP-1
[639.674499] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.568s)
[639.686466] Starting dynamic subtest: pipe-B-eDP-1
[641.041083] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.321s)
[641.044316] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.892s)
[641.957242] [114/133] (386s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[642.061654] Starting subtest: y-tiled-16bpp-rotate-270
[642.067169] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[642.175802] [115/133] (386s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[642.229960] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[642.335841] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[642.423786] [116/133] (385s left) kms_flip (modeset-vs-vblank-race)
[642.523163] Starting subtest: modeset-vs-vblank-race
[642.526242] Starting dynamic subtest: A-eDP1
[647.291611] Dynamic subtest A-eDP1: SUCCESS (4.676s)
[647.304657] Starting dynamic subtest: B-eDP1
[652.076326] Dynamic subtest B-eDP1: SUCCESS (4.731s)
[652.087609] Starting dynamic subtest: C-eDP1
[656.627331] Dynamic subtest C-eDP1: SUCCESS (4.521s)
[656.637492] Starting dynamic subtest: D-eDP1
[661.193859] Dynamic subtest D-eDP1: SUCCESS (4.549s)
[661.196427] Subtest modeset-vs-vblank-race: SUCCESS (18.488s)
[662.353963] [117/133] (366s left) gem_exec_schedule (smoketest-all)
[662.429942] Starting subtest: smoketest-all
[694.837454] Subtest smoketest-all: SUCCESS (32.350s)
[695.954808] [118/133] (332s left) gem_mmap_gtt (basic-small-copy-odd)
[696.021161] Subtest basic-small-copy-odd: SKIP (0.000s)
[696.076453] [119/133] (332s left) gen9_exec_parse (bb-start-cmd)
[696.137205] Subtest bb-start-cmd: SKIP (0.000s)
[696.211680] [120/133] (332s left) gem_mmap_gtt (basic)
[696.269135] Subtest basic: SKIP (0.000s)
[696.333668] [121/133] (332s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[696.391129] Starting subtest: etime-single-wait-available-unsubmitted
[696.497245] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.107s)
[696.592644] [122/133] (332s left) gem_exec_alignment (forked)
[696.660327] Starting subtest: forked
[735.924633] Subtest forked: SUCCESS (39.198s)
[736.063992] [123/133] (292s left) kms_getfb (getfb2-handle-zero)
[736.215476] Starting subtest: getfb2-handle-zero
[736.217573] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[736.315569] [124/133] (292s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[736.419295] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[736.422128] Starting dynamic subtest: pipe-A
[736.455490] Dynamic subtest pipe-A: SKIP (0.029s)
[736.465724] Starting dynamic subtest: pipe-B
[736.487316] Dynamic subtest pipe-B: SKIP (0.033s)
[736.497250] Starting dynamic subtest: pipe-C
[736.522245] Dynamic subtest pipe-C: SKIP (0.033s)
[736.532242] Starting dynamic subtest: pipe-D
[736.553820] Dynamic subtest pipe-D: SKIP (0.032s)
[736.559043] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.130s)
[736.664650] [125/133] (292s left) kms_content_protection (uevent)
[736.813145] Starting subtest: uevent
[736.819085] Subtest uevent: SKIP (0.000s)
[736.930440] [126/133] (291s left) kms_plane_multiple (2x-tiling-x)
[737.028210] Starting subtest: 2x-tiling-x
[737.033235] Subtest 2x-tiling-x: SKIP (0.000s)
[737.101610] [127/133] (291s left) gem_exec_schedule (preempt-contexts)
[737.179518] Starting subtest: preempt-contexts
[737.181353] Starting dynamic subtest: rcs0
[737.245037] Dynamic subtest rcs0: SUCCESS (0.070s)
[737.254980] Starting dynamic subtest: bcs0
[737.323829] Dynamic subtest bcs0: SUCCESS (0.078s)
[737.334057] Starting dynamic subtest: vcs0
[737.416908] Dynamic subtest vcs0: SUCCESS (0.092s)
[737.427787] Starting dynamic subtest: vcs1
[737.460960] Dynamic subtest vcs1: SUCCESS (0.043s)
[737.471024] Starting dynamic subtest: vecs0
[737.515126] Dynamic subtest vecs0: SUCCESS (0.053s)
[737.524978] Starting dynamic subtest: ccs0
[737.563973] Dynamic subtest ccs0: SUCCESS (0.049s)
[737.566114] Subtest preempt-contexts: SUCCESS (0.390s)
[738.863305] [128/133] (290s left) perf_pmu (most-busy-check-all)
[738.910717] Starting subtest: most-busy-check-all
[738.913038] Starting dynamic subtest: rcs0
[740.822572] Dynamic subtest rcs0: SUCCESS (1.912s)
[740.836611] Starting dynamic subtest: bcs0
[742.870357] Dynamic subtest bcs0: SUCCESS (2.044s)
[742.883377] Starting dynamic subtest: vcs0
[744.854090] Dynamic subtest vcs0: SUCCESS (1.981s)
[744.877303] Starting dynamic subtest: vcs1
[746.773732] Dynamic subtest vcs1: SUCCESS (1.917s)
[746.787964] Starting dynamic subtest: vecs0
[747.861363] Dynamic subtest vecs0: SUCCESS (1.085s)
[747.874169] Starting dynamic subtest: ccs0
[749.845623] Dynamic subtest ccs0: SUCCESS (1.982s)
[749.848622] Subtest most-busy-check-all: SUCCESS (10.939s)
[749.956126] [129/133] (279s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[750.055089] Starting subtest: multiplane-rotation-cropping-top
[767.534084] Subtest multiplane-rotation-cropping-top: SUCCESS (17.482s)
[767.630247] [130/133] (261s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[767.730009] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[767.735240] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[767.834174] [131/133] (261s left) syncobj_wait (signal)
[767.896184] Starting subtest: signal
[767.898679] Subtest signal: SUCCESS (0.000s)
[767.983665] [132/133] (261s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[768.070869] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[768.074855] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[768.143596] [133/133] (260s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[768.242888] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[775.037603] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.798s)
[775.152456] Closing watchdogs
results: parsing output: 0/ for test: sw_sync
results: parsing output: 1/ for test: gem_busy
results: parsing output: 2/ for test: i915_query
results: parsing output: 3/ for test: gem_userptr_blits
results: parsing output: 4/ for test: perf
results: parsing output: 5/ for test: kms_flip
results: parsing output: 6/ for test: kms_feature_discovery
results: parsing output: 7/ for test: kms_atomic_transition
results: parsing output: 8/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 9/ for test: kms_plane
results: parsing output: 10/ for test: gem_mmap_offset
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_psr2_sf
results: parsing output: 14/ for test: gem_mmap_wc
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gem_eio
results: parsing output: 18/ for test: kms_cursor_crc
results: parsing output: 19/ for test: kms_busy
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_close_race
results: parsing output: 24/ for test: api_intel_allocator
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: gem_mmap_wc
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: gem_exec_fence
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: kms_addfb_basic
results: parsing output: 34/ for test: gem_lmem_swapping
results: parsing output: 35/ for test: api_intel_bb
results: parsing output: 36/ for test: kms_vblank
results: parsing output: 37/ for test: kms_fbcon_fbt
results: parsing output: 38/ for test: kms_cursor_crc
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: kms_atomic_interruptible
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: gem_workarounds
results: parsing output: 43/ for test: kms_chamelium_edid
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_reloc
results: parsing output: 46/ for test: gem_userptr_blits
results: parsing output: 47/ for test: kms_universal_plane
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: gem_ctx_persistence
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_color
results: parsing output: 52/ for test: gem_exec_capture
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_render_copy
results: parsing output: 55/ for test: perf
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_whisper
results: parsing output: 58/ for test: prime_busy
results: parsing output: 59/ for test: gem_exec_balancer
results: parsing output: 60/ for test: gem_mmap_wc
results: parsing output: 61/ for test: sysfs_preempt_timeout
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: dmabuf_sync_file
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: gem_pread
results: parsing output: 67/ for test: kms_chamelium_hpd
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_vrr
results: parsing output: 71/ for test: kms_setmode
results: parsing output: 72/ for test: i915_hangman
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: i915_query
results: parsing output: 76/ for test: kms_setmode
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_atomic
results: parsing output: 79/ for test: kms_flip_scaled_crc
results: parsing output: 80/ for test: prime_mmap
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_wait
results: parsing output: 88/ for test: kms_getfb
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: gem_basic
results: parsing output: 92/ for test: kms_pm_rpm
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_pipe_crc_basic
results: parsing output: 97/ for test: gem_render_copy
results: parsing output: 98/ for test: kms_pm_backlight
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: i915_drm_fdinfo
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: kms_lease
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: gem_ctx_persistence
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_invalid_mode
results: parsing output: 107/ for test: kms_cursor_legacy
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: gem_softpin
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_psr2_sf
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: gem_exec_schedule
results: parsing output: 117/ for test: gem_mmap_gtt
results: parsing output: 118/ for test: gen9_exec_parse
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: gem_exec_alignment
results: parsing output: 122/ for test: kms_getfb
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: kms_content_protection
results: parsing output: 125/ for test: kms_plane_multiple
results: parsing output: 126/ for test: gem_exec_schedule
results: parsing output: 127/ for test: perf_pmu
results: parsing output: 128/ for test: kms_rotation_crc
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: kms_plane
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.