[37.104718] Initializing watchdogs
[37.104764]   /dev/watchdog0
[37.111581] [001/130] (960s left) kms_hdmi_inject (inject-audio)
[37.450724] Starting subtest: inject-audio
[37.453261] Subtest inject-audio: SKIP (0.000s)
[38.407410] [002/130] (959s left) syncobj_wait (wait-all-for-submit-snapshot)
[38.444915] Starting subtest: wait-all-for-submit-snapshot
[38.528860] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[38.606615] [003/130] (959s left) syncobj_wait (wait-all-for-submit-complex)
[38.640261] Starting subtest: wait-all-for-submit-complex
[38.866461] Subtest wait-all-for-submit-complex: SUCCESS (0.217s)
[38.932675] [004/130] (958s left) kms_plane (plane-position-hole-dpms)
[38.993921] Starting subtest: plane-position-hole-dpms
[39.596601] Starting dynamic subtest: pipe-A-plane-1
[42.332912] Dynamic subtest pipe-A-plane-1: SUCCESS (2.736s)
[42.346130] Starting dynamic subtest: pipe-A-plane-2
[45.082462] Dynamic subtest pipe-A-plane-2: SUCCESS (2.749s)
[45.094036] Starting dynamic subtest: pipe-A-plane-3
[47.719753] Dynamic subtest pipe-A-plane-3: SUCCESS (2.636s)
[47.725740] Starting dynamic subtest: pipe-A-plane-4
[50.443891] Dynamic subtest pipe-A-plane-4: SUCCESS (2.725s)
[50.456952] Starting dynamic subtest: pipe-A-plane-5
[50.462125] Dynamic subtest pipe-A-plane-5: SUCCESS (0.017s)
[51.849737] Starting dynamic subtest: pipe-B-plane-1
[54.490767] Dynamic subtest pipe-B-plane-1: SUCCESS (2.652s)
[54.501192] Starting dynamic subtest: pipe-B-plane-2
[57.189075] Dynamic subtest pipe-B-plane-2: SUCCESS (2.698s)
[57.195217] Starting dynamic subtest: pipe-B-plane-3
[59.881511] Dynamic subtest pipe-B-plane-3: SUCCESS (2.689s)
[59.887399] Starting dynamic subtest: pipe-B-plane-4
[62.562257] Dynamic subtest pipe-B-plane-4: SUCCESS (2.761s)
[62.573181] Starting dynamic subtest: pipe-B-plane-5
[62.576962] Dynamic subtest pipe-B-plane-5: SUCCESS (0.015s)
[62.577929] Subtest plane-position-hole-dpms: SUCCESS (23.661s)
[63.482215] [005/130] (934s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[63.545679] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[69.349417] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (5.937s)
[69.459146] [006/130] (927s left) kms_rotation_crc (sprite-rotation-180)
[69.522577] Starting subtest: sprite-rotation-180
[75.853823] Subtest sprite-rotation-180: SUCCESS (6.398s)
[77.125840] [007/130] (920s left) kms_psr (fbc-psr-sprite-blt)
[77.185562] Starting subtest: fbc-psr-sprite-blt
[77.186591] Starting dynamic subtest: eDP-1
[77.188412] Dynamic subtest eDP-1: SKIP (0.000s)
[77.190151] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[77.255879] [008/130] (920s left) gem_exec_capture (capture)
[77.294011] Starting subtest: capture
[77.294939] Starting dynamic subtest: rcs0-smem
[78.115361] Dynamic subtest rcs0-smem: SUCCESS (0.826s)
[78.121242] Starting dynamic subtest: bcs0-smem
[78.934670] Dynamic subtest bcs0-smem: SUCCESS (0.823s)
[78.940717] Starting dynamic subtest: vcs0-smem
[80.268921] Dynamic subtest vcs0-smem: SUCCESS (1.339s)
[80.274663] Starting dynamic subtest: vecs0-smem
[81.337549] Dynamic subtest vecs0-smem: FAIL (1.055s)
[81.338242] Subtest capture: FAIL (4.043s)
[81.426921] [009/130] (915s left) gem_ctx_persistence (hostile)
[81.461896] Starting subtest: hostile
[82.936887] Subtest hostile: FAIL (1.450s)
[83.049023] [010/130] (914s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[83.108700] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[90.467810] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.370s)
[90.590734] [011/130] (906s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[90.654837] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[93.908216] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.254s)
[94.067645] [012/130] (903s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[94.107437] Starting subtest: cpuset-medium-copy-odd
[100.793686] Subtest cpuset-medium-copy-odd: SUCCESS (6.685s)
[100.873141] [013/130] (896s left) prime_vgem (fence-flip-hang)
[101.002426] Subtest fence-flip-hang: SKIP (0.000s)
[101.092281] [014/130] (896s left) i915_pm_rps (thresholds)
[101.131223] Starting subtest: thresholds
[101.132855] Subtest thresholds: SKIP (0.001s)
[101.209315] [015/130] (896s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[101.281496] Starting subtest: y-tiled-64bpp-rotate-0
[103.233598] Subtest y-tiled-64bpp-rotate-0: SUCCESS (1.952s)
[103.402608] [016/130] (894s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[103.462330] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[103.464067] Starting dynamic subtest: pipe-A-default-mode
[106.590906] Dynamic subtest pipe-A-default-mode: SUCCESS (3.127s)
[106.591721] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (3.129s)
[107.528210] [017/130] (889s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[107.583820] Starting subtest: fbc-pr-sprite-mmap-cpu
[107.585479] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[107.657641] [018/130] (889s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[107.752211] Starting subtest: cursorB-vs-flipA-toggle
[107.756027] Subtest cursorB-vs-flipA-toggle: SKIP (0.003s)
[107.894267] [019/130] (889s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[107.960358] Starting subtest: yf-tiled-32bpp-rotate-90
[107.961814] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[108.040856] [020/130] (889s left) gem_ccs (suspend-resume)
[108.073367] Starting subtest: suspend-resume
[108.075706] Subtest suspend-resume: SKIP (0.000s)
[108.141718] [021/130] (889s left) syncobj_timeline (32bits-limit)
[108.171666] Starting subtest: 32bits-limit
[108.172483] Subtest 32bits-limit: SUCCESS (0.001s)
[108.238541] [022/130] (889s left) kms_psr (psr-cursor-blt)
[108.288952] Starting subtest: psr-cursor-blt
[108.289845] Starting dynamic subtest: eDP-1
[110.231486] Dynamic subtest eDP-1: SUCCESS (1.940s)
[110.232722] Subtest psr-cursor-blt: SUCCESS (1.941s)
[111.176987] [023/130] (886s left) kms_cursor_crc (cursor-onscreen-128x128)
[111.239193] Starting subtest: cursor-onscreen-128x128
[111.243617] Starting dynamic subtest: pipe-A-eDP-1
[113.709608] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.466s)
[113.744214] Starting dynamic subtest: pipe-C-eDP-1
[116.934156] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.196s)
[116.934890] Subtest cursor-onscreen-128x128: SUCCESS (5.694s)
[117.898877] [024/130] (879s left) syncobj_wait (multi-wait-submitted-signaled)
[117.938499] Starting subtest: multi-wait-submitted-signaled
[117.939345] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[118.012139] [025/130] (879s left) gem_tiled_blits (normal)
[118.126223] Starting subtest: normal
[141.234209] Subtest normal: SUCCESS (23.097s)
[141.345611] [026/130] (856s left) api_intel_bb (intel-bb-blit-none)
[141.426421] Starting subtest: intel-bb-blit-none
[145.670490] Subtest intel-bb-blit-none: SUCCESS (4.235s)
[145.779227] [027/130] (851s left) syncobj_wait (signal)
[145.823988] Starting subtest: signal
[145.825111] Subtest signal: SUCCESS (0.000s)
[145.889400] [028/130] (851s left) prime_mmap (test_reprime)
[145.919985] Starting subtest: test_reprime
[145.920749] Starting dynamic subtest: test_reprime-smem
[145.961688] Dynamic subtest test_reprime-smem: SUCCESS (0.042s)
[145.962523] Subtest test_reprime: SUCCESS (0.043s)
[146.039626] [029/130] (851s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[146.112476] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[146.114032] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[146.191553] [030/130] (851s left) kms_chamelium_hpd (vga-hpd-fast)
[146.563883] Subtest vga-hpd-fast: SKIP (0.000s)
[147.491872] [031/130] (850s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[147.588679] Starting subtest: basic-flip-before-cursor-atomic
[148.817278] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.229s)
[149.770479] [032/130] (847s left) prime_mmap_kms (buffer-sharing)
[149.834099] Starting subtest: buffer-sharing
[150.309169] Subtest buffer-sharing: SUCCESS (0.475s)
[151.239946] [033/130] (846s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[154.842830] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[154.848989] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.004s)
[155.784886] [034/130] (841s left) kms_vblank (query-forked-hang)
[155.844949] Starting subtest: query-forked-hang
[155.848021] Starting dynamic subtest: pipe-A-eDP-1
[182.878526] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.033s)
[182.886542] Starting dynamic subtest: pipe-C-eDP-1
[207.839586] Dynamic subtest pipe-C-eDP-1: SUCCESS (24.959s)
[207.840404] Subtest query-forked-hang: SUCCESS (51.996s)
[208.774535] [035/130] (789s left) gem_bad_reloc (negative-reloc-bltcopy)
[208.814574] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[208.879912] [036/130] (788s left) gem_userptr_blits (create-destroy-unsync)
[208.913866] Subtest create-destroy-unsync: SKIP (0.000s)
[209.025159] [037/130] (788s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[212.640578] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[212.648704] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.006s)
[213.575332] [038/130] (784s left) fbdev (nullptr)
[213.613144] Starting subtest: nullptr
[213.614184] Subtest nullptr: SUCCESS (0.000s)
[213.669435] [039/130] (784s left) kms_psr (fbc-psr2-cursor-blt)
[213.725738] Starting subtest: fbc-psr2-cursor-blt
[213.726863] Starting dynamic subtest: eDP-1
[213.728711] Dynamic subtest eDP-1: SKIP (0.000s)
[213.730281] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[213.808559] [040/130] (784s left) core_setmaster (master-drop-set-root)
[214.451249] Starting subtest: master-drop-set-root
[214.463319] Subtest master-drop-set-root: SUCCESS (0.010s)
[214.493247] [041/130] (783s left) i915_pm_rpm (system-suspend-devices)
[216.164411] Starting subtest: system-suspend-devices
[223.745586] Subtest system-suspend-devices: SUCCESS (7.581s)
[224.190594] [042/130] (773s left) kms_feature_discovery (display-1x)
[224.250964] Starting subtest: display-1x
[224.251896] Subtest display-1x: SUCCESS (0.000s)
[224.318198] [043/130] (773s left) dmabuf_sync_file (export-multiwait)
[224.357018] Starting subtest: export-multiwait
[224.357905] Subtest export-multiwait: SUCCESS (0.001s)
[224.420043] [044/130] (773s left) vgem_basic (create)
[224.457019] Starting subtest: create
[224.457847] Subtest create: SUCCESS (0.000s)
[224.517676] [045/130] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[224.583754] Starting subtest: psr-2p-primscrn-cur-indfb-move
[224.585200] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[224.669452] [046/130] (773s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[224.731089] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[224.733548] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[224.843688] [047/130] (773s left) gem_sync (basic-all)
[224.880917] Starting subtest: basic-all
[227.091186] Subtest basic-all: SUCCESS (2.209s)
[227.283374] [048/130] (770s left) kms_joiner (basic-big-joiner)
[227.339312] Starting subtest: basic-big-joiner
[227.340799] Subtest basic-big-joiner: SKIP (0.000s)
[227.415264] [049/130] (770s left) kms_cursor_crc (cursor-random-32x10)
[227.474385] Starting subtest: cursor-random-32x10
[227.535089] Subtest cursor-random-32x10: SKIP (0.059s)
[227.626440] [050/130] (770s left) syncobj_timeline (wait-all-interrupted)
[227.656458] Starting subtest: wait-all-interrupted
[227.759087] Subtest wait-all-interrupted: SUCCESS (0.102s)
[227.844034] [051/130] (770s left) syncobj_timeline (wait-for-submit-complex)
[227.874187] Starting subtest: wait-for-submit-complex
[227.937895] Subtest wait-for-submit-complex: SUCCESS (0.064s)
[228.017481] [052/130] (770s left) kms_plane_scaling (planes-downscale-factor-0-75)
[228.073098] Starting subtest: planes-downscale-factor-0-75
[228.073824] Starting dynamic subtest: pipe-A
[228.192865] Dynamic subtest pipe-A: SUCCESS (0.120s)
[228.212880] Starting dynamic subtest: pipe-B
[229.532395] Dynamic subtest pipe-B: SUCCESS (1.338s)
[229.553752] Starting dynamic subtest: pipe-C
[230.858382] Dynamic subtest pipe-C: SUCCESS (1.325s)
[230.859655] Subtest planes-downscale-factor-0-75: SUCCESS (2.786s)
[232.137203] [053/130] (765s left) kms_async_flips (alternate-sync-async-flip)
[232.193970] Starting subtest: alternate-sync-async-flip
[232.605358] Starting dynamic subtest: pipe-A-eDP-1
[235.460273] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.854s)
[235.887056] Starting dynamic subtest: pipe-B-eDP-1
[238.779103] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.906s)
[239.205115] Starting dynamic subtest: pipe-C-eDP-1
[242.107148] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.919s)
[242.108473] Subtest alternate-sync-async-flip: SUCCESS (9.913s)
[243.402919] [054/130] (754s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[243.471301] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[243.473395] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[243.574050] [055/130] (754s left) kms_hdr (static-swap)
[243.626801] Starting subtest: static-swap
[244.819528] Starting dynamic subtest: pipe-A-eDP-1
[251.160925] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.342s)
[251.162296] Subtest static-swap: SUCCESS (7.534s)
[251.259633] [056/130] (746s left) gem_ppgtt (shrink-vs-evict-any)
[251.297587] Starting subtest: shrink-vs-evict-any
[259.021571] Subtest shrink-vs-evict-any: SUCCESS (7.690s)
[259.054979] [057/130] (739s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[259.123617] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[260.686142] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.562s)
[260.814703] [058/130] (737s left) gem_mmap_gtt (bad-object)
[260.847596] Starting subtest: bad-object
[260.848329] Subtest bad-object: SUCCESS (0.000s)
[260.929912] [059/130] (737s left) kms_cursor_edge_walk (128x128-right-edge)
[260.984941] Starting subtest: 128x128-right-edge
[260.987922] Starting dynamic subtest: pipe-A-eDP-1
[265.592041] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.603s)
[265.606835] Starting dynamic subtest: pipe-C-eDP-1
[269.877394] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.283s)
[269.878739] Subtest 128x128-right-edge: SUCCESS (8.891s)
[270.796196] [060/130] (727s left) gen3_render_tiledx_blits
[270.858781] [061/130] (727s left) gem_spin_batch (user-each)
[270.896476] Starting subtest: user-each
[274.152643] Subtest user-each: SUCCESS (3.250s)
[274.393574] [062/130] (723s left) gem_exec_schedule (implicit-write-read)
[274.438069] Starting subtest: implicit-write-read
[274.438892] Starting dynamic subtest: rcs0
[274.517404] Dynamic subtest rcs0: SUCCESS (0.078s)
[274.535154] Starting dynamic subtest: bcs0
[274.601880] Dynamic subtest bcs0: SUCCESS (0.083s)
[274.618822] Starting dynamic subtest: vcs0
[274.684025] Dynamic subtest vcs0: SUCCESS (0.081s)
[274.700093] Starting dynamic subtest: vecs0
[274.765842] Dynamic subtest vecs0: SUCCESS (0.080s)
[274.767388] Subtest implicit-write-read: SUCCESS (0.328s)
[275.132198] [063/130] (723s left) gem_ctx_isolation (dirty-create)
[275.172931] Starting subtest: dirty-create
[275.173934] Starting dynamic subtest: rcs0
[275.321989] Dynamic subtest rcs0: SUCCESS (0.150s)
[275.331708] Starting dynamic subtest: bcs0
[275.528181] Dynamic subtest bcs0: SUCCESS (0.203s)
[275.537501] Starting dynamic subtest: vcs0
[275.718749] Dynamic subtest vcs0: SUCCESS (0.192s)
[275.727727] Starting dynamic subtest: vecs0
[275.938623] Dynamic subtest vecs0: SUCCESS (0.219s)
[275.939521] Subtest dirty-create: SUCCESS (0.768s)
[276.172287] [064/130] (722s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[276.233421] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[276.235129] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[276.354582] [065/130] (721s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[276.407614] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[276.408458] Starting dynamic subtest: pipe-A-default-mode
[277.731962] Dynamic subtest pipe-A-default-mode: SKIP (1.321s)
[277.734117] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (1.324s)
[277.914062] [066/130] (720s left) kms_chamelium_edid (dp-edid-resolution-list)
[278.283542] Subtest dp-edid-resolution-list: SKIP (0.000s)
[279.204356] [067/130] (719s left) kms_psr (pr-sprite-render)
[279.264790] Starting subtest: pr-sprite-render
[279.266645] Subtest pr-sprite-render: SKIP (0.000s)
[279.353796] [068/130] (719s left) syncobj_wait (invalid-reset-bad-pad)
[279.387714] Starting subtest: invalid-reset-bad-pad
[279.388784] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[279.457592] [069/130] (718s left) i915_hangman (error-state-basic)
[279.499224] Starting subtest: error-state-basic
[279.622995] Subtest error-state-basic: SUCCESS (0.124s)
[279.708065] [070/130] (718s left) perf_pmu (render-node-busy)
[279.746157] Starting subtest: render-node-busy
[279.746925] Starting dynamic subtest: rcs0
[280.347215] Dynamic subtest rcs0: SUCCESS (0.600s)
[280.365698] Starting dynamic subtest: bcs0
[280.906269] Dynamic subtest bcs0: SUCCESS (0.557s)
[280.917348] Starting dynamic subtest: vcs0
[281.460217] Dynamic subtest vcs0: SUCCESS (0.552s)
[281.480166] Starting dynamic subtest: vecs0
[282.013349] Dynamic subtest vecs0: SUCCESS (0.551s)
[282.015355] Subtest render-node-busy: SUCCESS (2.268s)
[282.099893] [071/130] (716s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[282.159187] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[282.160651] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[282.247699] [072/130] (716s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[282.309035] Starting subtest: 4-tiled-32bpp-rotate-180
[282.310570] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.000s)
[282.397975] [073/130] (716s left) kms_flip (2x-plain-flip)
[282.453781] Starting subtest: 2x-plain-flip
[282.458619] Subtest 2x-plain-flip: SKIP (0.004s)
[282.547981] [074/130] (716s left) gem_mmap_gtt (basic-copy)
[282.577650] Starting subtest: basic-copy
[283.478388] Subtest basic-copy: SUCCESS (0.893s)
[283.561017] [075/130] (715s left) gem_exec_schedule (preempt-user)
[283.600315] Starting subtest: preempt-user
[283.601120] Starting dynamic subtest: rcs0
[283.619109] Dynamic subtest rcs0: SUCCESS (0.018s)
[283.628910] Starting dynamic subtest: bcs0
[283.636808] Dynamic subtest bcs0: SUCCESS (0.017s)
[283.646977] Starting dynamic subtest: vcs0
[283.654580] Dynamic subtest vcs0: SUCCESS (0.017s)
[283.664624] Starting dynamic subtest: vecs0
[283.672510] Dynamic subtest vecs0: SUCCESS (0.017s)
[283.673316] Subtest preempt-user: SUCCESS (0.073s)
[284.026436] [076/130] (714s left) gem_exec_balancer (full-late)
[284.064619] Starting subtest: full-late
[285.124089] Subtest full-late: SUCCESS (1.060s)
[286.170868] [077/130] (712s left) gem_exec_reloc (basic-gtt-cpu-active)
[286.205233] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[286.260034] [078/130] (712s left) core_auth (basic-auth)
[286.291137] Starting subtest: basic-auth
[286.291945] Subtest basic-auth: SUCCESS (0.001s)
[286.368211] [079/130] (712s left) gem_workarounds (suspend-resume-fd)
[286.410123] Starting subtest: suspend-resume-fd
[302.762008] Subtest suspend-resume-fd: SUCCESS (2.317s)
[302.850894] [080/130] (709s left) gem_render_copy_redux (normal)
[302.885119] Starting subtest: normal
[303.299365] Subtest normal: SUCCESS (0.414s)
[303.408908] [081/130] (709s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[303.466129] Starting subtest: compare-crc-sanitycheck-xr24
[303.467107] Starting dynamic subtest: pipe-A-eDP-1
[304.220944] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.754s)
[304.243223] Starting dynamic subtest: pipe-B-eDP-1
[305.730080] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.507s)
[305.751817] Starting dynamic subtest: pipe-C-eDP-1
[307.173352] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.455s)
[307.174278] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.722s)
[308.110853] [082/130] (704s left) gem_tiled_partial_pwrite_pread (reads)
[308.144133] Subtest reads: SKIP (0.000s)
[308.203369] [083/130] (704s left) gem_exec_params (dr1-dirt)
[308.236184] Starting subtest: DR1-dirt
[308.237016] Subtest DR1-dirt: SUCCESS (0.000s)
[308.315446] [084/130] (704s left) gen7_exec_parse (cmd-crossing-page)
[308.349811] Subtest cmd-crossing-page: SKIP (0.000s)
[308.379077] [085/130] (704s left) kms_cdclk (mode-transition)
[308.434311] Starting subtest: mode-transition
[308.435311] Starting dynamic subtest: pipe-A-eDP-1
[308.750875] Dynamic subtest pipe-A-eDP-1: SKIP (0.329s)
[308.766974] Starting dynamic subtest: pipe-B-eDP-1
[308.769151] Dynamic subtest pipe-B-eDP-1: SKIP (0.003s)
[308.788006] Starting dynamic subtest: pipe-C-eDP-1
[308.789654] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[308.792422] Subtest mode-transition: SKIP (0.340s)
[309.645695] [086/130] (703s left) gem_exec_whisper (basic-fds-forked)
[309.686745] Starting subtest: basic-fds-forked
[315.565702] Subtest basic-fds-forked: SUCCESS (6.003s)
[315.752833] [087/130] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[315.812643] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[315.814276] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[315.920297] [088/130] (696s left) gem_exec_balancer (bonded-false-hang)
[316.909361] Starting subtest: bonded-false-hang
[316.911009] Subtest bonded-false-hang: SKIP (0.000s)
[316.995037] [089/130] (695s left) gem_exec_fence (basic-busy)
[317.152280] Starting subtest: basic-busy
[317.153635] Starting dynamic subtest: rcs0
[317.239721] Dynamic subtest rcs0: SUCCESS (0.091s)
[317.257880] Starting dynamic subtest: bcs0
[317.294857] Dynamic subtest bcs0: SUCCESS (0.054s)
[317.314626] Starting dynamic subtest: vcs0
[317.341138] Dynamic subtest vcs0: SUCCESS (0.046s)
[317.358464] Starting dynamic subtest: vecs0
[317.390729] Dynamic subtest vecs0: SUCCESS (0.048s)
[317.392178] Subtest basic-busy: SUCCESS (0.246s)
[317.872858] [090/130] (694s left) gem_reg_read (bad-register)
[317.910754] Starting subtest: bad-register
[317.911474] Subtest bad-register: SUCCESS (0.000s)
[317.985589] [091/130] (694s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[318.045338] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[318.047429] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[318.139554] [092/130] (694s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[318.198228] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[318.200704] Starting dynamic subtest: pipe-A-default-mode
[318.507782] Dynamic subtest pipe-A-default-mode: SKIP (0.309s)
[318.510038] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.312s)
[319.446209] [093/130] (693s left) perf (create-destroy-userspace-config)
[319.549104] Starting subtest: create-destroy-userspace-config
[319.566328] Subtest create-destroy-userspace-config: SUCCESS (0.018s)
[319.595394] [094/130] (693s left) kms_pm_backlight (fade-with-dpms)
[319.651243] Starting subtest: fade-with-dpms
[321.852932] Starting dynamic subtest: eDP-1
[324.468921] Dynamic subtest eDP-1: SUCCESS (2.631s)
[324.470242] Subtest fade-with-dpms: SUCCESS (4.850s)
[325.396714] [095/130] (687s left) gem_spin_batch (engines)
[325.434681] Starting subtest: engines
[325.435857] Starting dynamic subtest: rcs0
[328.678278] Dynamic subtest rcs0: SUCCESS (3.244s)
[328.701844] Starting dynamic subtest: bcs0
[331.938052] Dynamic subtest bcs0: SUCCESS (3.241s)
[331.950679] Starting dynamic subtest: vcs0
[335.147004] Dynamic subtest vcs0: SUCCESS (3.238s)
[335.170062] Starting dynamic subtest: vecs0
[338.407029] Dynamic subtest vecs0: SUCCESS (3.237s)
[338.408400] Subtest engines: SUCCESS (12.967s)
[338.616038] [096/130] (674s left) kms_vrr (flip-basic)
[338.679000] Starting subtest: flip-basic
[338.681993] Subtest flip-basic: SKIP (0.000s)
[338.762457] [097/130] (673s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[338.867362] Starting subtest: short-busy-flip-before-cursor-toggle
[338.869561] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[339.011178] [098/130] (673s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[339.117073] Starting subtest: cursorA-vs-flipA-legacy
[350.219126] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.095s)
[351.130518] [099/130] (661s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[351.171012] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[351.171817] Starting dynamic subtest: smem
[351.265254] Dynamic subtest smem: SUCCESS (0.094s)
[351.266254] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (0.094s)
[351.486470] [100/130] (661s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[351.543579] Starting subtest: crc-primary-basic-y-tiled-ccs
[351.544738] Starting dynamic subtest: pipe-A-eDP-1
[351.553770] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[351.564773] Starting dynamic subtest: pipe-B-eDP-1
[351.571793] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[351.580758] Starting dynamic subtest: pipe-C-eDP-1
[351.587689] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[351.589023] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.008s)
[351.616349] [101/130] (661s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[351.673130] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[351.675144] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[351.773601] [102/130] (661s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[351.835886] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[353.276454] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.440s)
[353.396512] [103/130] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[353.460416] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[353.462133] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[353.555467] [104/130] (659s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[353.609005] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[353.609907] Starting dynamic subtest: pipe-A-eDP-1
[353.617446] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[353.627263] Starting dynamic subtest: pipe-B-eDP-1
[353.634310] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[353.643236] Starting dynamic subtest: pipe-C-eDP-1
[353.650002] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[353.651299] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[353.684961] [105/130] (659s left) perf_pmu (busy-double-start)
[353.714040] Starting subtest: busy-double-start
[353.715046] Starting dynamic subtest: rcs0
[357.314981] Dynamic subtest rcs0: FAIL (3.583s)
[357.331890] Starting dynamic subtest: bcs0
[360.856866] Dynamic subtest bcs0: SUCCESS (3.558s)
[360.868283] Starting dynamic subtest: vcs0
[364.457161] Dynamic subtest vcs0: FAIL (3.557s)
[364.472722] Starting dynamic subtest: vecs0
[368.010628] Dynamic subtest vecs0: FAIL (3.555s)
[368.011738] Subtest busy-double-start: FAIL (14.257s)
[368.083810] [106/130] (644s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[368.143262] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[368.144312] Starting dynamic subtest: pipe-A-eDP-1
[368.152649] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[368.162235] Starting dynamic subtest: pipe-B-eDP-1
[368.169519] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[368.179251] Starting dynamic subtest: pipe-C-eDP-1
[368.186335] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[368.187820] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.006s)
[368.207022] [107/130] (644s left) gem_mmap_gtt (basic)
[368.236568] Starting subtest: basic
[368.237422] Subtest basic: SUCCESS (0.001s)
[368.302029] [108/130] (644s left) gem_exec_params (sol-reset-not-gen7)
[368.334162] Starting subtest: sol-reset-not-gen7
[368.336117] Subtest sol-reset-not-gen7: SUCCESS (0.001s)
[368.431666] [109/130] (644s left) kms_prop_blob (basic)
[368.464136] Starting subtest: basic
[368.464939] Subtest basic: SUCCESS (0.000s)
[368.532333] [110/130] (644s left) perf_pmu (semaphore-wait-idle)
[368.562790] Starting subtest: semaphore-wait-idle
[368.563622] Starting dynamic subtest: rcs0
[369.082650] Dynamic subtest rcs0: SUCCESS (0.510s)
[369.104668] Starting dynamic subtest: bcs0
[369.593817] Dynamic subtest bcs0: SUCCESS (0.509s)
[369.606430] Starting dynamic subtest: vcs0
[370.104890] Dynamic subtest vcs0: SUCCESS (0.509s)
[370.128931] Starting dynamic subtest: vecs0
[370.616926] Dynamic subtest vecs0: SUCCESS (0.510s)
[370.618602] Subtest semaphore-wait-idle: SUCCESS (2.046s)
[370.734253] [111/130] (642s left) gem_exec_fence (syncobj-timeline-signal)
[371.148007] Starting subtest: syncobj-timeline-signal
[371.149324] Subtest syncobj-timeline-signal: SUCCESS (0.004s)
[371.366225] [112/130] (641s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[371.426821] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[371.428429] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[371.542531] [113/130] (641s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[371.601227] Starting subtest: sprite-rotation-90-pos-100-0
[371.603051] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[371.674217] [114/130] (641s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[374.234325] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[374.311732] [115/130] (638s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[374.371200] Starting subtest: multiplane-rotation-cropping-bottom
[384.840504] Subtest multiplane-rotation-cropping-bottom: SUCCESS (10.473s)
[384.972871] [116/130] (628s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[385.009014] Starting subtest: invalid-single-wait-unsubmitted
[385.009925] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[385.086674] [117/130] (627s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[385.145703] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[388.553343] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (3.408s)
[388.682494] [118/130] (624s left) syncobj_timeline (multi-wait-all-signaled)
[388.717987] Starting subtest: multi-wait-all-signaled
[388.718833] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[388.798229] [119/130] (624s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[388.905606] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[398.883032] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.979s)
[399.834615] [120/130] (613s left) kms_flip (basic-flip-vs-modeset)
[399.893569] Starting subtest: basic-flip-vs-modeset
[399.895135] Starting dynamic subtest: A-eDP1
[402.680414] Dynamic subtest A-eDP1: SUCCESS (2.785s)
[402.701927] Starting dynamic subtest: B-eDP1
[405.112772] Dynamic subtest B-eDP1: SUCCESS (2.430s)
[405.134649] Starting dynamic subtest: C-eDP1
[407.512251] Dynamic subtest C-eDP1: SUCCESS (2.397s)
[407.517994] Subtest basic-flip-vs-modeset: SUCCESS (7.621s)
[408.474325] [121/130] (604s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[408.577868] Starting subtest: flip-vs-cursor-crc-atomic
[409.476577] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.898s)
[410.414540] [122/130] (602s left) gem_exec_whisper (basic-queues-all)
[410.456928] Starting subtest: basic-queues-all
[416.098099] Subtest basic-queues-all: SUCCESS (5.638s)
[416.294281] [123/130] (596s left) prime_mmap (test_forked_cpu_write)
[416.328438] Starting subtest: test_forked_cpu_write
[416.329278] Starting dynamic subtest: test_forked_cpu_write-smem
[416.335521] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[416.336346] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[416.443924] [124/130] (596s left) kms_color (invalid-ctm-matrix-sizes)
[416.500913] Starting subtest: invalid-ctm-matrix-sizes
[416.501759] Starting dynamic subtest: pipe-A
[416.503506] Dynamic subtest pipe-A: SUCCESS (0.004s)
[416.514755] Starting dynamic subtest: pipe-B
[416.515685] Dynamic subtest pipe-B: SUCCESS (0.005s)
[416.525098] Starting dynamic subtest: pipe-C
[416.525845] Dynamic subtest pipe-C: SUCCESS (0.006s)
[416.526575] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.017s)
[416.590346] [125/130] (596s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[416.649076] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[416.650864] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[416.743815] [126/130] (596s left) kms_pm_backlight (basic-brightness)
[416.799399] Starting subtest: basic-brightness
[416.849388] Starting dynamic subtest: eDP-1
[417.179494] Dynamic subtest eDP-1: SUCCESS (0.330s)
[417.180474] Subtest basic-brightness: SUCCESS (0.381s)
[418.136338] [127/130] (595s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[418.194415] Starting subtest: extended-pageflip-modeset-hang-oldfb
[418.196852] Starting dynamic subtest: pipe-A
[438.990505] Dynamic subtest pipe-A: SUCCESS (20.788s)
[439.012341] Starting dynamic subtest: pipe-C
[447.711940] Dynamic subtest pipe-C: SUCCESS (8.716s)
[447.713006] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (29.516s)
[448.664989] [128/130] (564s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[448.726122] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[452.416934] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (3.690s)
[452.545562] [129/130] (560s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[452.582007] Starting subtest: etime-single-wait-all-available-unsubmitted
[452.689823] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.106s)
[452.775054] [130/130] (560s left) gem_ctx_persistence (engines-hostile-preempt)
[452.805353] Starting subtest: engines-hostile-preempt
[452.806147] Starting dynamic subtest: rcs0
[452.851712] Dynamic subtest rcs0: SUCCESS (0.045s)
[452.871728] Starting dynamic subtest: bcs0
[452.872529] Dynamic subtest bcs0: SUCCESS (0.017s)
[452.881627] Starting dynamic subtest: vcs0
[452.899324] Dynamic subtest vcs0: SUCCESS (0.028s)
[452.915918] Starting dynamic subtest: vecs0
[452.930613] Dynamic subtest vecs0: SUCCESS (0.030s)
[452.931977] Subtest engines-hostile-preempt: SUCCESS (0.125s)
[453.055943] Closing watchdogs
Done.