[59.061865] Initializing watchdogs
[59.061912]   /dev/watchdog0
[59.076260] [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.080736] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.083133] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.086634] [001/133] (960s left) sw_sync (timeline_closed)
[59.152169] Starting subtest: timeline_closed
[59.154629] Subtest timeline_closed: SUCCESS (0.000s)
[59.202942] [002/133] (960s left) gem_busy (close-race)
[59.257367] Starting subtest: close-race
[82.901878] Subtest close-race: SUCCESS (23.642s)
[83.259906] [FACT gem_busy (close-race)] new: kernel.kmod_is_loaded.vgem: true
[83.267145] [003/133] (936s left) i915_query (query-topology-garbage-items)
[83.349702] Starting subtest: query-topology-garbage-items
[83.352145] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[83.457394] [004/133] (936s left) gem_userptr_blits (dmabuf-unsync)
[83.523657] Subtest dmabuf-unsync: SKIP (0.000s)
[83.666447] [005/133] (936s left) perf (gen8-unprivileged-single-ctx-counters)
[83.819783] Starting subtest: gen8-unprivileged-single-ctx-counters
[83.824126] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[83.862910] [006/133] (935s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[83.970956] Starting subtest: flip-vs-dpms-off-vs-modeset
[83.973446] Starting dynamic subtest: A-eDP1
[87.572465] Dynamic subtest A-eDP1: SUCCESS (3.601s)
[87.581573] Starting dynamic subtest: B-eDP1
[90.784733] Dynamic subtest B-eDP1: SUCCESS (3.212s)
[90.794293] Starting dynamic subtest: C-eDP1
[93.870652] Dynamic subtest C-eDP1: SUCCESS (3.086s)
[93.880864] Starting dynamic subtest: D-eDP1
[96.984366] Dynamic subtest D-eDP1: SUCCESS (3.113s)
[96.986755] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.017s)
[97.771801] [007/133] (922s left) kms_feature_discovery (chamelium)
[97.876081] Starting subtest: chamelium
[97.880199] Subtest chamelium: SKIP (0.000s)
[97.947295] [008/133] (921s left) kms_atomic_transition (plane-all-modeset-transition)
[98.059785] Starting subtest: plane-all-modeset-transition
[98.064501] Subtest plane-all-modeset-transition: SKIP (0.000s)
[98.130859] [009/133] (921s left) gem_tiled_partial_pwrite_pread (reads)
[98.190438] Subtest reads: SKIP (0.000s)
[98.254792] [010/133] (921s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[98.343282] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[98.347872] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[98.416573] [011/133] (921s left) gem_mmap_offset (oob-read)
[98.463723] Starting subtest: oob-read
[98.465691] Subtest oob-read: SUCCESS (0.000s)
[98.559606] [012/133] (921s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[98.645938] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[98.648279] Starting dynamic subtest: pipe-A-eDP-1
[98.673697] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[98.683817] Starting dynamic subtest: pipe-B-eDP-1
[98.706487] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[98.713574] Starting dynamic subtest: pipe-C-eDP-1
[98.733181] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[98.739939] Starting dynamic subtest: pipe-D-eDP-1
[98.760783] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[98.764445] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[100.854871] [013/133] (919s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[100.903551] Starting subtest: multi-wait-available-submitted-signaled
[100.906060] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.001s)
[101.014838] [014/133] (919s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[101.124565] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[101.129232] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[101.196654] [015/133] (918s left) gem_mmap_wc (read)
[101.252111] Subtest read: SKIP (0.000s)
[101.304761] [016/133] (918s left) kms_big_fb (linear-16bpp-rotate-180)
[101.412147] Starting subtest: linear-16bpp-rotate-180
[102.856856] Subtest linear-16bpp-rotate-180: SUCCESS (1.446s)
[102.990729] [017/133] (917s left) kms_psr (fbc-psr2-sprite-render)
[103.092330] Starting subtest: fbc-psr2-sprite-render
[103.094120] Starting dynamic subtest: eDP-1
[103.097399] Dynamic subtest eDP-1: SKIP (0.000s)
[103.100819] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[103.164833] [018/133] (917s left) gem_eio (in-flight-external)
[103.383198] Starting subtest: in-flight-external
[104.985073] Subtest in-flight-external: SUCCESS (1.602s)
[105.127369] [019/133] (915s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[105.381408] Starting subtest: cursor-rapid-movement-256x256
[105.386156] Starting dynamic subtest: pipe-A-eDP-1
[105.930954] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.544s)
[105.956744] Starting dynamic subtest: pipe-D-eDP-1
[107.168995] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.217s)
[107.171298] Subtest cursor-rapid-movement-256x256: SUCCESS (1.786s)
[108.012828] [020/133] (912s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[108.120448] Starting subtest: extended-modeset-hang-oldfb-with-reset
[108.122816] Starting dynamic subtest: pipe-A
[119.787381] Dynamic subtest pipe-A: SUCCESS (11.666s)
[119.797894] Starting dynamic subtest: pipe-D
[132.068297] Dynamic subtest pipe-D: SUCCESS (12.277s)
[132.070677] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.947s)
[132.875220] [021/133] (887s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[132.973178] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[132.975157] Starting dynamic subtest: pipe-A-eDP-1
[133.892582] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.913s)
[133.902501] Starting dynamic subtest: pipe-B-eDP-1
[135.451474] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.556s)
[135.461301] Starting dynamic subtest: pipe-C-eDP-1
[136.983322] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.530s)
[136.992993] Starting dynamic subtest: pipe-D-eDP-1
[138.545815] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.564s)
[138.548187] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.567s)
[139.696088] [022/133] (880s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[139.796034] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[139.800319] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[139.887696] [023/133] (880s left) kms_big_fb (yf-tiled-addfb)
[139.984250] Starting subtest: yf-tiled-addfb
[139.988505] Subtest yf-tiled-addfb: SKIP (0.000s)
[140.091192] [024/133] (880s left) gem_close_race (multigpu-basic-threads)
[140.158236] Starting subtest: multigpu-basic-threads
[140.162601] Subtest multigpu-basic-threads: SKIP (0.000s)
[140.384084] [025/133] (880s left) api_intel_allocator (two-level-inception-interruptible)
[140.443949] Starting subtest: two-level-inception-interruptible
[145.849625] Subtest two-level-inception-interruptible: SUCCESS (5.406s)
[145.979964] [026/133] (874s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[146.088631] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[153.744838] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.659s)
[153.886051] [027/133] (866s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[153.989896] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[153.992098] Starting dynamic subtest: pipe-A-eDP-1
[154.014236] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[154.024508] Starting dynamic subtest: pipe-B-eDP-1
[154.045679] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[154.053768] Starting dynamic subtest: pipe-C-eDP-1
[154.075339] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[154.083201] Starting dynamic subtest: pipe-D-eDP-1
[154.104806] Dynamic subtest pipe-D-eDP-1: SKIP (0.002s)
[154.109167] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.007s)
[156.267170] [028/133] (864s left) gem_mmap_wc (copy)
[156.324357] Subtest copy: SKIP (0.000s)
[156.392745] [029/133] (864s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[156.517239] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[156.521754] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[156.662139] [030/133] (864s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[156.753056] Starting subtest: x-tiled-16bpp-rotate-90
[156.773628] Subtest x-tiled-16bpp-rotate-90: SKIP (0.020s)
[156.901219] [031/133] (863s left) kms_cursor_crc (cursor-sliding-256x85)
[157.165032] Starting subtest: cursor-sliding-256x85
[157.211863] Subtest cursor-sliding-256x85: SKIP (0.043s)
[157.387250] [032/133] (863s left) gem_exec_fence (syncobj-invalid-flags)
[157.854461] Starting subtest: syncobj-invalid-flags
[157.856918] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[159.008808] [033/133] (861s left) kms_flip (dpms-vs-vblank-race-interruptible)
[159.098308] Starting subtest: dpms-vs-vblank-race-interruptible
[159.100660] Starting dynamic subtest: A-eDP1
[162.925710] Dynamic subtest A-eDP1: SUCCESS (3.825s)
[162.934692] Starting dynamic subtest: B-eDP1
[167.551178] Dynamic subtest B-eDP1: SUCCESS (4.623s)
[167.560497] Starting dynamic subtest: C-eDP1
[172.194544] Dynamic subtest C-eDP1: SUCCESS (4.640s)
[172.205049] Starting dynamic subtest: D-eDP1
[176.820657] Dynamic subtest D-eDP1: SUCCESS (4.625s)
[176.826470] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (17.726s)
[177.693439] [034/133] (843s left) kms_addfb_basic (basic-y-tiled-legacy)
[177.756143] Starting subtest: basic-y-tiled-legacy
[177.760450] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[177.895803] [035/133] (843s left) gem_lmem_swapping (heavy-verify-multi)
[177.948288] Subtest heavy-verify-multi: SKIP (0.000s)
[177.993804] [036/133] (843s left) api_intel_bb (misplaced-blitter)
[178.046340] Starting subtest: misplaced-blitter
[178.051892] Subtest misplaced-blitter: SUCCESS (0.008s)
[178.183169] [037/133] (842s left) kms_vblank (query-idle-hang)
[178.289952] Starting subtest: query-idle-hang
[178.292076] Starting dynamic subtest: pipe-A-eDP-1
[204.685591] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.393s)
[204.699292] Starting dynamic subtest: pipe-D-eDP-1
[229.660399] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.974s)
[229.662784] Subtest query-idle-hang: SUCCESS (51.371s)
[230.476310] [038/133] (790s left) kms_fbcon_fbt (psr-suspend)
[230.539536] Starting subtest: psr-suspend
[281.368653] Subtest psr-suspend: SUCCESS (20.596s)
[284.453126] [039/133] (739s left) kms_cursor_crc (cursor-offscreen-128x42)
[284.695831] Starting subtest: cursor-offscreen-128x42
[284.747282] Subtest cursor-offscreen-128x42: SKIP (0.047s)
[284.954954] [040/133] (739s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[285.050951] Starting subtest: bad-pixel-format-y-tiled-ccs
[285.053203] Starting dynamic subtest: pipe-A-eDP-1
[285.076049] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[285.086573] Starting dynamic subtest: pipe-B-eDP-1
[285.108140] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[285.117893] Starting dynamic subtest: pipe-C-eDP-1
[285.139694] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[285.148768] Starting dynamic subtest: pipe-D-eDP-1
[285.171927] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[285.176017] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[287.325274] [041/133] (736s left) kms_atomic_interruptible (universal-setplane-cursor)
[287.416711] Starting subtest: universal-setplane-cursor
[287.419026] Starting dynamic subtest: pipe-A-eDP-1
[294.845050] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.422s)
[294.847354] Subtest universal-setplane-cursor: SUCCESS (7.423s)
[295.693133] [042/133] (728s left) gem_lmem_swapping (verify-ccs)
[295.741486] Subtest verify-ccs: SKIP (0.000s)
[295.787021] [043/133] (728s left) gem_workarounds (reset-context)
[295.849278] Starting subtest: reset-context
[296.004671] Subtest reset-context: SUCCESS (0.158s)
[296.126449] [044/133] (728s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[296.511694] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[297.308669] [045/133] (727s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[297.367667] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[297.370094] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[297.462958] [046/133] (726s left) gem_exec_reloc (basic-write-wc-noreloc)
[297.526287] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[297.601548] [047/133] (726s left) gem_userptr_blits (coherency-unsync)
[297.667276] Subtest coherency-unsync: SKIP (0.000s)
[297.786776] [048/133] (726s left) kms_universal_plane (universal-plane-functional)
[297.880667] Starting subtest: universal-plane-functional
[297.882519] Starting dynamic subtest: pipe-A-eDP-1
[301.888166] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.006s)
[301.899031] Starting dynamic subtest: pipe-B-eDP-1
[306.510793] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.621s)
[306.520590] Starting dynamic subtest: pipe-C-eDP-1
[310.832219] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.319s)
[310.843399] Starting dynamic subtest: pipe-D-eDP-1
[315.155596] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.322s)
[315.158064] Subtest universal-plane-functional: SUCCESS (17.274s)
[316.026716] [049/133] (708s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[316.126712] Starting subtest: 4-tiled-32bpp-rotate-180
[317.606280] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.479s)
[317.729752] [050/133] (706s left) gem_ctx_persistence (engines-persistence)
[317.785215] Starting subtest: engines-persistence
[317.787008] Starting dynamic subtest: rcs0
[318.564333] Dynamic subtest rcs0: SUCCESS (0.781s)
[318.576108] Starting dynamic subtest: bcs0
[319.335148] Dynamic subtest bcs0: SUCCESS (0.769s)
[319.349780] Starting dynamic subtest: vcs0
[320.099356] Dynamic subtest vcs0: SUCCESS (0.761s)
[320.111255] Starting dynamic subtest: vcs1
[320.849544] Dynamic subtest vcs1: SUCCESS (0.748s)
[320.860009] Starting dynamic subtest: vecs0
[321.635635] Dynamic subtest vecs0: SUCCESS (0.784s)
[321.647592] Starting dynamic subtest: ccs0
[322.386308] Dynamic subtest ccs0: SUCCESS (0.749s)
[322.388721] Subtest engines-persistence: SUCCESS (4.605s)
[322.526977] [051/133] (702s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[322.624817] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[329.561692] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.939s)
[329.712617] [052/133] (695s left) kms_color (ctm-negative)
[329.824674] Starting subtest: ctm-negative
[329.826801] Starting dynamic subtest: pipe-A-eDP-1
[330.532018] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.709s)
[330.542254] Starting dynamic subtest: pipe-B-eDP-1
[331.971046] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.438s)
[331.980802] Starting dynamic subtest: pipe-C-eDP-1
[333.341647] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.371s)
[333.361420] Starting dynamic subtest: pipe-D-eDP-1
[334.676935] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[334.679278] Subtest ctm-negative: SUCCESS (4.852s)
[335.482975] [053/133] (689s left) gem_exec_capture (capture)
[335.568805] Starting subtest: capture
[335.571021] Starting dynamic subtest: rcs0-smem
[336.389357] Dynamic subtest rcs0-smem: SUCCESS (0.822s)
[336.401179] Starting dynamic subtest: bcs0-smem
[337.404351] Dynamic subtest bcs0-smem: SUCCESS (1.014s)
[337.413367] Starting dynamic subtest: vcs0-smem
[338.210795] Dynamic subtest vcs0-smem: SUCCESS (0.804s)
[338.221207] Starting dynamic subtest: vcs1-smem
[339.026250] Dynamic subtest vcs1-smem: SUCCESS (0.815s)
[339.037087] Starting dynamic subtest: vecs0-smem
[340.428475] Dynamic subtest vecs0-smem: FAIL (1.339s)
[340.439084] Starting dynamic subtest: ccs0-smem
[341.688106] Dynamic subtest ccs0-smem: SUCCESS (1.321s)
[341.690433] Subtest capture: FAIL (6.120s)
[341.794362] [054/133] (683s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[341.894884] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[341.898891] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[341.989925] [055/133] (682s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[342.056210] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[342.060802] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[342.242328] [056/133] (682s left) perf (blocking)
[342.339931] Starting subtest: blocking
[342.342398] Starting dynamic subtest: 0-rcs0
[352.375018] Dynamic subtest 0-rcs0: SUCCESS (10.032s)
[352.384901] Starting dynamic subtest: 1-vcs1
[362.396718] Dynamic subtest 1-vcs1: SUCCESS (10.019s)
[362.399581] Subtest blocking: SUCCESS (20.055s)
[362.446752] [057/133] (662s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[362.546668] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[362.550646] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[362.651860] [058/133] (662s left) gem_exec_whisper (basic-normal)
[362.722283] Starting subtest: basic-normal
[367.737765] Subtest basic-normal: SUCCESS (5.012s)
[367.939344] [059/133] (657s left) prime_busy (before-wait)
[367.995597] Starting subtest: before-wait
[368.001524] Starting dynamic subtest: rcs0
[368.032682] Dynamic subtest rcs0: SUCCESS (0.033s)
[368.044224] Starting dynamic subtest: bcs0
[368.074488] Dynamic subtest bcs0: SUCCESS (0.041s)
[368.084742] Starting dynamic subtest: vcs0
[368.117492] Dynamic subtest vcs0: SUCCESS (0.042s)
[368.127861] Starting dynamic subtest: vcs1
[368.158905] Dynamic subtest vcs1: SUCCESS (0.041s)
[368.168493] Starting dynamic subtest: vecs0
[368.200983] Dynamic subtest vecs0: SUCCESS (0.041s)
[368.210998] Starting dynamic subtest: ccs0
[368.243919] Dynamic subtest ccs0: SUCCESS (0.042s)
[368.354518] Subtest before-wait: SUCCESS (0.357s)
[368.447804] [060/133] (656s left) gem_exec_balancer (bonded-sync)
[368.505912] Starting subtest: bonded-sync
[368.510350] Subtest bonded-sync: SKIP (0.000s)
[368.809684] [061/133] (656s left) gem_mmap_wc (write-read)
[368.861138] Subtest write-read: SKIP (0.000s)
[368.922831] [062/133] (656s left) sysfs_preempt_timeout (timeout)
[368.976886] Starting subtest: timeout
[368.979159] Starting dynamic subtest: vecs0
[378.151249] Dynamic subtest vecs0: SUCCESS (9.170s)
[378.163272] Starting dynamic subtest: ccs0
[379.435469] Dynamic subtest ccs0: SUCCESS (1.282s)
[379.446239] Starting dynamic subtest: vcs0
[389.098538] Dynamic subtest vcs0: SUCCESS (9.660s)
[389.110759] Starting dynamic subtest: bcs0
[390.344946] Dynamic subtest bcs0: SUCCESS (1.246s)
[390.355558] Starting dynamic subtest: rcs0
[391.605100] Dynamic subtest rcs0: SUCCESS (1.257s)
[391.619576] Starting dynamic subtest: vcs1
[401.125062] Dynamic subtest vcs1: SUCCESS (9.516s)
[401.127726] Subtest timeout: SUCCESS (32.144s)
[401.212814] [063/133] (623s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[401.310552] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[401.314620] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[401.410390] [064/133] (623s left) gem_spin_batch (spin-each)
[401.470533] Starting subtest: spin-each
[404.742335] Subtest spin-each: SUCCESS (3.262s)
[405.178617] [065/133] (620s left) dmabuf_sync_file (export-multiwait)
[405.217902] Starting subtest: export-multiwait
[405.220118] Subtest export-multiwait: SUCCESS (0.001s)
[405.276362] [066/133] (620s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[405.332511] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[405.334774] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[405.427890] [067/133] (619s left) gem_pread (exhaustion)
[405.482208] Subtest exhaustion: SKIP (0.000s)
[405.548940] [068/133] (619s left) kms_chamelium_hpd (vga-hpd-fast)
[405.939888] Subtest vga-hpd-fast: SKIP (0.000s)
[406.788427] [069/133] (618s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[406.885479] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[414.426984] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.544s)
[414.568880] [070/133] (610s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[414.667539] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[414.672275] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[414.758689] [071/133] (610s left) kms_vrr (flipline)
[414.856115] Starting subtest: flipline
[414.862719] Subtest flipline: SKIP (0.000s)
[414.929808] [072/133] (610s left) kms_setmode (invalid-clone-exclusive-crtc)
[414.996129] Starting subtest: invalid-clone-exclusive-crtc
[415.003161] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[415.103902] [073/133] (610s left) i915_hangman (detector)
[415.176849] Starting subtest: detector
[415.178847] Starting dynamic subtest: rcs0
[427.082561] Dynamic subtest rcs0: SUCCESS (11.905s)
[427.095597] Starting dynamic subtest: bcs0
[438.090840] Dynamic subtest bcs0: SUCCESS (11.004s)
[438.102686] Starting dynamic subtest: vcs0
[450.122804] Dynamic subtest vcs0: SUCCESS (12.028s)
[450.135494] Starting dynamic subtest: vcs1
[461.065877] Dynamic subtest vcs1: SUCCESS (10.939s)
[461.078821] Starting dynamic subtest: vecs0
[473.034341] Dynamic subtest vecs0: SUCCESS (11.965s)
[473.046165] Starting dynamic subtest: ccs0
[484.106640] Dynamic subtest ccs0: SUCCESS (11.068s)
[484.109493] Subtest detector: SUCCESS (68.926s)
[484.233773] [074/133] (541s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[484.338444] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[484.342876] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[484.412068] [075/133] (541s left) gem_exec_reloc (basic-wc-noreloc)
[484.482198] Subtest basic-wc-noreloc: SKIP (0.000s)
[484.557667] [076/133] (541s left) i915_query (query-topology-matches-eu-total)
[484.607840] Starting subtest: query-topology-matches-eu-total
[484.610072] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[484.705310] [077/133] (541s left) kms_setmode (invalid-clone-single-crtc)
[484.770210] Starting subtest: invalid-clone-single-crtc
[484.776850] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[484.867996] [078/133] (540s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[484.977140] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[484.981091] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[485.060540] [079/133] (540s left) kms_atomic (atomic-invalid-params)
[485.150855] Starting subtest: atomic-invalid-params
[485.152712] Starting dynamic subtest: pipe-A-eDP-1
[485.543313] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.390s)
[485.545181] Subtest atomic-invalid-params: SUCCESS (0.392s)
[486.362923] [080/133] (539s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[486.455803] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[486.460146] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[486.530328] [081/133] (539s left) prime_mmap (test_aperture_limit)
[486.592735] Starting subtest: test_aperture_limit
[486.594645] Starting dynamic subtest: test_aperture_limit-smem
[486.601070] Dynamic subtest test_aperture_limit-smem: SKIP (0.009s)
[486.605296] Subtest test_aperture_limit: SKIP (0.009s)
[486.675302] [082/133] (539s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[486.757392] Starting subtest: map-fixed-invalidate-overlap-busy
[486.761246] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[486.880007] [083/133] (539s left) kms_psr (fbc-psr2-cursor-plane-move)
[486.975664] Starting subtest: fbc-psr2-cursor-plane-move
[486.977542] Starting dynamic subtest: eDP-1
[486.981479] Dynamic subtest eDP-1: SKIP (0.000s)
[486.985396] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[487.051384] [084/133] (539s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[487.142623] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[487.144898] Starting dynamic subtest: pipe-A-eDP-1
[487.599761] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.435s)
[487.611122] Starting dynamic subtest: pipe-B-eDP-1
[489.052093] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.445s)
[489.064285] Starting dynamic subtest: pipe-C-eDP-1
[490.473288] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.420s)
[490.485409] Starting dynamic subtest: pipe-D-eDP-1
[491.831329] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.358s)
[491.833526] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.663s)
[492.991247] [085/133] (533s left) gem_exec_schedule (submit-early-slice)
[493.066544] Starting subtest: submit-early-slice
[493.068411] Starting dynamic subtest: rcs0
[493.104365] Dynamic subtest rcs0: SUCCESS (0.045s)
[493.116987] Starting dynamic subtest: bcs0
[493.149561] Dynamic subtest bcs0: SUCCESS (0.045s)
[493.160530] Starting dynamic subtest: vcs0
[493.193960] Dynamic subtest vcs0: SUCCESS (0.043s)
[493.204883] Starting dynamic subtest: vcs1
[493.237301] Dynamic subtest vcs1: SUCCESS (0.043s)
[493.250197] Starting dynamic subtest: vecs0
[493.278232] Dynamic subtest vecs0: SUCCESS (0.040s)
[493.293801] Starting dynamic subtest: ccs0
[493.324536] Dynamic subtest ccs0: SUCCESS (0.045s)
[493.326916] Subtest submit-early-slice: SUCCESS (0.265s)
[495.238694] [086/133] (530s left) gem_exec_fence (syncobj-unused-fence)
[495.688961] Starting subtest: syncobj-unused-fence
[495.695357] Subtest syncobj-unused-fence: SUCCESS (0.007s)
[497.190982] [087/133] (529s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[497.310919] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[501.313164] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.004s)
[502.504723] [088/133] (523s left) gem_wait (write-wait)
[502.573917] Starting subtest: write-wait
[502.576334] Starting dynamic subtest: all-engines
[503.677082] Dynamic subtest all-engines: SUCCESS (1.102s)
[503.696561] Starting dynamic subtest: rcs0
[504.732045] Dynamic subtest rcs0: SUCCESS (1.052s)
[504.750600] Starting dynamic subtest: bcs0
[505.789007] Dynamic subtest bcs0: SUCCESS (1.054s)
[505.807149] Starting dynamic subtest: vcs0
[506.842242] Dynamic subtest vcs0: SUCCESS (1.051s)
[506.859564] Starting dynamic subtest: vcs1
[507.894877] Dynamic subtest vcs1: SUCCESS (1.051s)
[507.912225] Starting dynamic subtest: vecs0
[508.947858] Dynamic subtest vecs0: SUCCESS (1.051s)
[508.965480] Starting dynamic subtest: ccs0
[510.006631] Dynamic subtest ccs0: SUCCESS (1.057s)
[510.009115] Subtest write-wait: SUCCESS (7.433s)
[510.203808] [089/133] (516s left) kms_getfb (getfb-handle-valid)
[510.316791] Starting subtest: getfb-handle-valid
[510.319141] Subtest getfb-handle-valid: SUCCESS (0.000s)
[510.419895] [090/133] (515s left) kms_psr (pr-cursor-mmap-gtt)
[510.515705] Starting subtest: pr-cursor-mmap-gtt
[510.519688] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[510.593889] [091/133] (515s left) syncobj_wait (wait-for-submit-complex)
[510.661315] Starting subtest: wait-for-submit-complex
[510.723374] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[510.813896] [092/133] (515s left) gem_basic (multigpu-create-close)
[510.871745] Starting subtest: multigpu-create-close
[510.875914] Subtest multigpu-create-close: SKIP (0.000s)
[510.967372] [093/133] (515s left) kms_pm_rpm (pc8-residency)
[513.220327] Starting subtest: pc8-residency
[513.224841] Subtest pc8-residency: SKIP (0.000s)
[513.609625] [094/133] (512s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[513.719723] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[517.281228] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.564s)
[518.418545] [095/133] (508s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[518.526831] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[519.273062] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.748s)
[519.415839] [096/133] (507s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[519.516076] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[519.520451] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[519.615900] [097/133] (507s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[519.709595] Starting subtest: compare-crc-sanitycheck-xr24
[519.711885] Starting dynamic subtest: pipe-A-eDP-1
[520.347048] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.637s)
[520.357993] Starting dynamic subtest: pipe-B-eDP-1
[521.594213] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.246s)
[521.605356] Starting dynamic subtest: pipe-C-eDP-1
[522.866509] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.269s)
[522.877539] Starting dynamic subtest: pipe-D-eDP-1
[524.106862] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.242s)
[524.109156] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.399s)
[524.953252] [098/133] (501s left) gem_render_copy (yf-tiled-ccs-to-linear)
[525.020880] Starting subtest: yf-tiled-ccs-to-linear
[525.025186] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[525.202726] [099/133] (501s left) kms_pm_backlight (fade-with-dpms)
[525.297709] Starting subtest: fade-with-dpms
[527.644697] Starting dynamic subtest: eDP-1
[530.137283] Dynamic subtest eDP-1: SUCCESS (2.494s)
[530.140109] Subtest fade-with-dpms: SUCCESS (4.841s)
[530.929317] [100/133] (495s left) i915_pm_rpm (gem-pread)
[532.460201] Starting subtest: gem-pread
[534.536157] Subtest gem-pread: SUCCESS (2.079s)
[534.625794] [101/133] (492s left) i915_drm_fdinfo (virtual-busy)
[534.676480] Starting subtest: virtual-busy
[534.681026] Subtest virtual-busy: SKIP (0.000s)
[534.748174] [102/133] (492s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[534.848162] Starting subtest: pr-cursor-plane-update-sf
[534.852253] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[534.933965] [103/133] (491s left) kms_lease (cursor-implicit-plane)
[535.028740] Starting subtest: cursor-implicit-plane
[535.030991] Starting dynamic subtest: pipe-A-eDP-1
[535.487819] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.458s)
[535.498549] Starting dynamic subtest: pipe-B-eDP-1
[536.623806] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.135s)
[536.633982] Starting dynamic subtest: pipe-C-eDP-1
[537.732051] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.109s)
[537.743136] Starting dynamic subtest: pipe-D-eDP-1
[538.865390] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[538.867975] Subtest cursor-implicit-plane: SUCCESS (3.839s)
[539.726232] [104/133] (487s left) syncobj_timeline (wait-any-complex)
[539.783148] Starting subtest: wait-any-complex
[539.844601] Subtest wait-any-complex: SUCCESS (0.063s)
[539.937764] [105/133] (487s left) gem_ctx_persistence (legacy-engines-mixed-process)
[540.008089] Starting subtest: legacy-engines-mixed-process
[540.010539] Starting dynamic subtest: render
[542.126297] Dynamic subtest render: SUCCESS (2.118s)
[542.138413] Starting dynamic subtest: bsd1
[544.107160] Dynamic subtest bsd1: SUCCESS (1.979s)
[544.119297] Starting dynamic subtest: bsd2
[546.155398] Dynamic subtest bsd2: SUCCESS (2.046s)
[546.167660] Starting dynamic subtest: blt
[548.133582] Dynamic subtest blt: SUCCESS (1.977s)
[548.147374] Starting dynamic subtest: vebox
[550.141349] Dynamic subtest vebox: SUCCESS (2.006s)
[550.143716] Subtest legacy-engines-mixed-process: SUCCESS (10.134s)
[550.239559] [106/133] (476s left) kms_flip (plain-flip-ts-check)
[550.344451] Starting subtest: plain-flip-ts-check
[550.346740] Starting dynamic subtest: A-eDP1
[559.213167] Dynamic subtest A-eDP1: SUCCESS (8.870s)
[559.230383] Starting dynamic subtest: B-eDP1
[568.034977] Dynamic subtest B-eDP1: SUCCESS (8.819s)
[568.048879] Starting dynamic subtest: C-eDP1
[576.850046] Dynamic subtest C-eDP1: SUCCESS (8.812s)
[576.861651] Starting dynamic subtest: D-eDP1
[585.683996] Dynamic subtest D-eDP1: SUCCESS (8.832s)
[585.688731] Subtest plain-flip-ts-check: SUCCESS (35.345s)
[586.877078] [107/133] (440s left) kms_invalid_mode (bad-vsync-start)
[586.974105] Starting subtest: bad-vsync-start
[586.976329] Starting dynamic subtest: pipe-A-eDP-1
[587.262640] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[587.273392] Starting dynamic subtest: pipe-B-eDP-1
[587.275693] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[587.285282] Starting dynamic subtest: pipe-C-eDP-1
[587.287265] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[587.297042] Starting dynamic subtest: pipe-D-eDP-1
[587.299241] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[587.301491] Subtest bad-vsync-start: SUCCESS (0.293s)
[588.102739] [108/133] (439s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[588.221624] Starting subtest: basic-flip-before-cursor-varying-size
[589.146898] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.926s)
[589.947469] [109/133] (437s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[590.070880] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[599.149554] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.078s)
[599.984046] [110/133] (427s left) gem_softpin (overlap)
[600.067265] Starting subtest: overlap
[601.034889] Subtest overlap: SUCCESS (0.966s)
[601.141321] [111/133] (426s left) kms_psr (psr2-sprite-mmap-gtt)
[601.236339] Starting subtest: psr2-sprite-mmap-gtt
[601.238530] Starting dynamic subtest: eDP-1
[602.664304] Dynamic subtest eDP-1: SKIP (1.424s)
[602.668931] Subtest psr2-sprite-mmap-gtt: SKIP (1.426s)
[602.818495] [112/133] (424s left) kms_frontbuffer_tracking (psr-suspend)
[602.932099] Starting subtest: psr-suspend
[641.914211] Subtest psr-suspend: SUCCESS (8.763s)
[649.059242] [113/133] (385s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[649.176251] Starting subtest: psr2-cursor-plane-update-sf
[649.178687] Starting dynamic subtest: pipe-A-eDP-1
[649.746627] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[649.757553] Starting dynamic subtest: pipe-B-eDP-1
[651.001657] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.251s)
[651.003827] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.828s)
[651.886143] [114/133] (382s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[651.991732] Starting subtest: y-tiled-16bpp-rotate-270
[651.996335] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[652.082270] [115/133] (382s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[652.136877] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[652.243634] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[652.331247] [116/133] (382s left) kms_flip (modeset-vs-vblank-race)
[652.423320] Starting subtest: modeset-vs-vblank-race
[652.425480] Starting dynamic subtest: A-eDP1
[657.122097] Dynamic subtest A-eDP1: SUCCESS (4.693s)
[657.138370] Starting dynamic subtest: B-eDP1
[661.852350] Dynamic subtest B-eDP1: SUCCESS (4.723s)
[661.868380] Starting dynamic subtest: C-eDP1
[666.470843] Dynamic subtest C-eDP1: SUCCESS (4.613s)
[666.485093] Starting dynamic subtest: D-eDP1
[671.136700] Dynamic subtest D-eDP1: SUCCESS (4.660s)
[671.139387] Subtest modeset-vs-vblank-race: SUCCESS (18.702s)
[672.298788] [117/133] (362s left) gem_exec_schedule (smoketest-all)
[672.385796] Starting subtest: smoketest-all
[704.775045] Subtest smoketest-all: SUCCESS (32.336s)
[706.397012] [118/133] (328s left) gem_mmap_gtt (basic-small-copy-odd)
[706.460746] Subtest basic-small-copy-odd: SKIP (0.000s)
[706.520020] [119/133] (328s left) gen9_exec_parse (bb-start-cmd)
[706.575887] Subtest bb-start-cmd: SKIP (0.000s)
[706.662979] [120/133] (328s left) gem_mmap_gtt (basic)
[706.725309] Subtest basic: SKIP (0.000s)
[706.789389] [121/133] (327s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[706.840983] Starting subtest: etime-single-wait-available-unsubmitted
[706.947989] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.107s)
[707.024185] [122/133] (327s left) gem_exec_alignment (forked)
[707.079529] Starting subtest: forked
[744.395925] Subtest forked: SUCCESS (37.303s)
[745.455691] [123/133] (289s left) kms_getfb (getfb2-handle-zero)
[745.600667] Starting subtest: getfb2-handle-zero
[745.602927] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[745.701290] [124/133] (289s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[745.799166] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[745.801479] Starting dynamic subtest: pipe-A
[745.822398] Dynamic subtest pipe-A: SKIP (0.019s)
[745.833491] Starting dynamic subtest: pipe-B
[745.857478] Dynamic subtest pipe-B: SKIP (0.033s)
[745.867986] Starting dynamic subtest: pipe-C
[745.891223] Dynamic subtest pipe-C: SKIP (0.033s)
[745.901369] Starting dynamic subtest: pipe-D
[745.923472] Dynamic subtest pipe-D: SKIP (0.033s)
[745.927827] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.122s)
[746.025012] [125/133] (288s left) kms_content_protection (uevent)
[746.183202] Starting subtest: uevent
[746.187751] Subtest uevent: SKIP (0.000s)
[746.300300] [126/133] (288s left) kms_plane_multiple (2x-tiling-x)
[746.388376] Starting subtest: 2x-tiling-x
[746.391783] Subtest 2x-tiling-x: SKIP (0.000s)
[746.473846] [127/133] (288s left) gem_exec_schedule (preempt-contexts)
[746.544786] Starting subtest: preempt-contexts
[746.546692] Starting dynamic subtest: rcs0
[746.606160] Dynamic subtest rcs0: SUCCESS (0.065s)
[746.616557] Starting dynamic subtest: bcs0
[746.656925] Dynamic subtest bcs0: SUCCESS (0.051s)
[746.667065] Starting dynamic subtest: vcs0
[746.709944] Dynamic subtest vcs0: SUCCESS (0.052s)
[746.719925] Starting dynamic subtest: vcs1
[746.755856] Dynamic subtest vcs1: SUCCESS (0.046s)
[746.766906] Starting dynamic subtest: vecs0
[746.803843] Dynamic subtest vecs0: SUCCESS (0.047s)
[746.814047] Starting dynamic subtest: ccs0
[746.852041] Dynamic subtest ccs0: SUCCESS (0.047s)
[746.853910] Subtest preempt-contexts: SUCCESS (0.313s)
[748.429553] [128/133] (286s left) perf_pmu (most-busy-check-all)
[748.479518] Starting subtest: most-busy-check-all
[748.481847] Starting dynamic subtest: rcs0
[750.328254] Dynamic subtest rcs0: SUCCESS (1.848s)
[750.341849] Starting dynamic subtest: bcs0
[752.376882] Dynamic subtest bcs0: SUCCESS (2.047s)
[752.390558] Starting dynamic subtest: vcs0
[754.361722] Dynamic subtest vcs0: SUCCESS (1.982s)
[754.374870] Starting dynamic subtest: vcs1
[756.343855] Dynamic subtest vcs1: SUCCESS (1.982s)
[756.358311] Starting dynamic subtest: vecs0
[758.327529] Dynamic subtest vecs0: SUCCESS (1.981s)
[758.340381] Starting dynamic subtest: ccs0
[760.376725] Dynamic subtest ccs0: SUCCESS (2.047s)
[760.379651] Subtest most-busy-check-all: SUCCESS (11.905s)
[760.479611] [129/133] (274s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[760.592328] Starting subtest: multiplane-rotation-cropping-top
[777.967872] Subtest multiplane-rotation-cropping-top: SUCCESS (17.382s)
[778.076253] [130/133] (257s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[778.185226] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[778.188850] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[778.285169] [131/133] (256s left) syncobj_wait (signal)
[778.348875] Starting subtest: signal
[778.351163] Subtest signal: SUCCESS (0.000s)
[778.454684] [132/133] (256s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[778.550698] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[778.554243] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[778.627875] [133/133] (256s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[778.731125] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[785.523978] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.796s)
[785.638748] 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.