[41.308484] Initializing watchdogs
[41.308620]   /dev/watchdog0
[41.322150] [001/144] (960s left) kms_psr (pr-cursor-mmap-cpu)
[41.429513] Starting subtest: pr-cursor-mmap-cpu
[41.434419] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[41.552570] [002/144] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[41.666608] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[41.671201] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[41.739395] [003/144] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[41.808522] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[46.473141] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (4.669s)
[46.616422] [004/144] (955s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt)
[46.719302] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-gtt
[46.724801] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[46.792617] [005/144] (955s left) gem_exec_fence (syncobj-export)
[47.318047] Starting subtest: syncobj-export
[47.330728] Subtest syncobj-export: SUCCESS (0.012s)
[48.491304] [006/144] (953s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[48.599180] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[52.560119] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.965s)
[52.668378] [007/144] (949s left) kms_hdmi_inject (inject-4k)
[53.017704] Starting subtest: inject-4k
[53.311892] Subtest inject-4k: SUCCESS (0.293s)
[53.920222] [008/144] (948s left) kms_psr (psr-no-drrs)
[53.998608] Starting subtest: psr-no-drrs
[54.001056] Starting dynamic subtest: eDP-1
[55.717116] Dynamic subtest eDP-1: SUCCESS (1.718s)
[55.720042] Subtest psr-no-drrs: SUCCESS (1.719s)
[56.624534] [009/144] (945s left) kms_ccs (pipe-b-bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[57.025348] Starting subtest: pipe-B-bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[57.031103] Subtest pipe-B-bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[57.904673] [010/144] (944s left) vc4/vc4_tiling (set-bad-modifier)
[58.016641] Subtest set-bad-modifier: SKIP (0.000s)
[58.067605] [011/144] (944s left) kms_color (ctm-negative)
[58.144467] Starting subtest: ctm-negative
[58.146986] Starting dynamic subtest: pipe-A
[58.910350] Dynamic subtest pipe-A: SUCCESS (0.767s)
[58.925566] Starting dynamic subtest: pipe-B
[60.428233] Dynamic subtest pipe-B: SUCCESS (1.517s)
[60.438022] Starting dynamic subtest: pipe-C
[61.905518] Dynamic subtest pipe-C: SUCCESS (1.478s)
[61.920350] Starting dynamic subtest: pipe-D
[63.375494] Dynamic subtest pipe-D: SUCCESS (1.470s)
[63.378232] Subtest ctm-negative: SUCCESS (5.232s)
[64.296935] [012/144] (937s left) kms_ccs (pipe-b-crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[64.696044] Starting subtest: pipe-B-crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[67.220026] Subtest pipe-B-crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (2.525s)
[68.401067] [013/144] (933s left) kms_addfb_basic (too-wide)
[68.491943] Starting subtest: too-wide
[68.494448] Subtest too-wide: SUCCESS (0.000s)
[68.566871] [014/144] (933s left) kms_flip (nonexisting-fb)
[68.674589] Starting subtest: nonexisting-fb
[68.679733] Starting dynamic subtest: A-eDP1
[69.999671] Dynamic subtest A-eDP1: SUCCESS (1.320s)
[70.013929] Starting dynamic subtest: B-eDP1
[71.282031] Dynamic subtest B-eDP1: SUCCESS (1.281s)
[71.297689] Starting dynamic subtest: C-eDP1
[72.538417] Dynamic subtest C-eDP1: SUCCESS (1.256s)
[72.553156] Starting dynamic subtest: D-eDP1
[73.819709] Dynamic subtest D-eDP1: SUCCESS (1.279s)
[73.831301] Subtest nonexisting-fb: SUCCESS (5.158s)
[75.074899] [015/144] (927s left) kms_frontbuffer_tracking (fbc-tiling-y)
[75.182785] Starting subtest: fbc-tiling-y
[75.187221] Subtest fbc-tiling-y: SKIP (0.000s)
[75.267639] [016/144] (926s left) kms_pm_backlight (bad-brightness)
[75.342052] Starting subtest: bad-brightness
[75.413770] Starting dynamic subtest: eDP-1
[75.731694] Dynamic subtest eDP-1: SUCCESS (0.311s)
[75.733865] Subtest bad-brightness: SUCCESS (0.383s)
[76.672884] [017/144] (925s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-rc-ccs)
[77.056475] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs
[77.061787] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s)
[77.927241] [018/144] (924s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[77.994812] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[78.009265] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.013s)
[78.069755] [019/144] (924s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[78.164073] Starting subtest: read-crc-frame-sequence
[78.166331] Starting dynamic subtest: pipe-A-eDP-1
[78.933216] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.765s)
[78.948655] Starting dynamic subtest: pipe-B-eDP-1
[80.470561] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.535s)
[80.485723] Starting dynamic subtest: pipe-C-eDP-1
[81.915717] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.442s)
[81.930790] Starting dynamic subtest: pipe-D-eDP-1
[83.343845] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.426s)
[83.346424] Subtest read-crc-frame-sequence: SUCCESS (5.180s)
[84.263798] [020/144] (918s left) fbdev (info)
[84.299140] Starting subtest: info
[84.302141] Subtest info: SUCCESS (0.000s)
[84.372979] [021/144] (917s left) gem_exec_capture (capture)
[84.450366] Starting subtest: capture
[84.453175] Starting dynamic subtest: rcs0-smem
[85.287329] Dynamic subtest rcs0-smem: SUCCESS (0.837s)
[85.301137] Starting dynamic subtest: bcs0-smem
[86.129413] Dynamic subtest bcs0-smem: SUCCESS (0.841s)
[86.143137] Starting dynamic subtest: vcs0-smem
[86.955897] Dynamic subtest vcs0-smem: SUCCESS (0.824s)
[86.969691] Starting dynamic subtest: vcs1-smem
[88.292601] Dynamic subtest vcs1-smem: SUCCESS (1.341s)
[88.307103] Starting dynamic subtest: vecs0-smem
[89.117235] Dynamic subtest vecs0-smem: SUCCESS (0.824s)
[89.131216] Starting dynamic subtest: ccs0-smem
[89.953269] Dynamic subtest ccs0-smem: SUCCESS (0.834s)
[89.955590] Subtest capture: SUCCESS (5.503s)
[90.041396] [022/144] (912s left) kms_cursor_crc (cursor-sliding-256x256)
[90.242599] Starting subtest: cursor-sliding-256x256
[90.245139] Starting dynamic subtest: pipe-A-eDP-1
[94.035396] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.788s)
[94.061268] Starting dynamic subtest: pipe-D-eDP-1
[98.577938] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.521s)
[98.581231] Subtest cursor-sliding-256x256: SUCCESS (8.333s)
[99.457319] [023/144] (902s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[99.536984] Starting subtest: fbcpsr-slowdraw
[122.708469] Subtest fbcpsr-slowdraw: SUCCESS (23.175s)
[122.837505] [024/144] (879s left) gem_pread (exhaustion)
[122.901618] Subtest exhaustion: SKIP (0.000s)
[122.938763] [025/144] (879s left) kms_ccs (pipe-a-missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[123.305695] Starting subtest: pipe-A-missing-ccs-buffer-y-tiled-gen12-rc-ccs
[123.310749] Subtest pipe-A-missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[124.211477] [026/144] (878s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[124.304789] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[124.309098] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[124.392485] [027/144] (878s left) perf_pmu (rc6-suspend)
[124.442587] Starting subtest: rc6-suspend
[146.986660] Subtest rc6-suspend: SUCCESS (7.550s)
[147.755180] [028/144] (869s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[147.833608] Starting subtest: invalid-multi-wait-all-unsubmitted
[147.836316] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[147.926333] [029/144] (869s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[148.032094] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[148.036411] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[148.120386] [030/144] (869s left) kms_ccs (pipe-c-missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[148.538900] Starting subtest: pipe-C-missing-ccs-buffer-y-tiled-gen12-mc-ccs
[148.545079] Subtest pipe-C-missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[149.380094] [031/144] (868s left) gem_exec_reloc (basic-concurrent0)
[149.459124] Subtest basic-concurrent0: SKIP (0.000s)
[149.526201] [032/144] (868s left) kms_dsc (dsc-with-bpc-formats)
[149.606121] Starting subtest: dsc-with-bpc-formats
[149.611032] Subtest dsc-with-bpc-formats: SKIP (0.002s)
[149.682278] [033/144] (868s left) gen9_exec_parse (basic-rejected)
[149.753361] Subtest basic-rejected: SKIP (0.000s)
[149.791764] [034/144] (867s left) gem_eio (in-flight-external)
[150.034913] Starting subtest: in-flight-external
[151.521029] Subtest in-flight-external: SUCCESS (1.486s)
[151.627373] [035/144] (866s left) kms_pm_rpm (dpms-lpsp)
[153.577646] Starting subtest: dpms-lpsp
[153.897794] Subtest dpms-lpsp: SUCCESS (0.321s)
[153.982967] [036/144] (863s left) gem_lmem_swapping (massive-random)
[154.037366] Subtest massive-random: SKIP (0.000s)
[154.082096] [037/144] (863s left) gem_watchdog (default-virtual)
[154.167788] Starting subtest: default-virtual
[160.455686] Subtest default-virtual: SUCCESS (6.292s)
[160.540813] [038/144] (857s left) gem_workarounds (suspend-resume-context)
[160.660580] Starting subtest: suspend-resume-context
[176.785752] Subtest suspend-resume-context: SUCCESS (1.160s)
[176.899736] [039/144] (855s left) gem_exec_balancer (bonded-false-hang)
[177.161266] Starting subtest: bonded-false-hang
[177.166678] Subtest bonded-false-hang: SKIP (0.000s)
[177.237622] [040/144] (855s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[177.308303] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[177.311052] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[177.392681] [041/144] (855s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[177.484884] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[177.490359] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[177.564163] [042/144] (855s left) gem_exec_balancer (hang)
[177.828965] Starting subtest: hang
[179.375536] Subtest hang: SUCCESS (1.547s)
[179.483209] [043/144] (853s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[179.575210] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[179.579629] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[179.672729] [044/144] (853s left) kms_ccs (pipe-b-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[180.088559] Starting subtest: pipe-B-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[180.093415] Subtest pipe-B-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[180.946333] [045/144] (852s left) gem_mmap_gtt (basic-write-read-distinct)
[181.008912] Subtest basic-write-read-distinct: SKIP (0.000s)
[181.062761] [046/144] (852s left) i915_suspend (debugfs-reader)
[181.139031] Starting subtest: debugfs-reader
[198.789681] Subtest debugfs-reader: SUCCESS (3.166s)
[198.891214] [047/144] (848s left) gem_huc_copy (huc-copy)
[198.975847] Starting subtest: huc-copy
[198.983412] Subtest huc-copy: SUCCESS (0.008s)
[199.083893] [048/144] (848s left) gem_exec_schedule (implicit-read-write)
[199.179118] Starting subtest: implicit-read-write
[199.181201] Starting dynamic subtest: rcs0
[199.322202] Dynamic subtest rcs0: SUCCESS (0.152s)
[199.336254] Starting dynamic subtest: vcs0
[199.440860] Dynamic subtest vcs0: SUCCESS (0.118s)
[199.461956] Starting dynamic subtest: vcs1
[199.541661] Dynamic subtest vcs1: SUCCESS (0.099s)
[199.561546] Starting dynamic subtest: vecs0
[199.619306] Dynamic subtest vecs0: SUCCESS (0.077s)
[199.639630] Starting dynamic subtest: ccs0
[199.701019] Dynamic subtest ccs0: SUCCESS (0.081s)
[199.703823] Subtest implicit-read-write: SUCCESS (0.531s)
[201.018272] [049/144] (846s left) kms_cursor_crc (cursor-offscreen-64x21)
[201.210167] Starting subtest: cursor-offscreen-64x21
[201.242514] Subtest cursor-offscreen-64x21: SKIP (0.027s)
[201.468622] [050/144] (846s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[201.577526] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[201.582686] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[201.667971] [051/144] (846s left) kms_selftest (drm_damage_helper)
[201.730625] Starting subtest: drm_damage_helper
[201.887550] Starting dynamic subtest: drm_test_damage_iter_no_damage
[202.053462] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.167s)
[202.073758] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[202.076538] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.000s)
[202.089469] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[202.091992] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.000s)
[202.101269] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[202.104029] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.000s)
[202.112621] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[202.115333] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.000s)
[202.123571] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[202.126231] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.000s)
[202.134141] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[202.136807] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.000s)
[202.144073] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[202.146193] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.000s)
[202.152904] Starting dynamic subtest: drm_test_damage_iter_single_damage
[202.155079] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.000s)
[202.166304] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[202.168367] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.000s)
[202.175078] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[202.177095] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.000s)
[202.183945] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[202.186109] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.000s)
[202.192837] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[202.195046] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.000s)
[202.201584] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[202.203646] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.000s)
[202.218295] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[202.221265] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.000s)
[202.238112] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[202.240500] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.000s)
[202.251138] Starting dynamic subtest: drm_test_damage_iter_damage
[202.253341] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.000s)
[202.261804] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[202.264124] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.000s)
[202.271753] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[202.273992] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.000s)
[202.280980] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[202.283071] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.001s)
[202.289613] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[202.291755] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.000s)
[202.293858] Subtest drm_damage_helper: SUCCESS (0.331s)
[202.310679] [052/144] (845s left) syncobj_wait (reset-during-wait-for-submit)
[202.380353] Starting subtest: reset-during-wait-for-submit
[202.485125] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[202.563650] [053/144] (845s left) kms_busy (extended-modeset-hang-newfb)
[202.626918] Starting subtest: extended-modeset-hang-newfb
[202.630125] Starting dynamic subtest: pipe-A
[225.995281] Dynamic subtest pipe-A: SUCCESS (23.364s)
[226.019547] Starting dynamic subtest: pipe-D
[249.100506] Dynamic subtest pipe-D: SUCCESS (23.100s)
[249.103240] Subtest extended-modeset-hang-newfb: SUCCESS (46.473s)
[250.000793] [054/144] (797s left) kms_psr (psr2-cursor-render)
[250.086481] Starting subtest: psr2-cursor-render
[250.089353] Starting dynamic subtest: eDP-1
[251.906543] Dynamic subtest eDP-1: SUCCESS (1.817s)
[251.909370] Subtest psr2-cursor-render: SUCCESS (1.819s)
[252.818958] [055/144] (795s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[252.939947] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[252.942469] Starting dynamic subtest: pipe-A-default-mode
[254.162040] Dynamic subtest pipe-A-default-mode: SKIP (1.215s)
[254.167262] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.219s)
[254.323807] [056/144] (793s left) perf_pmu (busy-no-semaphores)
[254.393271] Starting subtest: busy-no-semaphores
[254.395788] Starting dynamic subtest: rcs0
[254.946211] Dynamic subtest rcs0: SUCCESS (0.551s)
[254.966669] Starting dynamic subtest: bcs0
[255.501056] Dynamic subtest bcs0: SUCCESS (0.553s)
[255.520995] Starting dynamic subtest: vcs0
[256.056895] Dynamic subtest vcs0: SUCCESS (0.553s)
[256.076844] Starting dynamic subtest: vcs1
[257.433197] Dynamic subtest vcs1: SUCCESS (1.373s)
[257.454488] Starting dynamic subtest: vecs0
[259.416649] Dynamic subtest vecs0: SUCCESS (1.982s)
[259.437152] Starting dynamic subtest: ccs0
[261.403978] Dynamic subtest ccs0: SUCCESS (1.985s)
[261.407037] Subtest busy-no-semaphores: SUCCESS (7.010s)
[261.526657] [057/144] (786s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[261.599347] Starting subtest: wait-all-for-submit-delayed-submit
[261.699600] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[261.785782] [058/144] (786s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[261.876432] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[264.646689] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (2.774s)
[264.775908] [059/144] (783s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[265.152561] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[266.038558] [060/144] (782s left) kms_flip (2x-plain-flip-ts-check)
[266.125440] Starting subtest: 2x-plain-flip-ts-check
[266.137978] Subtest 2x-plain-flip-ts-check: SKIP (0.014s)
[266.219867] [061/144] (781s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[266.341078] Starting subtest: y-tiled-64bpp-rotate-180
[266.345830] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[266.436795] [062/144] (781s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[266.535334] Starting subtest: x-tiled-16bpp-rotate-180
[267.955971] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.423s)
[268.084134] [063/144] (780s left) syncobj_timeline (invalid-signal-illegal-handle)
[268.159909] Starting subtest: invalid-signal-illegal-handle
[268.162242] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[268.241483] [064/144] (779s left) kms_frontbuffer_tracking (fbc-2p-rte)
[268.331755] Starting subtest: fbc-2p-rte
[268.335786] Subtest fbc-2p-rte: SKIP (0.000s)
[268.414613] [065/144] (779s left) vgem_basic (dmabuf-fence-before)
[268.484119] Starting subtest: dmabuf-fence-before
[268.486301] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[268.541657] [066/144] (779s left) core_auth (getclient-simple)
[268.593904] Starting subtest: getclient-simple
[268.602420] Subtest getclient-simple: SUCCESS (0.011s)
[268.687954] [067/144] (779s left) syncobj_timeline (invalid-query-zero-handles)
[268.730041] Starting subtest: invalid-query-zero-handles
[268.731976] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[268.796036] [068/144] (779s left) gem_madvise (dontneed-after-mmap)
[268.825987] Starting subtest: dontneed-after-mmap
[268.835724] Subtest dontneed-after-mmap: SUCCESS (0.010s)
[268.930425] [069/144] (779s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[269.034373] Starting subtest: cursorA-vs-flipB-varying-size
[269.038782] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[269.169465] [070/144] (779s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[269.272979] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[269.276751] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[269.362217] [071/144] (779s left) kms_chamelium_frames (hdmi-frame-dump)
[269.762416] Subtest hdmi-frame-dump: SKIP (0.000s)
[270.641153] [072/144] (777s left) kms_psr (fbc-psr2-primary-blt)
[270.736319] Starting subtest: fbc-psr2-primary-blt
[270.739157] Starting dynamic subtest: eDP-1
[270.744512] Dynamic subtest eDP-1: SKIP (0.000s)
[270.749429] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[270.834012] [073/144] (777s left) gem_userptr_blits (unsync-unmap-cycles)
[270.926958] Subtest unsync-unmap-cycles: SKIP (0.000s)
[271.044214] [074/144] (777s left) gem_fence_thrash (bo-write-verify-none)
[271.099949] Starting subtest: bo-write-verify-none
[271.119571] Subtest bo-write-verify-none: SKIP (0.016s)
[271.151802] [075/144] (777s left) gem_userptr_blits (sync-overlap)
[271.248525] Starting subtest: sync-overlap
[271.251361] Subtest sync-overlap: SUCCESS (0.000s)
[271.368982] [076/144] (777s left) i915_pciid
[271.521394] [077/144] (777s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[271.591137] Starting subtest: invalid-single-wait-all-unsubmitted
[271.593887] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[271.674690] [078/144] (776s left) v3d/v3d_submit_csd (job-perfmon)
[271.746846] Subtest job-perfmon: SKIP (0.000s)
[271.797555] [079/144] (776s left) kms_flip (plain-flip-ts-check-interruptible)
[271.894854] Starting subtest: plain-flip-ts-check-interruptible
[271.900316] Starting dynamic subtest: A-eDP1
[280.842220] Dynamic subtest A-eDP1: SUCCESS (8.944s)
[280.862826] Starting dynamic subtest: B-eDP1
[289.803968] Dynamic subtest B-eDP1: SUCCESS (8.960s)
[289.824851] Starting dynamic subtest: C-eDP1
[298.747927] Dynamic subtest C-eDP1: SUCCESS (8.935s)
[298.766555] Starting dynamic subtest: D-eDP1
[307.701520] Dynamic subtest D-eDP1: SUCCESS (8.957s)
[307.709101] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.814s)
[308.962413] [080/144] (739s left) gem_ctx_shared (exec-single-timeline)
[309.040075] Starting subtest: exec-single-timeline
[309.043082] Starting dynamic subtest: rcs0
[310.125167] Dynamic subtest rcs0: SUCCESS (1.083s)
[310.145518] Starting dynamic subtest: bcs0
[311.212068] Dynamic subtest bcs0: SUCCESS (1.085s)
[311.233444] Starting dynamic subtest: vcs0
[312.300520] Dynamic subtest vcs0: SUCCESS (1.087s)
[312.321434] Starting dynamic subtest: vcs1
[313.388411] Dynamic subtest vcs1: SUCCESS (1.085s)
[313.409737] Starting dynamic subtest: vecs0
[314.476589] Dynamic subtest vecs0: SUCCESS (1.086s)
[314.497896] Starting dynamic subtest: ccs0
[315.566518] Dynamic subtest ccs0: SUCCESS (1.085s)
[315.569314] Subtest exec-single-timeline: SUCCESS (6.524s)
[315.764059] [081/144] (732s left) kms_ccs (pipe-b-missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[316.160385] Starting subtest: pipe-B-missing-ccs-buffer-y-tiled-gen12-rc-ccs
[316.166879] Subtest pipe-B-missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[317.075776] [082/144] (731s left) v3d/v3d_mmap (mmap-bo)
[317.127766] Subtest mmap-bo: SKIP (0.000s)
[317.180127] [083/144] (731s left) gem_softpin (evict-active-interruptible)
[317.251804] Starting subtest: evict-active-interruptible
[317.268942] Subtest evict-active-interruptible: SUCCESS (0.016s)
[317.376421] [084/144] (731s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[317.496964] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[318.186591] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.692s)
[318.367148] [085/144] (730s left) gem_exec_params (secure-non-root)
[318.446624] Starting subtest: secure-non-root
[318.451515] Subtest secure-non-root: SKIP (0.000s)
[318.548084] [086/144] (730s left) gem_mmap_gtt (medium-copy-odd)
[318.619365] Subtest medium-copy-odd: SKIP (0.000s)
[318.672040] [087/144] (730s left) kms_lease (page-flip-implicit-plane)
[318.785748] Starting subtest: page-flip-implicit-plane
[318.788473] Starting dynamic subtest: pipe-A-eDP-1
[319.283162] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.496s)
[319.305636] Starting dynamic subtest: pipe-B-eDP-1
[320.496653] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.210s)
[320.518136] Starting dynamic subtest: pipe-C-eDP-1
[321.747746] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.245s)
[321.768876] Starting dynamic subtest: pipe-D-eDP-1
[322.937503] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.187s)
[322.940551] Subtest page-flip-implicit-plane: SUCCESS (4.155s)
[323.856922] [088/144] (725s left) gem_mmap_wc (copy)
[323.946291] Subtest copy: SKIP (0.000s)
[324.003442] [089/144] (724s left) kms_cdclk (mode-transition)
[324.097448] Starting subtest: mode-transition
[324.099961] Starting dynamic subtest: pipe-A-eDP-1
[324.392000] Dynamic subtest pipe-A-eDP-1: SKIP (0.291s)
[324.409848] Starting dynamic subtest: pipe-B-eDP-1
[324.415521] Dynamic subtest pipe-B-eDP-1: SKIP (0.003s)
[324.432534] Starting dynamic subtest: pipe-C-eDP-1
[324.437568] Dynamic subtest pipe-C-eDP-1: SKIP (0.002s)
[324.445612] Starting dynamic subtest: pipe-D-eDP-1
[324.450444] Dynamic subtest pipe-D-eDP-1: SKIP (0.002s)
[324.455385] Subtest mode-transition: SKIP (0.304s)
[325.331542] [090/144] (723s left) kms_plane_scaling (planes-downscale-factor-0-5)
[325.454672] Starting subtest: planes-downscale-factor-0-5
[325.457291] Starting dynamic subtest: pipe-A-eDP-1
[325.469571] Dynamic subtest pipe-A-eDP-1: SKIP (0.009s)
[325.487395] Starting dynamic subtest: pipe-B-eDP-1
[325.492306] Dynamic subtest pipe-B-eDP-1: SKIP (0.013s)
[325.505456] Starting dynamic subtest: pipe-C-eDP-1
[325.510626] Dynamic subtest pipe-C-eDP-1: SKIP (0.013s)
[325.520943] Starting dynamic subtest: pipe-D-eDP-1
[325.525370] Dynamic subtest pipe-D-eDP-1: SKIP (0.013s)
[325.530077] Subtest planes-downscale-factor-0-5: SKIP (0.055s)
[325.577191] [091/144] (723s left) kms_vblank (ts-continuation-modeset-hang)
[325.694404] Starting subtest: ts-continuation-modeset-hang
[325.696912] Starting dynamic subtest: pipe-A-eDP-1
[339.815072] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.118s)
[339.831020] Starting dynamic subtest: pipe-D-eDP-1
[354.796272] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.974s)
[354.799588] Subtest ts-continuation-modeset-hang: SUCCESS (29.103s)
[355.731630] [092/144] (693s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[355.829903] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[355.837310] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[355.897656] [093/144] (693s left) gem_exec_params (rel-constants-invalid)
[355.960858] Starting subtest: rel-constants-invalid
[355.964045] Subtest rel-constants-invalid: SUCCESS (0.000s)
[356.038930] [094/144] (693s left) sysfs_timeslice_duration (timeout)
[356.110149] Starting subtest: timeout
[356.112635] Starting dynamic subtest: vecs0
[365.891963] Dynamic subtest vecs0: SUCCESS (9.777s)
[365.912869] Starting dynamic subtest: ccs0
[366.478341] Dynamic subtest ccs0: SUCCESS (0.587s)
[366.498340] Starting dynamic subtest: vcs0
[375.878043] Dynamic subtest vcs0: SUCCESS (9.395s)
[375.901297] Starting dynamic subtest: bcs0
[376.471484] Dynamic subtest bcs0: SUCCESS (0.595s)
[376.492469] Starting dynamic subtest: rcs0
[377.030008] Dynamic subtest rcs0: SUCCESS (0.556s)
[377.048647] Starting dynamic subtest: vcs1
[386.881548] Dynamic subtest vcs1: SUCCESS (9.849s)
[386.884457] Subtest timeout: SUCCESS (30.768s)
[386.960073] [095/144] (662s left) drm_fdinfo (busy-hang)
[387.038380] Starting subtest: busy-hang
[387.040884] Starting dynamic subtest: rcs0
[387.045751] Dynamic subtest rcs0: SKIP (0.001s)
[387.067317] Starting dynamic subtest: bcs0
[387.071864] Dynamic subtest bcs0: SKIP (0.001s)
[387.086430] Starting dynamic subtest: vcs0
[387.091087] Dynamic subtest vcs0: SKIP (0.000s)
[387.101163] Starting dynamic subtest: vcs1
[387.105786] Dynamic subtest vcs1: SKIP (0.000s)
[387.114599] Starting dynamic subtest: vecs0
[387.119115] Dynamic subtest vecs0: SKIP (0.000s)
[387.127417] Starting dynamic subtest: ccs0
[387.131946] Dynamic subtest ccs0: SKIP (0.000s)
[387.136355] Subtest busy-hang: SKIP (0.005s)
[387.158142] [096/144] (662s left) gem_pxp (reject-modify-context-protection-on)
[389.766973] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[389.846844] [097/144] (659s left) gem_ccs (block-multicopy-compressed)
[389.954534] Starting subtest: block-multicopy-compressed
[389.959778] Subtest block-multicopy-compressed: SKIP (0.000s)
[390.059025] [098/144] (659s left) kms_cursor_crc (cursor-alpha-transparent)
[390.157679] Starting subtest: cursor-alpha-transparent
[390.160103] Starting dynamic subtest: pipe-A-eDP-1
[390.881507] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.724s)
[390.895928] Starting dynamic subtest: pipe-D-eDP-1
[392.329378] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.440s)
[392.332283] Subtest cursor-alpha-transparent: SUCCESS (2.169s)
[393.212587] [099/144] (656s left) api_intel_bb (blit-noreloc-keep-cache)
[393.276549] Starting subtest: blit-noreloc-keep-cache
[393.289803] Subtest blit-noreloc-keep-cache: SUCCESS (0.016s)
[393.395480] [100/144] (655s left) kms_atomic (plane-overlay-legacy)
[393.485390] Starting subtest: plane-overlay-legacy
[393.487939] Starting dynamic subtest: pipe-A-eDP-1
[393.938824] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.452s)
[393.941795] Subtest plane-overlay-legacy: SUCCESS (0.454s)
[394.821199] [101/144] (654s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[394.926513] Starting subtest: psr-rgb565-draw-mmap-wc
[399.714677] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (4.790s)
[399.827358] [102/144] (649s left) gem_exec_flush (basic-wb-rw-default)
[399.882283] Starting subtest: basic-wb-rw-default
[405.346327] Subtest basic-wb-rw-default: SUCCESS (5.468s)
[405.541546] [103/144] (643s left) vc4/vc4_create_bo (create-bo-4096)
[405.630365] Subtest create-bo-4096: SKIP (0.000s)
[405.681861] [104/144] (643s left) kms_rotation_crc (sprite-rotation-270)
[405.773508] Starting subtest: sprite-rotation-270
[405.778317] Subtest sprite-rotation-270: SKIP (0.001s)
[405.841467] [105/144] (643s left) kms_ccs (pipe-a-ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[406.235171] Starting subtest: pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs
[406.238539] Subtest pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.000s)
[407.122975] [106/144] (642s left) sysfs_heartbeat_interval (idempotent)
[407.173903] Starting subtest: idempotent
[407.176561] Starting dynamic subtest: vecs0
[407.179163] Dynamic subtest vecs0: SUCCESS (0.000s)
[407.199786] Starting dynamic subtest: ccs0
[407.202686] Dynamic subtest ccs0: SUCCESS (0.000s)
[407.215067] Starting dynamic subtest: vcs0
[407.217984] Dynamic subtest vcs0: SUCCESS (0.000s)
[407.228527] Starting dynamic subtest: bcs0
[407.231004] Dynamic subtest bcs0: SUCCESS (0.000s)
[407.239657] Starting dynamic subtest: rcs0
[407.241803] Dynamic subtest rcs0: SUCCESS (0.000s)
[407.249178] Starting dynamic subtest: vcs1
[407.251202] Dynamic subtest vcs1: SUCCESS (0.000s)
[407.253239] Subtest idempotent: SUCCESS (0.004s)
[407.270240] [107/144] (642s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[407.313582] Starting subtest: y-tiled-to-vebox-x-tiled
[407.318284] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[407.503320] [108/144] (642s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[407.595523] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[412.510831] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.920s)
[412.622207] [109/144] (636s left) kms_cursor_legacy (forked-bo)
[412.763034] Starting subtest: forked-bo
[412.765896] Starting dynamic subtest: pipe-A
[418.214481] Dynamic subtest pipe-A: SUCCESS (5.446s)
[418.237624] Starting dynamic subtest: pipe-B
[423.775560] Dynamic subtest pipe-B: SUCCESS (5.560s)
[423.797691] Starting dynamic subtest: pipe-C
[429.337396] Dynamic subtest pipe-C: SUCCESS (5.560s)
[429.358369] Starting dynamic subtest: pipe-D
[434.911330] Dynamic subtest pipe-D: SUCCESS (5.572s)
[434.934666] Starting dynamic subtest: all-pipes
[440.474684] Dynamic subtest all-pipes: SUCCESS (5.561s)
[440.477749] Subtest forked-bo: SUCCESS (27.708s)
[440.642352] [110/144] (608s left) kms_ccs (pipe-c-random-ccs-data-y-tiled-gen12-rc-ccs)
[441.048120] Starting subtest: pipe-C-random-ccs-data-y-tiled-gen12-rc-ccs
[441.056749] Subtest pipe-C-random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[441.933784] [111/144] (607s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[442.023182] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[442.028013] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[442.115410] [112/144] (607s left) gem_exec_schedule (preempt-self)
[442.214069] Starting subtest: preempt-self
[442.216819] Starting dynamic subtest: rcs0
[442.274918] Dynamic subtest rcs0: SUCCESS (0.063s)
[442.287025] Starting dynamic subtest: bcs0
[442.329492] Dynamic subtest bcs0: SUCCESS (0.053s)
[442.341659] Starting dynamic subtest: vcs0
[442.379518] Dynamic subtest vcs0: SUCCESS (0.049s)
[442.398917] Starting dynamic subtest: vcs1
[442.425214] Dynamic subtest vcs1: SUCCESS (0.045s)
[442.443412] Starting dynamic subtest: vecs0
[442.466386] Dynamic subtest vecs0: SUCCESS (0.040s)
[442.483132] Starting dynamic subtest: ccs0
[442.510396] Dynamic subtest ccs0: SUCCESS (0.043s)
[442.513330] Subtest preempt-self: SUCCESS (0.300s)
[444.016282] [113/144] (605s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[444.130915] Starting subtest: cursorA-vs-flipB-legacy
[444.136110] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[444.220435] [114/144] (605s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[444.305415] Starting subtest: primary-y-tiled-reflect-x-270
[444.309851] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[444.365373] [115/144] (605s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[444.427393] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[444.429963] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[444.523778] [116/144] (605s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[444.643557] Starting subtest: x-tiled-64bpp-rotate-270
[444.668928] Subtest x-tiled-64bpp-rotate-270: SKIP (0.027s)
[444.758991] [117/144] (605s left) syncobj_eventfd (invalid-bad-flags)
[444.833459] Starting subtest: invalid-bad-flags
[444.836282] Subtest invalid-bad-flags: SUCCESS (0.000s)
[444.918349] [118/144] (604s left) gem_exec_parallel (basic)
[445.009554] Starting subtest: basic
[445.011806] Starting dynamic subtest: rcs0
[446.491903] Dynamic subtest rcs0: SUCCESS (1.481s)
[446.501631] Starting dynamic subtest: bcs0
[448.040491] Dynamic subtest bcs0: SUCCESS (1.547s)
[448.061659] Starting dynamic subtest: vcs0
[449.933968] Dynamic subtest vcs0: SUCCESS (1.891s)
[449.956356] Starting dynamic subtest: vcs1
[451.919142] Dynamic subtest vcs1: SUCCESS (1.983s)
[451.940388] Starting dynamic subtest: vecs0
[453.870950] Dynamic subtest vecs0: SUCCESS (1.950s)
[453.892064] Starting dynamic subtest: ccs0
[456.038523] Dynamic subtest ccs0: SUCCESS (2.167s)
[456.041631] Subtest basic: SUCCESS (11.029s)
[456.238330] [119/144] (593s left) gem_ctx_persistence (heartbeat-stop)
[456.330577] Starting subtest: heartbeat-stop
[456.335909] Subtest heartbeat-stop: SKIP (0.000s)
[456.430126] [120/144] (593s left) gem_exec_store (basic)
[456.517876] Starting subtest: basic
[456.622118] Subtest basic: SUCCESS (0.106s)
[456.804555] [121/144] (593s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[456.897306] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[456.902168] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[456.979127] [122/144] (593s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[457.043791] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[457.094248] [123/144] (592s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[457.198398] Starting subtest: plane-all-modeset-transition-fencing
[457.203472] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[457.268207] [124/144] (592s left) kms_plane_cursor (primary)
[457.333972] Starting subtest: primary
[457.338927] Starting dynamic subtest: pipe-A-eDP-1-size-64
[460.537937] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (3.203s)
[460.565684] Starting dynamic subtest: pipe-A-eDP-1-size-128
[463.738350] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (3.184s)
[463.772494] Starting dynamic subtest: pipe-A-eDP-1-size-256
[467.027128] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (3.273s)
[467.368418] Starting dynamic subtest: pipe-B-eDP-1-size-64
[471.336075] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.985s)
[471.366248] Starting dynamic subtest: pipe-B-eDP-1-size-128
[474.580250] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (3.229s)
[474.616005] Starting dynamic subtest: pipe-B-eDP-1-size-256
[477.792753] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (3.195s)
[478.116694] Starting dynamic subtest: pipe-C-eDP-1-size-64
[481.681170] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.580s)
[481.717608] Starting dynamic subtest: pipe-C-eDP-1-size-128
[484.448211] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.748s)
[484.483303] Starting dynamic subtest: pipe-C-eDP-1-size-256
[487.302333] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.836s)
[487.636641] Starting dynamic subtest: pipe-D-eDP-1-size-64
[491.193561] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.575s)
[491.232840] Starting dynamic subtest: pipe-D-eDP-1-size-128
[493.862417] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.647s)
[493.890829] Starting dynamic subtest: pipe-D-eDP-1-size-256
[496.738299] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.858s)
[497.065733] Subtest primary: SUCCESS (39.729s)
[498.000622] [125/144] (552s left) gem_ctx_engines (independent)
[498.085627] Starting subtest: independent
[498.088273] Starting dynamic subtest: rcs0
[498.276838] Dynamic subtest rcs0: SUCCESS (0.190s)
[498.298355] Starting dynamic subtest: bcs0
[498.461968] Dynamic subtest bcs0: SUCCESS (0.184s)
[498.485007] Starting dynamic subtest: vcs0
[498.596868] Dynamic subtest vcs0: SUCCESS (0.134s)
[498.618560] Starting dynamic subtest: vcs1
[498.770027] Dynamic subtest vcs1: SUCCESS (0.173s)
[498.790270] Starting dynamic subtest: vecs0
[498.965911] Dynamic subtest vecs0: SUCCESS (0.196s)
[498.985109] Starting dynamic subtest: ccs0
[499.198092] Dynamic subtest ccs0: SUCCESS (0.232s)
[499.219907] Starting dynamic subtest: all-engines
[500.242348] Dynamic subtest all-engines: SUCCESS (1.043s)
[500.245058] Subtest independent: SUCCESS (2.156s)
[501.976433] [126/144] (548s left) kms_psr (fbc-pr-basic)
[502.122348] Starting subtest: fbc-pr-basic
[502.127543] Subtest fbc-pr-basic: SKIP (0.000s)
[502.253039] [127/144] (547s left) kms_ccs (pipe-d-crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[502.689392] Starting subtest: pipe-D-crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[503.713296] Subtest pipe-D-crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (1.022s)
[504.912953] [128/144] (545s left) gen3_render_tiledx_blits
[505.006027] [129/144] (545s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[505.093483] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[505.095894] Starting dynamic subtest: pipe-A-default-mode
[505.389578] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[505.394562] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.294s)
[506.321039] [130/144] (543s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[506.432336] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[506.436812] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[506.520201] [131/144] (543s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[506.597152] Starting subtest: etime-multi-wait-all-for-submit-submitted
[506.699227] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[506.778997] [132/144] (543s left) gem_softpin (full)
[506.848943] Starting subtest: full
[506.867432] Subtest full: SUCCESS (0.019s)
[506.976006] [133/144] (543s left) i915_pm_rpm (gem-idle)
[509.019675] Starting subtest: gem-idle
[517.459562] Subtest gem-idle: SUCCESS (8.441s)
[518.021232] [134/144] (532s left) kms_psr2_sf (plane-move-sf-dmg-area)
[524.084642] Starting subtest: plane-move-sf-dmg-area
[524.087451] Starting dynamic subtest: pipe-A-eDP-1
[529.554500] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.465s)
[529.576549] Starting dynamic subtest: pipe-B-eDP-1
[535.018441] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.459s)
[535.021603] Subtest plane-move-sf-dmg-area: SUCCESS (10.932s)
[535.957978] [135/144] (514s left) kms_pm_rpm (system-suspend-modeset)
[537.985578] Starting subtest: system-suspend-modeset
[561.975638] Subtest system-suspend-modeset: SUCCESS (8.989s)
[562.774987] [136/144] (502s left) kms_ccs (pipe-c-bad-pixel-format-yf-tiled-ccs)
[563.164427] Starting subtest: pipe-C-bad-pixel-format-yf-tiled-ccs
[563.170155] Subtest pipe-C-bad-pixel-format-yf-tiled-ccs: SKIP (0.000s)
[564.075945] [137/144] (501s left) kms_async_flips (alternate-sync-async-flip)
[564.163468] Starting subtest: alternate-sync-async-flip
[564.535971] Starting dynamic subtest: pipe-A-eDP-1
[567.355434] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.822s)
[567.749424] Starting dynamic subtest: pipe-B-eDP-1
[570.579547] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.843s)
[570.973371] Starting dynamic subtest: pipe-C-eDP-1
[573.844127] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.885s)
[574.234088] Starting dynamic subtest: pipe-D-eDP-1
[577.107569] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.887s)
[577.110002] Subtest alternate-sync-async-flip: SUCCESS (12.949s)
[578.424446] [138/144] (487s left) vgem_basic (unload)
[578.465600] Starting subtest: unload
[607.445345] Subtest unload: SUCCESS (28.979s)
[607.566965] [139/144] (457s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-gen12-rc-ccs)
[608.011512] Starting subtest: pipe-D-crc-primary-basic-y-tiled-gen12-rc-ccs
[608.017770] Subtest pipe-D-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[608.875228] [140/144] (456s left) kms_vblank (ts-continuation-idle)
[608.953333] Starting subtest: ts-continuation-idle
[608.956024] Starting dynamic subtest: pipe-A-eDP-1
[609.395066] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.438s)
[609.415209] Starting dynamic subtest: pipe-D-eDP-1
[610.571560] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.170s)
[610.574263] Subtest ts-continuation-idle: SUCCESS (1.618s)
[611.502411] [141/144] (454s left) vc4/vc4_wait_seqno (bad-seqno-1ns)
[611.584970] Subtest bad-seqno-1ns: SKIP (0.000s)
[611.625367] [142/144] (454s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[611.697880] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[611.702331] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[611.788259] [143/144] (453s left) gem_mmap_offset (close-race)
[611.858833] Starting subtest: close-race
[631.964751] Subtest close-race: SUCCESS (20.107s)
[632.061271] [144/144] (433s left) kms_ccs (pipe-c-crc-primary-basic-yf-tiled-ccs)
[632.460813] Starting subtest: pipe-C-crc-primary-basic-yf-tiled-ccs
[632.467106] Subtest pipe-C-crc-primary-basic-yf-tiled-ccs: SKIP (0.000s)
[633.324575] Closing watchdogs
Done.