[56.207504] Initializing watchdogs
[56.207548]   /dev/watchdog0
[56.219404] [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]
[56.223197] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.225197] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.228536] [001/131] (960s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite)
[56.381859] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite
[61.247854] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.919s)
[61.360917] [002/131] (955s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[61.469434] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[61.473629] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[61.586293] [003/131] (955s left) syncobj_timeline (signal-array)
[61.642952] Starting subtest: signal-array
[61.645405] Subtest signal-array: SUCCESS (0.000s)
[61.740634] [004/131] (955s left) kms_lease (setcrtc-implicit-plane)
[61.846087] Starting subtest: setcrtc-implicit-plane
[61.848659] Starting dynamic subtest: pipe-A-eDP-1
[62.337733] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.494s)
[62.346221] Starting dynamic subtest: pipe-B-eDP-1
[63.510451] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.176s)
[63.517806] Starting dynamic subtest: pipe-C-eDP-1
[64.614355] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.110s)
[64.622423] Starting dynamic subtest: pipe-D-eDP-1
[65.743197] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[65.745708] Subtest setcrtc-implicit-plane: SUCCESS (3.916s)
[66.566809] [005/131] (950s left) kms_vblank (query-forked-busy-hang)
[66.673910] Starting subtest: query-forked-busy-hang
[66.676198] Starting dynamic subtest: pipe-A-eDP-1
[92.706439] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.054s)
[92.717924] Starting dynamic subtest: pipe-D-eDP-1
[107.679023] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.969s)
[107.681411] Subtest query-forked-busy-hang: SUCCESS (41.027s)
[108.492478] [006/131] (908s left) prime_busy (hang)
[108.559057] Starting subtest: hang
[108.561342] Starting dynamic subtest: rcs0
[134.460241] Dynamic subtest rcs0: SUCCESS (25.899s)
[134.468453] Starting dynamic subtest: bcs0
[141.556299] Dynamic subtest bcs0: SUCCESS (7.095s)
[141.564911] Starting dynamic subtest: vcs0
[148.596060] Dynamic subtest vcs0: SUCCESS (7.042s)
[148.602594] Starting dynamic subtest: vcs1
[155.567696] Dynamic subtest vcs1: SUCCESS (6.969s)
[155.573883] Starting dynamic subtest: vecs0
[162.607504] Dynamic subtest vecs0: SUCCESS (7.037s)
[162.613612] Starting dynamic subtest: ccs0
[176.443889] Dynamic subtest ccs0: SUCCESS (13.837s)
[176.446428] Subtest hang: SUCCESS (67.881s)
[176.566386] [007/131] (840s left) api_intel_bb (intel-bb-blit-y)
[176.620059] Starting subtest: intel-bb-blit-y
[180.072629] Subtest intel-bb-blit-y: SUCCESS (3.453s)
[180.201244] [008/131] (836s left) gem_create (create-size-update)
[180.261862] Starting subtest: create-size-update
[180.263846] Subtest create-size-update: SUCCESS (0.000s)
[180.478428] [009/131] (836s left) gem_mmap_offset (ptrace)
[180.539490] Starting subtest: ptrace
[180.544128] Starting dynamic subtest: smem0-wb
[180.559425] Dynamic subtest smem0-wb: SUCCESS (0.017s)
[180.566511] Starting dynamic subtest: smem0-wc
[180.575679] Dynamic subtest smem0-wc: SUCCESS (0.016s)
[180.582081] Starting dynamic subtest: smem0-uc
[180.593666] Dynamic subtest smem0-uc: SUCCESS (0.018s)
[180.596258] Subtest ptrace: SUCCESS (0.057s)
[180.721187] [010/131] (836s left) gem_exec_params (secure-non-root)
[180.782623] Starting subtest: secure-non-root
[180.787404] Subtest secure-non-root: SKIP (0.000s)
[180.898602] [011/131] (836s left) gen9_exec_parse (bb-large)
[180.960089] Subtest bb-large: SKIP (0.000s)
[181.033924] [012/131] (835s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[181.143513] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[181.147765] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[181.247814] [013/131] (835s left) gem_fence_thrash (bo-write-verify-x)
[181.283727] Starting subtest: bo-write-verify-x
[181.310108] Subtest bo-write-verify-x: SKIP (0.027s)
[181.360372] [014/131] (835s left) gem_pxp (reject-modify-context-protection-off-3)
[181.489348] Starting subtest: reject-modify-context-protection-off-3
[181.491557] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[181.587652] [015/131] (835s left) gem_exec_balancer (full-late-pulse)
[181.651347] Starting subtest: full-late-pulse
[186.600129] Subtest full-late-pulse: SUCCESS (4.948s)
[186.855210] [016/131] (830s left) gem_tiled_pread_pwrite
[186.960662] [017/131] (830s left) kms_color (ctm-negative)
[187.071891] Starting subtest: ctm-negative
[187.074222] Starting dynamic subtest: pipe-A-eDP-1
[187.772239] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.697s)
[187.782045] Starting dynamic subtest: pipe-B-eDP-1
[189.184974] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.414s)
[189.192822] Starting dynamic subtest: pipe-C-eDP-1
[190.549337] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.364s)
[190.561547] Starting dynamic subtest: pipe-D-eDP-1
[191.901220] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.351s)
[191.903479] Subtest ctm-negative: SUCCESS (4.827s)
[192.697957] [018/131] (824s left) i915_pm_rps (thresholds)
[192.758440] Starting subtest: thresholds
[192.763217] Subtest thresholds: SKIP (0.001s)
[192.830698] [019/131] (824s left) kms_busy (basic-hang)
[192.932921] Starting subtest: basic-hang
[192.935348] Starting dynamic subtest: flip-pipe-A
[193.675783] Dynamic subtest flip-pipe-A: SUCCESS (0.740s)
[193.687641] Starting dynamic subtest: modeset-pipe-A
[197.119497] Dynamic subtest modeset-pipe-A: SUCCESS (3.442s)
[197.127468] Starting dynamic subtest: flip-pipe-D
[198.327931] Dynamic subtest flip-pipe-D: SUCCESS (1.206s)
[198.336584] Starting dynamic subtest: modeset-pipe-D
[201.710793] Dynamic subtest modeset-pipe-D: SUCCESS (3.386s)
[201.713074] Subtest basic-hang: SUCCESS (8.777s)
[202.519533] [020/131] (814s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[202.581828] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[202.639204] [021/131] (814s left) gem_wait (write-wait)
[202.711485] Starting subtest: write-wait
[202.713861] Starting dynamic subtest: all-engines
[203.839057] Dynamic subtest all-engines: SUCCESS (1.127s)
[203.852786] Starting dynamic subtest: rcs0
[204.892954] Dynamic subtest rcs0: SUCCESS (1.053s)
[204.906555] Starting dynamic subtest: bcs0
[205.948314] Dynamic subtest bcs0: SUCCESS (1.055s)
[205.962988] Starting dynamic subtest: vcs0
[207.000464] Dynamic subtest vcs0: SUCCESS (1.051s)
[207.015271] Starting dynamic subtest: vcs1
[208.053209] Dynamic subtest vcs1: SUCCESS (1.052s)
[208.068022] Starting dynamic subtest: vecs0
[209.105263] Dynamic subtest vecs0: SUCCESS (1.051s)
[209.119138] Starting dynamic subtest: ccs0
[210.162481] Dynamic subtest ccs0: SUCCESS (1.056s)
[210.164974] Subtest write-wait: SUCCESS (7.451s)
[210.357225] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[210.360338] [022/131] (806s left) kms_cursor_crc (cursor-random-512x170)
[210.630855] Starting subtest: cursor-random-512x170
[210.636920] Subtest cursor-random-512x170: SKIP (0.001s)
[210.770471] [023/131] (806s left) gem_exec_parallel (fds)
[210.830638] Starting subtest: fds
[210.833045] Starting dynamic subtest: rcs0
[213.350403] Dynamic subtest rcs0: SUCCESS (2.518s)
[213.360598] Starting dynamic subtest: bcs0
[216.333866] Dynamic subtest bcs0: SUCCESS (2.982s)
[216.347391] Starting dynamic subtest: vcs0
[220.136257] Dynamic subtest vcs0: SUCCESS (3.802s)
[220.144611] Starting dynamic subtest: vcs1
[224.131061] Dynamic subtest vcs1: SUCCESS (3.993s)
[224.138436] Starting dynamic subtest: vecs0
[228.019824] Dynamic subtest vecs0: SUCCESS (3.888s)
[228.028700] Starting dynamic subtest: ccs0
[230.274078] Dynamic subtest ccs0: SUCCESS (2.253s)
[230.276855] Subtest fds: SUCCESS (19.441s)
[230.456075] [024/131] (786s left) i915_query (query-regions-sanity-check)
[230.515232] Starting subtest: query-regions-sanity-check
[230.526394] Subtest query-regions-sanity-check: SUCCESS (0.014s)
[230.634815] [025/131] (786s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[230.747162] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[230.751468] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[230.845173] [026/131] (786s left) perf_pmu (multi-client)
[230.881564] Starting subtest: multi-client
[230.884021] Starting dynamic subtest: rcs0
[231.943509] Dynamic subtest rcs0: SUCCESS (1.060s)
[231.952919] Starting dynamic subtest: bcs0
[232.991813] Dynamic subtest bcs0: SUCCESS (1.048s)
[233.000539] Starting dynamic subtest: vcs0
[234.041591] Dynamic subtest vcs0: SUCCESS (1.048s)
[234.052421] Starting dynamic subtest: vcs1
[235.936482] Dynamic subtest vcs1: SUCCESS (1.894s)
[235.947081] Starting dynamic subtest: vecs0
[237.920436] Dynamic subtest vecs0: SUCCESS (1.983s)
[237.930030] Starting dynamic subtest: ccs0
[239.971103] Dynamic subtest ccs0: SUCCESS (2.050s)
[239.973895] Subtest multi-client: SUCCESS (9.088s)
[240.082738] [027/131] (777s left) syncobj_timeline (invalid-query-bad-pad)
[240.142877] Starting subtest: invalid-query-bad-pad
[240.145168] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[240.250908] [028/131] (777s left) kms_setmode (basic)
[240.304161] Starting subtest: basic
[240.635656] Starting dynamic subtest: pipe-A-eDP-1
[242.721239] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.082s)
[243.070043] Starting dynamic subtest: pipe-B-eDP-1
[245.146167] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.079s)
[245.453180] Subtest basic: SUCCESS (5.148s)
[246.276808] [029/131] (771s left) kms_psr (psr-sprite-render)
[246.380428] Starting subtest: psr-sprite-render
[246.382624] Starting dynamic subtest: eDP-1
[248.111504] Dynamic subtest eDP-1: SUCCESS (1.728s)
[248.113924] Subtest psr-sprite-render: SUCCESS (1.729s)
[248.938329] [030/131] (768s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[249.039178] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[249.041572] Starting dynamic subtest: pipe-A-default-mode
[249.338517] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[249.343219] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.294s)
[250.165361] [031/131] (767s left) drm_fdinfo (most-busy-check-all)
[250.205054] Starting subtest: most-busy-check-all
[250.207511] Starting dynamic subtest: rcs0
[250.212263] Dynamic subtest rcs0: SKIP (0.005s)
[250.221836] Starting dynamic subtest: bcs0
[250.226271] Dynamic subtest bcs0: SKIP (0.000s)
[250.233366] Starting dynamic subtest: vcs0
[250.237437] Dynamic subtest vcs0: SKIP (0.000s)
[250.244392] Starting dynamic subtest: vcs1
[250.248578] Dynamic subtest vcs1: SKIP (0.000s)
[250.255454] Starting dynamic subtest: vecs0
[250.259682] Dynamic subtest vecs0: SKIP (0.000s)
[250.266669] Starting dynamic subtest: ccs0
[250.270960] Dynamic subtest ccs0: SKIP (0.000s)
[250.275339] Subtest most-busy-check-all: SKIP (0.007s)
[250.307947] [032/131] (767s left) gem_exec_whisper (basic-sync-all)
[250.376856] Starting subtest: basic-sync-all
[257.043155] Subtest basic-sync-all: SUCCESS (6.663s)
[257.221363] [033/131] (760s left) kms_dirtyfb (default-dirtyfb-ioctl)
[257.333064] Starting subtest: default-dirtyfb-ioctl
[257.335314] Starting dynamic subtest: A-eDP-1
[258.987534] Dynamic subtest A-eDP-1: SUCCESS (1.652s)
[258.989758] Subtest default-dirtyfb-ioctl: SUCCESS (1.653s)
[259.799818] [034/131] (757s left) kms_rmfb (close-fd)
[259.893106] Starting subtest: close-fd
[259.895507] Starting dynamic subtest: pipe-A-eDP-1
[260.010438] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.115s)
[260.019322] Starting dynamic subtest: pipe-B-eDP-1
[261.163099] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.153s)
[261.173282] Starting dynamic subtest: pipe-C-eDP-1
[262.292389] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.127s)
[262.299811] Starting dynamic subtest: pipe-D-eDP-1
[263.418559] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[263.420643] Subtest close-fd: SUCCESS (3.523s)
[264.617187] [035/131] (753s left) i915_pm_rpm (debugfs-read)
[266.161443] Starting subtest: debugfs-read
[267.318176] Subtest debugfs-read: SUCCESS (1.158s)
[267.676470] [036/131] (750s left) gem_mmap_gtt (basic-write-read-distinct)
[267.740567] Subtest basic-write-read-distinct: SKIP (0.000s)
[267.800459] [037/131] (749s left) kms_pm_lpsp (screens-disabled)
[268.895255] Starting subtest: screens-disabled
[268.899927] Subtest screens-disabled: SKIP (0.000s)
[268.959722] [038/131] (748s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[273.930366] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[273.933368] Starting dynamic subtest: pipe-A-eDP-1
[274.720826] Dynamic subtest pipe-A-eDP-1: SKIP (0.785s)
[274.729392] Starting dynamic subtest: pipe-B-eDP-1
[275.853831] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[275.858301] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.920s)
[277.026098] [039/131] (740s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[277.077634] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[277.080046] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[277.183470] [040/131] (740s left) gem_pread (display)
[277.246164] Subtest display: SKIP (0.000s)
[277.299670] [041/131] (740s left) kms_atomic (test-only)
[277.393052] Starting subtest: test-only
[277.454498] Starting dynamic subtest: pipe-A-eDP-1
[279.839065] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.385s)
[279.841446] Subtest test-only: SUCCESS (2.445s)
[280.692680] [042/131] (737s left) gem_userptr_blits (sd-probe)
[280.758907] Starting subtest: sd-probe
[280.760757] Subtest sd-probe: SUCCESS (0.000s)
[280.875439] [043/131] (737s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[280.975440] Starting subtest: x-tiled-64bpp-rotate-90
[280.997241] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[281.135464] [044/131] (736s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[281.227101] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[281.229530] Starting dynamic subtest: pipe-A
[282.848379] Dynamic subtest pipe-A: SUCCESS (1.621s)
[282.856748] Starting dynamic subtest: pipe-B
[285.470698] Dynamic subtest pipe-B: SUCCESS (2.622s)
[285.479189] Starting dynamic subtest: pipe-C
[286.626242] Dynamic subtest pipe-C: SUCCESS (1.154s)
[286.635502] Starting dynamic subtest: pipe-D
[287.835474] Dynamic subtest pipe-D: SUCCESS (1.209s)
[287.837846] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (6.608s)
[289.021082] [045/131] (729s left) kms_frontbuffer_tracking (fbc-1p-rte)
[289.126692] Starting subtest: fbc-1p-rte
[293.594047] Subtest fbc-1p-rte: SUCCESS (4.472s)
[293.760097] [046/131] (724s left) kms_psr (pr-sprite-render)
[293.857173] Starting subtest: pr-sprite-render
[293.861672] Subtest pr-sprite-render: SKIP (0.000s)
[293.920578] [047/131] (724s left) gem_partial_pwrite_pread (reads)
[293.973018] Subtest reads: SKIP (0.000s)
[294.051088] [048/131] (724s left) kms_dsc (dsc-fractional-bpp)
[294.144485] Starting subtest: dsc-fractional-bpp
[294.154774] Subtest dsc-fractional-bpp: SKIP (0.000s)
[294.210868] [049/131] (723s left) kms_cursor_crc (cursor-offscreen-512x170)
[294.454114] Starting subtest: cursor-offscreen-512x170
[294.458057] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[294.577395] [050/131] (723s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[294.678972] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[295.480697] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.803s)
[295.675466] [051/131] (722s left) gem_exec_schedule (pi-common)
[295.859335] Starting subtest: pi-common
[295.861665] Starting dynamic subtest: rcs0
[295.952914] Dynamic subtest rcs0: SUCCESS (0.095s)
[295.963541] Starting dynamic subtest: bcs0
[296.038090] Dynamic subtest bcs0: SUCCESS (0.084s)
[296.048512] Starting dynamic subtest: vcs0
[296.121318] Dynamic subtest vcs0: SUCCESS (0.082s)
[296.136720] Starting dynamic subtest: vcs1
[296.200664] Dynamic subtest vcs1: SUCCESS (0.078s)
[296.216071] Starting dynamic subtest: vecs0
[296.277734] Dynamic subtest vecs0: SUCCESS (0.076s)
[296.293122] Starting dynamic subtest: ccs0
[296.353109] Dynamic subtest ccs0: SUCCESS (0.074s)
[296.355975] Subtest pi-common: SUCCESS (0.496s)
[296.594695] [052/131] (721s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[296.699242] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[296.703726] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[296.792208] [053/131] (721s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[296.910764] Starting subtest: 2x-long-cursor-vs-flip-legacy
[296.915261] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[297.003139] [054/131] (721s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[297.111867] Starting subtest: yf-tiled-addfb-size-offset-overflow
[297.116342] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[297.198104] [055/131] (721s left) gem_exec_schedule (u-semaphore-codependency)
[297.274200] Starting subtest: u-semaphore-codependency
[297.280123] Subtest u-semaphore-codependency: SUCCESS (0.011s)
[297.626975] [056/131] (720s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[297.727540] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[297.731653] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[297.813069] [057/131] (720s left) gem_workarounds (suspend-resume-context)
[297.875091] Starting subtest: suspend-resume-context
[314.406655] Subtest suspend-resume-context: SUCCESS (1.285s)
[314.535297] [058/131] (719s left) kms_panel_fitting (atomic-fastset)
[314.635728] Starting subtest: atomic-fastset
[314.638030] Starting dynamic subtest: pipe-A-eDP-1
[314.795157] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.159s)
[314.805004] Starting dynamic subtest: pipe-B-eDP-1
[315.971478] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.209s)
[315.979944] Starting dynamic subtest: pipe-C-eDP-1
[317.119222] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.175s)
[317.127508] Starting dynamic subtest: pipe-D-eDP-1
[318.316575] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s)
[318.318901] Subtest atomic-fastset: SUCCESS (3.768s)
[319.519482] [059/131] (714s left) gem_mmap (bad-size)
[319.574787] Subtest bad-size: SKIP (0.000s)
[319.621814] [060/131] (714s left) perf_pmu (busy-double-start)
[319.659071] Starting subtest: busy-double-start
[319.661550] Starting dynamic subtest: rcs0
[323.289494] Dynamic subtest rcs0: FAIL (3.613s)
[323.299239] Starting dynamic subtest: bcs0
[326.839723] Dynamic subtest bcs0: SUCCESS (3.646s)
[326.849520] Starting dynamic subtest: vcs0
[330.729186] Dynamic subtest vcs0: FAIL (3.844s)
[330.737676] Starting dynamic subtest: vcs1
[334.736020] Dynamic subtest vcs1: SUCCESS (4.084s)
[334.754077] Starting dynamic subtest: vecs0
[338.731352] Dynamic subtest vecs0: FAIL (3.932s)
[338.741527] Starting dynamic subtest: ccs0
[342.295499] Dynamic subtest ccs0: FAIL (3.566s)
[342.297532] Subtest busy-double-start: FAIL (22.693s)
[342.345864] [061/131] (691s left) kms_async_flips (test-cursor-atomic)
[342.451179] Starting subtest: test-cursor-atomic
[342.455774] Subtest test-cursor-atomic: SKIP (0.000s)
[343.610795] [062/131] (690s left) kms_chamelium_edid (hdmi-mode-timings)
[344.000767] Subtest hdmi-mode-timings: SKIP (0.000s)
[344.814548] [063/131] (688s left) gem_exec_fence (invalid-fence-array)
[345.226769] Starting subtest: invalid-fence-array
[345.229160] Subtest invalid-fence-array: SUCCESS (0.002s)
[347.273438] [064/131] (686s left) gem_close (basic)
[347.341152] Starting subtest: basic
[347.343101] Subtest basic: SUCCESS (0.000s)
[347.456677] [065/131] (686s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[347.555524] Starting subtest: y-tiled-16bpp-rotate-90
[347.559802] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[347.662887] [066/131] (686s left) gem_tiled_blits (basic)
[347.720288] Subtest basic: SKIP (0.000s)
[347.790676] [067/131] (686s left) kms_content_protection (atomic)
[347.965322] Starting subtest: atomic
[351.370795] Subtest atomic: SKIP (3.402s)
[352.541090] [068/131] (681s left) gem_mmap_wc (fault-concurrent)
[352.609602] Subtest fault-concurrent: SKIP (0.000s)
[352.676514] [069/131] (681s left) kms_flip (modeset-vs-vblank-race)
[352.786527] Starting subtest: modeset-vs-vblank-race
[352.788660] Starting dynamic subtest: A-eDP1
[357.442272] Dynamic subtest A-eDP1: SUCCESS (4.655s)
[357.452995] Starting dynamic subtest: B-eDP1
[362.146653] Dynamic subtest B-eDP1: SUCCESS (4.702s)
[362.161832] Starting dynamic subtest: C-eDP1
[366.835918] Dynamic subtest C-eDP1: SUCCESS (4.688s)
[366.845776] Starting dynamic subtest: D-eDP1
[371.361878] Dynamic subtest D-eDP1: SUCCESS (4.525s)
[371.364207] Subtest modeset-vs-vblank-race: SUCCESS (18.580s)
[372.503383] [070/131] (661s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[372.608522] Starting subtest: fbc-psr2-sprite-plane-onoff
[372.610882] Starting dynamic subtest: eDP-1
[372.615147] Dynamic subtest eDP-1: SKIP (0.000s)
[372.619524] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[372.675076] [071/131] (661s left) kms_vblank (wait-idle)
[372.774488] Starting subtest: wait-idle
[372.778314] Starting dynamic subtest: pipe-A-eDP-1
[374.537191] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.762s)
[374.554714] Starting dynamic subtest: pipe-D-eDP-1
[376.937172] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.393s)
[376.939503] Subtest wait-idle: SUCCESS (4.162s)
[377.754574] [072/131] (656s left) gem_ctx_persistence (legacy-engines-queued)
[377.811611] Starting subtest: legacy-engines-queued
[377.814005] Starting dynamic subtest: render
[377.908860] Dynamic subtest render: SUCCESS (0.096s)
[377.920828] Starting dynamic subtest: bsd1
[379.148102] Dynamic subtest bsd1: SUCCESS (1.236s)
[379.160051] Starting dynamic subtest: bsd2
[381.129425] Dynamic subtest bsd2: SUCCESS (1.979s)
[381.141550] Starting dynamic subtest: blt
[381.190949] Dynamic subtest blt: SUCCESS (0.061s)
[381.202138] Starting dynamic subtest: vebox
[383.113899] Dynamic subtest vebox: SUCCESS (1.920s)
[383.117071] Subtest legacy-engines-queued: SUCCESS (5.303s)
[383.197809] [073/131] (650s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[383.307564] Starting subtest: crc-primary-basic-yf-tiled-ccs
[383.309882] Starting dynamic subtest: pipe-A-eDP-1
[383.333097] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.343040] Starting dynamic subtest: pipe-B-eDP-1
[383.365051] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[383.373715] Starting dynamic subtest: pipe-C-eDP-1
[383.395785] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[383.404903] Starting dynamic subtest: pipe-D-eDP-1
[383.427140] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[383.431561] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.005s)
[385.494532] [074/131] (648s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[385.588174] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[385.590975] Starting dynamic subtest: pipe-A-eDP-1
[385.612341] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[385.623373] Starting dynamic subtest: pipe-B-eDP-1
[385.643097] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[385.651331] Starting dynamic subtest: pipe-C-eDP-1
[385.670906] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[385.679311] Starting dynamic subtest: pipe-D-eDP-1
[385.699716] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[385.701707] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[387.744427] [075/131] (646s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[387.845100] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[387.849715] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[387.929582] [076/131] (646s left) kms_plane_scaling (planes-upscale-factor-0-25)
[388.013497] Starting subtest: planes-upscale-factor-0-25
[388.015626] Starting dynamic subtest: pipe-A
[388.077545] Dynamic subtest pipe-A: SUCCESS (0.064s)
[388.086252] Starting dynamic subtest: pipe-B
[389.177635] Dynamic subtest pipe-B: SUCCESS (1.100s)
[389.188269] Starting dynamic subtest: pipe-C
[390.285305] Dynamic subtest pipe-C: SUCCESS (1.107s)
[390.295254] Starting dynamic subtest: pipe-D
[391.415528] Dynamic subtest pipe-D: SUCCESS (1.130s)
[391.417735] Subtest planes-upscale-factor-0-25: SUCCESS (3.404s)
[392.570051] [077/131] (641s left) gem_exec_reloc (basic-concurrent16)
[392.636217] Subtest basic-concurrent16: SKIP (0.000s)
[392.728584] [078/131] (641s left) kms_setmode (invalid-clone-exclusive-crtc)
[392.791048] Starting subtest: invalid-clone-exclusive-crtc
[392.797776] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[392.894180] [079/131] (641s left) gem_exec_fence (syncobj-timeline-unused-fence)
[393.403055] Starting subtest: syncobj-timeline-unused-fence
[393.409168] Subtest syncobj-timeline-unused-fence: SUCCESS (0.008s)
[395.290753] [080/131] (638s left) kms_getfb (getfb2-accept-ccs)
[395.358117] Starting subtest: getfb2-accept-ccs
[395.360574] Subtest getfb2-accept-ccs: SUCCESS (0.000s)
[395.479439] [081/131] (638s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[395.580547] Starting subtest: disable-crc-after-crtc
[395.582417] Starting dynamic subtest: pipe-A-eDP-1
[397.207350] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.627s)
[397.217184] Starting dynamic subtest: pipe-B-eDP-1
[399.439477] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.232s)
[399.449224] Starting dynamic subtest: pipe-C-eDP-1
[401.678148] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.238s)
[401.687364] Starting dynamic subtest: pipe-D-eDP-1
[403.932027] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.254s)
[403.934551] Subtest disable-crc-after-crtc: SUCCESS (8.356s)
[404.773614] [082/131] (629s left) syncobj_wait (wait-all-interrupted)
[404.831760] Starting subtest: wait-all-interrupted
[404.932821] Subtest wait-all-interrupted: SUCCESS (0.102s)
[405.001270] [083/131] (629s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[405.092590] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[405.094893] Starting dynamic subtest: pipe-A-eDP-1
[405.104010] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[405.113974] Starting dynamic subtest: pipe-B-eDP-1
[405.122413] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[405.130045] Starting dynamic subtest: pipe-C-eDP-1
[405.138550] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[405.145969] Starting dynamic subtest: pipe-D-eDP-1
[405.154968] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[405.159001] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s)
[407.213656] [084/131] (627s left) gem_lmem_swapping (heavy-verify-random-ccs)
[407.259665] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[407.299836] [085/131] (627s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[407.402777] Starting subtest: bad-pixel-format-y-tiled-ccs
[407.405131] Starting dynamic subtest: pipe-A-eDP-1
[407.428653] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[407.437929] Starting dynamic subtest: pipe-B-eDP-1
[407.459635] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[407.467573] Starting dynamic subtest: pipe-C-eDP-1
[407.489299] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[407.497447] Starting dynamic subtest: pipe-D-eDP-1
[407.519687] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[407.524095] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[409.568617] [086/131] (624s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[409.697561] Starting subtest: cursorA-vs-flipA-atomic
[420.853770] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.153s)
[421.703755] [087/131] (612s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[421.807226] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[421.811691] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[421.913407] [088/131] (612s left) gem_render_copy_redux (flink-interruptible)
[421.979932] Starting subtest: flink-interruptible
[422.862525] Subtest flink-interruptible: SUCCESS (0.883s)
[423.006975] [089/131] (611s left) kms_psr (fbc-psr-cursor-plane-move)
[423.113295] Starting subtest: fbc-psr-cursor-plane-move
[423.115307] Starting dynamic subtest: eDP-1
[423.118933] Dynamic subtest eDP-1: SKIP (0.000s)
[423.122514] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[423.177312] [090/131] (611s left) kms_chamelium_color (degamma)
[423.283797] Subtest degamma: SKIP (0.000s)
[423.322775] [091/131] (611s left) gem_ctx_shared (single-timeline)
[423.386278] Starting subtest: single-timeline
[423.404796] Subtest single-timeline: SUCCESS (0.014s)
[423.650923] [092/131] (611s left) gem_ctx_persistence (heartbeat-hang)
[423.712601] Starting subtest: heartbeat-hang
[423.717004] Subtest heartbeat-hang: SKIP (0.000s)
[423.824361] [093/131] (610s left) gem_softpin (allocator-fork)
[423.893902] Starting subtest: allocator-fork
[426.109105] Subtest allocator-fork: SUCCESS (2.213s)
[426.262498] [094/131] (608s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[426.368553] Starting subtest: fbc-pr-sprite-mmap-cpu
[426.373095] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[426.433029] [095/131] (608s left) perf (mi-rpc)
[426.547076] Starting subtest: mi-rpc
[426.551241] Subtest mi-rpc: SKIP (0.000s)
[426.586538] [096/131] (608s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[426.693875] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[431.881091] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (5.189s)
[431.998037] [097/131] (602s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[432.056913] Starting subtest: mixed-tiled-to-y-tiled-ccs
[432.061701] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[432.239139] [098/131] (602s left) kms_psr (psr2-suspend)
[432.335731] Starting subtest: psr2-suspend
[432.337928] Starting dynamic subtest: eDP-1
[450.781416] Dynamic subtest eDP-1: SUCCESS (2.912s)
[450.783701] Subtest psr2-suspend: SUCCESS (2.913s)
[451.548743] [099/131] (598s left) kms_invalid_mode (overflow-vrefresh)
[451.645779] Starting subtest: overflow-vrefresh
[451.648148] Starting dynamic subtest: pipe-A-eDP-1
[451.929756] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.294s)
[451.938466] Starting dynamic subtest: pipe-B-eDP-1
[451.940387] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[451.947862] Starting dynamic subtest: pipe-C-eDP-1
[451.949783] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[451.957604] Starting dynamic subtest: pipe-D-eDP-1
[451.959786] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[451.962026] Subtest overflow-vrefresh: SUCCESS (0.299s)
[452.747977] [100/131] (597s left) kms_plane (pixel-format-source-clamping)
[452.843420] Starting subtest: pixel-format-source-clamping
[452.879837] Starting dynamic subtest: pipe-A-plane-0
[457.407379] Dynamic subtest pipe-A-plane-0: SUCCESS (4.611s)
[457.416314] Starting dynamic subtest: pipe-A-plane-3
[459.604149] Dynamic subtest pipe-A-plane-3: SUCCESS (2.221s)
[459.614633] Starting dynamic subtest: pipe-A-plane-5
[459.616573] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[460.709769] Starting dynamic subtest: pipe-B-plane-0
[465.249568] Dynamic subtest pipe-B-plane-0: SUCCESS (4.578s)
[465.261136] Starting dynamic subtest: pipe-B-plane-3
[467.451104] Dynamic subtest pipe-B-plane-3: SUCCESS (2.209s)
[467.459461] Starting dynamic subtest: pipe-B-plane-5
[467.460973] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[467.758045] Subtest pixel-format-source-clamping: SUCCESS (15.073s)
[468.624235] [101/131] (581s left) gen3_render_tiledy_blits
[468.733964] [102/131] (581s left) kms_big_fb (linear-8bpp-rotate-90)
[468.837417] Starting subtest: linear-8bpp-rotate-90
[468.857757] Subtest linear-8bpp-rotate-90: SKIP (0.020s)
[468.988608] [103/131] (581s left) syncobj_basic (test-valid-cycle)
[469.044463] Starting subtest: test-valid-cycle
[469.046780] Subtest test-valid-cycle: SUCCESS (0.000s)
[469.149464] [104/131] (581s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[469.255068] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[469.259444] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[469.353006] [105/131] (581s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[469.463997] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[469.468453] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[469.563526] [106/131] (580s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[469.665604] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[469.669091] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[469.742693] [107/131] (580s left) drm_fdinfo (busy-idle-check-all)
[469.780736] Starting subtest: busy-idle-check-all
[469.787712] Starting dynamic subtest: rcs0
[469.791440] Dynamic subtest rcs0: SKIP (0.004s)
[469.800025] Starting dynamic subtest: bcs0
[469.803898] Dynamic subtest bcs0: SKIP (0.000s)
[469.811933] Starting dynamic subtest: vcs0
[469.815698] Dynamic subtest vcs0: SKIP (0.000s)
[469.824468] Starting dynamic subtest: vcs1
[469.827712] Dynamic subtest vcs1: SKIP (0.000s)
[469.834967] Starting dynamic subtest: vecs0
[469.838267] Dynamic subtest vecs0: SKIP (0.000s)
[469.845731] Starting dynamic subtest: ccs0
[469.849123] Dynamic subtest ccs0: SKIP (0.000s)
[469.852504] Subtest busy-idle-check-all: SKIP (0.008s)
[469.880754] [108/131] (580s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[474.944812] Starting subtest: psr2-plane-move-sf-dmg-area
[474.947137] Starting dynamic subtest: pipe-A-eDP-1
[480.242556] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.304s)
[480.251185] Starting dynamic subtest: pipe-B-eDP-1
[485.527581] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.285s)
[485.530172] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.593s)
[486.403171] [109/131] (564s left) gem_caching (read-writes)
[486.468803] Subtest read-writes: SKIP (0.000s)
[486.537608] [110/131] (564s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[486.640677] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[491.675379] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.035s)
[491.806551] [111/131] (558s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[493.848875] Starting subtest: modeset-non-lpsp-stress-no-wait
[493.901783] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.049s)
[494.201614] [112/131] (556s left) kms_cursor_edge_walk (256x256-top-bottom)
[494.303258] Starting subtest: 256x256-top-bottom
[494.305594] Starting dynamic subtest: pipe-A-eDP-1
[497.756945] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.450s)
[497.766940] Starting dynamic subtest: pipe-D-eDP-1
[500.859974] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.103s)
[500.862224] Subtest 256x256-top-bottom: SUCCESS (6.556s)
[501.671861] [113/131] (548s left) gem_exec_reloc (basic-gtt-read-noreloc)
[501.739528] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[501.792352] [114/131] (548s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[501.888322] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[501.890700] Starting dynamic subtest: pipe-A-default-mode
[502.188888] Dynamic subtest pipe-A-default-mode: SKIP (0.295s)
[502.193775] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.297s)
[502.997323] [115/131] (547s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[503.106111] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[506.413789] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (3.312s)
[506.528176] [116/131] (544s left) syncobj_wait (wait-all-delayed-signal)
[506.592447] Starting subtest: wait-all-delayed-signal
[506.696321] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[506.766046] [117/131] (544s left) kms_addfb_basic (basic)
[506.825008] Starting subtest: basic
[506.827060] Subtest basic: SUCCESS (0.000s)
[506.963079] [118/131] (543s left) gem_userptr_blits (unsync-unmap)
[507.037019] Subtest unsync-unmap: SKIP (0.000s)
[507.148600] [119/131] (543s left) gem_mmap_gtt (coherency)
[507.199000] Subtest coherency: SKIP (0.000s)
[507.264067] [120/131] (543s left) sysfs_heartbeat_interval (precise)
[507.319953] Starting subtest: precise
[507.322419] Starting dynamic subtest: vecs0
[514.730139] Dynamic subtest vecs0: SUCCESS (7.408s)
[514.742212] Starting dynamic subtest: ccs0
[515.883026] Dynamic subtest ccs0: SUCCESS (1.153s)
[515.894761] Starting dynamic subtest: vcs0
[523.709747] Dynamic subtest vcs0: SUCCESS (7.823s)
[523.720587] Starting dynamic subtest: bcs0
[524.870389] Dynamic subtest bcs0: SUCCESS (1.160s)
[524.882242] Starting dynamic subtest: rcs0
[525.984630] Dynamic subtest rcs0: SUCCESS (1.114s)
[525.994173] Starting dynamic subtest: vcs1
[533.669507] Dynamic subtest vcs1: SUCCESS (7.685s)
[533.672056] Subtest precise: SUCCESS (26.356s)
[533.744218] [121/131] (517s left) api_intel_bb (simple-bb-ctx)
[533.805930] Starting subtest: simple-bb-ctx
[533.816128] Subtest simple-bb-ctx: SUCCESS (0.012s)
[533.963620] [122/131] (517s left) syncobj_eventfd (invalid-illegal-handle)
[534.017265] Starting subtest: invalid-illegal-handle
[534.019647] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[534.110924] [123/131] (516s left) sw_sync (sync_merge_same)
[534.144350] Starting subtest: sync_merge_same
[534.146765] Subtest sync_merge_same: SUCCESS (0.000s)
[534.186850] [124/131] (516s left) syncobj_wait (single-wait-for-submit-signaled)
[534.235896] Starting subtest: single-wait-for-submit-signaled
[534.238095] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[534.346347] [125/131] (516s left) syncobj_timeline (wait-any-snapshot)
[534.408549] Starting subtest: wait-any-snapshot
[534.487751] Subtest wait-any-snapshot: SUCCESS (0.081s)
[534.558966] [126/131] (516s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[534.666738] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[534.670550] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[534.775247] [127/131] (516s left) gem_exec_reloc (basic-range)
[534.845443] Subtest basic-range: SKIP (0.000s)
[534.923242] [128/131] (516s left) gem_close_race (multigpu-basic-process)
[534.999581] Starting subtest: multigpu-basic-process
[535.003516] Subtest multigpu-basic-process: SKIP (0.000s)
[535.230290] [129/131] (515s left) gem_userptr_blits (process-exit)
[535.299660] Starting subtest: process-exit
[535.309146] Subtest process-exit: SUCCESS (0.010s)
[535.428215] [130/131] (515s left) gem_eio (in-flight-suspend)
[535.635035] Starting subtest: in-flight-suspend
[578.619139] Subtest in-flight-suspend: SUCCESS (13.170s)
[578.744349] [131/131] (502s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[578.845934] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[578.850163] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[578.945576] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_lease
results: parsing output: 4/ for test: kms_vblank
results: parsing output: 5/ for test: prime_busy
results: parsing output: 6/ for test: api_intel_bb
results: parsing output: 7/ for test: gem_create
results: parsing output: 8/ for test: gem_mmap_offset
results: parsing output: 9/ for test: gem_exec_params
results: parsing output: 10/ for test: gen9_exec_parse
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_fence_thrash
results: parsing output: 13/ for test: gem_pxp
results: parsing output: 14/ for test: gem_exec_balancer
results: parsing output: 15/ for test: gem_tiled_pread_pwrite
results: parsing output: 16/ for test: kms_color
results: parsing output: 17/ for test: i915_pm_rps
results: parsing output: 18/ for test: kms_busy
results: parsing output: 19/ for test: kms_pipe_b_c_ivb
results: parsing output: 20/ for test: gem_wait
results: parsing output: 21/ for test: kms_cursor_crc
results: parsing output: 22/ for test: gem_exec_parallel
results: parsing output: 23/ for test: i915_query
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: perf_pmu
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: kms_setmode
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: kms_flip_scaled_crc
results: parsing output: 30/ for test: drm_fdinfo
results: parsing output: 31/ for test: gem_exec_whisper
results: parsing output: 32/ for test: kms_dirtyfb
results: parsing output: 33/ for test: kms_rmfb
results: parsing output: 34/ for test: i915_pm_rpm
results: parsing output: 35/ for test: gem_mmap_gtt
results: parsing output: 36/ for test: kms_pm_lpsp
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: gem_pread
results: parsing output: 40/ for test: kms_atomic
results: parsing output: 41/ for test: gem_userptr_blits
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: kms_plane_scaling
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: gem_partial_pwrite_pread
results: parsing output: 47/ for test: kms_dsc
results: parsing output: 48/ for test: kms_cursor_crc
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: gem_exec_schedule
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: gem_workarounds
results: parsing output: 57/ for test: kms_panel_fitting
results: parsing output: 58/ for test: gem_mmap
results: parsing output: 59/ for test: perf_pmu
results: parsing output: 60/ for test: kms_async_flips
results: parsing output: 61/ for test: kms_chamelium_edid
results: parsing output: 62/ for test: gem_exec_fence
results: parsing output: 63/ for test: gem_close
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: gem_tiled_blits
results: parsing output: 66/ for test: kms_content_protection
results: parsing output: 67/ for test: gem_mmap_wc
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_vblank
results: parsing output: 71/ for test: gem_ctx_persistence
results: parsing output: 72/ for test: kms_ccs
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: gem_exec_reloc
results: parsing output: 77/ for test: kms_setmode
results: parsing output: 78/ for test: gem_exec_fence
results: parsing output: 79/ for test: kms_getfb
results: parsing output: 80/ for test: kms_pipe_crc_basic
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: kms_ccs
results: parsing output: 83/ for test: gem_lmem_swapping
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_render_copy_redux
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: kms_chamelium_color
results: parsing output: 90/ for test: gem_ctx_shared
results: parsing output: 91/ for test: gem_ctx_persistence
results: parsing output: 92/ for test: gem_softpin
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: perf
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_render_copy
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: kms_invalid_mode
results: parsing output: 99/ for test: kms_plane
results: parsing output: 100/ for test: gen3_render_tiledy_blits
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: syncobj_basic
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: drm_fdinfo
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: gem_caching
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_pm_rpm
results: parsing output: 111/ for test: kms_cursor_edge_walk
results: parsing output: 112/ for test: gem_exec_reloc
results: parsing output: 113/ for test: kms_flip_scaled_crc
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_addfb_basic
results: parsing output: 117/ for test: gem_userptr_blits
results: parsing output: 118/ for test: gem_mmap_gtt
results: parsing output: 119/ for test: sysfs_heartbeat_interval
results: parsing output: 120/ for test: api_intel_bb
results: parsing output: 121/ for test: syncobj_eventfd
results: parsing output: 122/ for test: sw_sync
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: gem_exec_reloc
results: parsing output: 127/ for test: gem_close_race
results: parsing output: 128/ for test: gem_userptr_blits
results: parsing output: 129/ for test: gem_eio
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.