[38.125942] Initializing watchdogs
[38.126014]   /dev/watchdog0
[38.131588] [001/130] (960s left) kms_hdmi_inject (inject-audio)
[38.476467] Starting subtest: inject-audio
[38.479880] Subtest inject-audio: SKIP (0.000s)
[39.616619] [002/130] (959s left) syncobj_wait (wait-all-for-submit-snapshot)
[39.651487] Starting subtest: wait-all-for-submit-snapshot
[39.735947] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s)
[39.782911] [003/130] (958s left) syncobj_wait (wait-all-for-submit-complex)
[39.817578] Starting subtest: wait-all-for-submit-complex
[40.045112] Subtest wait-all-for-submit-complex: SUCCESS (0.218s)
[40.086059] [004/130] (958s left) kms_plane (plane-position-hole-dpms)
[40.146870] Starting subtest: plane-position-hole-dpms
[40.656128] Starting dynamic subtest: pipe-A-plane-1
[43.494062] Dynamic subtest pipe-A-plane-1: SUCCESS (2.838s)
[43.506213] Starting dynamic subtest: pipe-A-plane-2
[46.106278] Dynamic subtest pipe-A-plane-2: SUCCESS (2.612s)
[46.117782] Starting dynamic subtest: pipe-A-plane-3
[48.725756] Dynamic subtest pipe-A-plane-3: SUCCESS (2.617s)
[48.731627] Starting dynamic subtest: pipe-A-plane-4
[51.300860] Dynamic subtest pipe-A-plane-4: SUCCESS (2.578s)
[51.311818] Starting dynamic subtest: pipe-A-plane-5
[51.312598] Dynamic subtest pipe-A-plane-5: SUCCESS (0.006s)
[52.592500] Starting dynamic subtest: pipe-B-plane-1
[55.148452] Dynamic subtest pipe-B-plane-1: SUCCESS (2.565s)
[55.162983] Starting dynamic subtest: pipe-B-plane-2
[57.682330] Dynamic subtest pipe-B-plane-2: SUCCESS (2.534s)
[57.697258] Starting dynamic subtest: pipe-B-plane-3
[60.315136] Dynamic subtest pipe-B-plane-3: SUCCESS (2.632s)
[60.329215] Starting dynamic subtest: pipe-B-plane-4
[62.968281] Dynamic subtest pipe-B-plane-4: SUCCESS (2.581s)
[62.980706] Starting dynamic subtest: pipe-B-plane-5
[62.981729] Dynamic subtest pipe-B-plane-5: SUCCESS (0.007s)
[62.982720] Subtest plane-position-hole-dpms: SUCCESS (22.756s)
[63.850297] [005/130] (934s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[63.924143] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[70.325733] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (6.310s)
[70.435209] [006/130] (928s left) kms_rotation_crc (sprite-rotation-180)
[70.494550] Starting subtest: sprite-rotation-180
[74.500441] Subtest sprite-rotation-180: SUCCESS (3.978s)
[75.760432] [007/130] (923s left) kms_psr (fbc-psr-sprite-blt)
[75.826285] Starting subtest: fbc-psr-sprite-blt
[75.827322] Starting dynamic subtest: eDP-1
[75.829232] Dynamic subtest eDP-1: SKIP (0.000s)
[75.830799] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[75.875805] [008/130] (923s left) gem_exec_capture (capture)
[75.916832] Starting subtest: capture
[75.917624] Starting dynamic subtest: rcs0-smem
[76.737881] Dynamic subtest rcs0-smem: SUCCESS (0.817s)
[76.744173] Starting dynamic subtest: bcs0-smem
[78.068555] Dynamic subtest bcs0-smem: SUCCESS (1.326s)
[78.075279] Starting dynamic subtest: vcs0-smem
[78.903217] Dynamic subtest vcs0-smem: SUCCESS (0.832s)
[78.909662] Starting dynamic subtest: vecs0-smem
[80.288479] Dynamic subtest vecs0-smem: FAIL (1.364s)
[80.289178] Subtest capture: FAIL (4.340s)
[80.358317] [009/130] (918s left) gem_ctx_persistence (hostile)
[80.389690] Starting subtest: hostile
[81.910283] Subtest hostile: FAIL (1.488s)
[81.985652] [010/130] (917s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[82.052217] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[88.998533] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (6.937s)
[89.082651] [011/130] (909s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[89.143486] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[92.553296] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.407s)
[93.819495] [012/130] (905s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[93.859377] Starting subtest: cpuset-medium-copy-odd
[100.515607] Subtest cpuset-medium-copy-odd: SUCCESS (6.652s)
[100.573079] [013/130] (898s left) prime_vgem (fence-flip-hang)
[100.704138] Subtest fence-flip-hang: SKIP (0.000s)
[100.779349] [014/130] (898s left) i915_pm_rps (thresholds)
[100.819944] Starting subtest: thresholds
[100.823298] Subtest thresholds: SKIP (0.002s)
[100.892093] [015/130] (898s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[100.964568] Starting subtest: y-tiled-64bpp-rotate-0
[102.488277] Subtest y-tiled-64bpp-rotate-0: SUCCESS (1.523s)
[102.581772] [016/130] (896s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[102.637900] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[102.639271] Starting dynamic subtest: pipe-A-default-mode
[105.605297] Dynamic subtest pipe-A-default-mode: SUCCESS (2.965s)
[105.606181] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (2.967s)
[106.542225] [017/130] (892s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[106.606361] Starting subtest: fbc-pr-sprite-mmap-cpu
[106.607929] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[106.654056] [018/130] (892s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[106.738831] Starting subtest: cursorB-vs-flipA-toggle
[106.741461] Subtest cursorB-vs-flipA-toggle: SKIP (0.002s)
[106.833610] [019/130] (892s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[106.896475] Starting subtest: yf-tiled-32bpp-rotate-90
[106.898107] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[106.968441] [020/130] (892s left) gem_ccs (suspend-resume)
[107.000435] Starting subtest: suspend-resume
[107.001940] Subtest suspend-resume: SKIP (0.000s)
[107.040299] [021/130] (892s left) syncobj_timeline (32bits-limit)
[107.071128] Starting subtest: 32bits-limit
[107.074562] Subtest 32bits-limit: SUCCESS (0.002s)
[107.116982] [022/130] (892s left) kms_psr (psr-cursor-blt)
[107.173937] Starting subtest: psr-cursor-blt
[107.174933] Starting dynamic subtest: eDP-1
[108.925456] Dynamic subtest eDP-1: SUCCESS (1.747s)
[108.926652] Subtest psr-cursor-blt: SUCCESS (1.748s)
[109.806527] [023/130] (889s left) kms_cursor_crc (cursor-onscreen-128x128)
[109.873181] Starting subtest: cursor-onscreen-128x128
[109.877409] Starting dynamic subtest: pipe-A-eDP-1
[111.324205] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.444s)
[111.373904] Starting dynamic subtest: pipe-C-eDP-1
[113.555816] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.188s)
[113.556613] Subtest cursor-onscreen-128x128: SUCCESS (3.682s)
[114.473291] [024/130] (884s left) syncobj_wait (multi-wait-submitted-signaled)
[114.516405] Starting subtest: multi-wait-submitted-signaled
[114.517188] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[114.558028] [025/130] (884s left) gem_tiled_blits (normal)
[114.652868] Starting subtest: normal
[139.573891] Subtest normal: SUCCESS (24.909s)
[139.627425] [026/130] (859s left) api_intel_bb (intel-bb-blit-none)
[139.713469] Starting subtest: intel-bb-blit-none
[143.795705] Subtest intel-bb-blit-none: SUCCESS (4.074s)
[143.880880] [027/130] (855s left) syncobj_wait (signal)
[143.925048] Starting subtest: signal
[143.925877] Subtest signal: SUCCESS (0.000s)
[143.974897] [028/130] (855s left) prime_mmap (test_reprime)
[144.012145] Starting subtest: test_reprime
[144.012920] Starting dynamic subtest: test_reprime-smem
[144.050979] Dynamic subtest test_reprime-smem: SUCCESS (0.042s)
[144.051792] Subtest test_reprime: SUCCESS (0.042s)
[144.139876] [029/130] (855s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[144.208938] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[144.210483] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[144.280496] [030/130] (855s left) kms_chamelium_hpd (vga-hpd-fast)
[144.642604] Subtest vga-hpd-fast: SKIP (0.000s)
[145.557670] [031/130] (853s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[145.641966] Starting subtest: basic-flip-before-cursor-atomic
[146.403284] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.761s)
[147.301909] [032/130] (852s left) prime_mmap_kms (buffer-sharing)
[147.364079] Starting subtest: buffer-sharing
[147.767625] Subtest buffer-sharing: SUCCESS (0.402s)
[148.654506] [033/130] (850s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[152.137887] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[152.147083] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.006s)
[153.070253] [034/130] (846s left) kms_vblank (query-forked-hang)
[153.126924] Starting subtest: query-forked-hang
[153.129372] Starting dynamic subtest: pipe-A-eDP-1
[179.867401] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.737s)
[179.881670] Starting dynamic subtest: pipe-C-eDP-1
[204.890161] Dynamic subtest pipe-C-eDP-1: SUCCESS (25.019s)
[204.890958] Subtest query-forked-hang: SUCCESS (51.761s)
[205.807908] [035/130] (793s left) gem_bad_reloc (negative-reloc-bltcopy)
[205.844711] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[205.893700] [036/130] (793s left) gem_userptr_blits (create-destroy-unsync)
[205.933189] Subtest create-destroy-unsync: SKIP (0.000s)
[206.032990] [037/130] (793s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[209.489817] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[209.494046] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.003s)
[210.415409] [038/130] (789s left) fbdev (nullptr)
[210.461926] Starting subtest: nullptr
[210.463307] Subtest nullptr: SUCCESS (0.000s)
[210.508758] [039/130] (789s left) kms_psr (fbc-psr2-cursor-blt)
[210.572501] Starting subtest: fbc-psr2-cursor-blt
[210.573423] Starting dynamic subtest: eDP-1
[210.575090] Dynamic subtest eDP-1: SKIP (0.000s)
[210.576464] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[210.624954] [040/130] (788s left) core_setmaster (master-drop-set-root)
[211.289403] Starting subtest: master-drop-set-root
[211.295072] Subtest master-drop-set-root: SUCCESS (0.006s)
[211.328859] [041/130] (788s left) i915_pm_rpm (system-suspend-devices)
[216.901229] Starting subtest: system-suspend-devices
[224.508602] Subtest system-suspend-devices: SUCCESS (7.607s)
[224.932413] [042/130] (774s left) kms_feature_discovery (display-1x)
[224.988459] Starting subtest: display-1x
[224.989400] Subtest display-1x: SUCCESS (0.000s)
[225.040114] [043/130] (774s left) dmabuf_sync_file (export-multiwait)
[225.075438] Starting subtest: export-multiwait
[225.076273] Subtest export-multiwait: SUCCESS (0.001s)
[225.114924] [044/130] (774s left) vgem_basic (create)
[225.142320] Starting subtest: create
[225.143061] Subtest create: SUCCESS (0.000s)
[225.180954] [045/130] (774s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[225.236788] Starting subtest: psr-2p-primscrn-cur-indfb-move
[225.238366] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[225.320479] [046/130] (774s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[225.377945] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[225.379516] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[225.445999] [047/130] (774s left) gem_sync (basic-all)
[225.481356] Starting subtest: basic-all
[227.683412] Subtest basic-all: SUCCESS (2.202s)
[227.847707] [048/130] (771s left) kms_joiner (basic-big-joiner)
[227.909466] Starting subtest: basic-big-joiner
[227.911379] Subtest basic-big-joiner: SKIP (0.000s)
[227.963992] [049/130] (771s left) kms_cursor_crc (cursor-random-32x10)
[228.020126] Starting subtest: cursor-random-32x10
[228.072443] Subtest cursor-random-32x10: SKIP (0.051s)
[228.141902] [050/130] (771s left) syncobj_timeline (wait-all-interrupted)
[228.175917] Starting subtest: wait-all-interrupted
[228.288978] Subtest wait-all-interrupted: SUCCESS (0.105s)
[228.336353] [051/130] (771s left) syncobj_timeline (wait-for-submit-complex)
[228.367967] Starting subtest: wait-for-submit-complex
[228.432635] Subtest wait-for-submit-complex: SUCCESS (0.064s)
[228.486630] [052/130] (771s left) kms_plane_scaling (planes-downscale-factor-0-75)
[228.545070] Starting subtest: planes-downscale-factor-0-75
[228.546210] Starting dynamic subtest: pipe-A
[229.742758] Dynamic subtest pipe-A: SUCCESS (1.198s)
[229.762865] Starting dynamic subtest: pipe-B
[231.024041] Dynamic subtest pipe-B: SUCCESS (1.278s)
[231.042685] Starting dynamic subtest: pipe-C
[232.291849] Dynamic subtest pipe-C: SUCCESS (1.269s)
[232.292680] Subtest planes-downscale-factor-0-75: SUCCESS (3.748s)
[233.584589] [053/130] (766s left) kms_async_flips (alternate-sync-async-flip)
[233.644468] Starting subtest: alternate-sync-async-flip
[234.020642] Starting dynamic subtest: pipe-A-eDP-1
[236.884327] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.863s)
[237.273382] Starting dynamic subtest: pipe-B-eDP-1
[240.153911] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.895s)
[240.539639] Starting dynamic subtest: pipe-C-eDP-1
[243.421301] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.899s)
[243.422460] Subtest alternate-sync-async-flip: SUCCESS (9.778s)
[244.719210] [054/130] (755s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[244.781900] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[244.783350] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[244.847265] [055/130] (755s left) kms_hdr (static-swap)
[244.902061] Starting subtest: static-swap
[244.905294] Subtest static-swap: SKIP (0.001s)
[244.955290] [056/130] (754s left) gem_ppgtt (shrink-vs-evict-any)
[244.984648] Starting subtest: shrink-vs-evict-any
[252.990920] Subtest shrink-vs-evict-any: SUCCESS (7.997s)
[253.058885] [057/130] (746s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[253.128114] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[254.188802] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.061s)
[254.312165] [058/130] (745s left) gem_mmap_gtt (bad-object)
[254.345514] Starting subtest: bad-object
[254.346307] Subtest bad-object: SUCCESS (0.000s)
[254.428229] [059/130] (745s left) kms_cursor_edge_walk (128x128-right-edge)
[254.482933] Starting subtest: 128x128-right-edge
[254.483976] Starting dynamic subtest: pipe-A-eDP-1
[257.523889] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.040s)
[257.537446] Starting dynamic subtest: pipe-C-eDP-1
[260.246200] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.721s)
[260.247443] Subtest 128x128-right-edge: SUCCESS (5.764s)
[261.170813] [060/130] (738s left) gen3_render_tiledx_blits
[261.241188] [061/130] (738s left) gem_spin_batch (user-each)
[261.278743] Starting subtest: user-each
[264.540834] Subtest user-each: SUCCESS (3.257s)
[264.737822] [062/130] (735s left) gem_exec_schedule (implicit-write-read)
[264.785786] Starting subtest: implicit-write-read
[264.786684] Starting dynamic subtest: rcs0
[264.911600] Dynamic subtest rcs0: SUCCESS (0.125s)
[264.924092] Starting dynamic subtest: bcs0
[265.001733] Dynamic subtest bcs0: SUCCESS (0.089s)
[265.017549] Starting dynamic subtest: vcs0
[265.094477] Dynamic subtest vcs0: SUCCESS (0.092s)
[265.108125] Starting dynamic subtest: vecs0
[265.186078] Dynamic subtest vecs0: SUCCESS (0.090s)
[265.187774] Subtest implicit-write-read: SUCCESS (0.401s)
[265.522116] [063/130] (734s left) gem_ctx_isolation (dirty-create)
[265.557925] Starting subtest: dirty-create
[265.558834] Starting dynamic subtest: rcs0
[265.727829] Dynamic subtest rcs0: SUCCESS (0.170s)
[265.737608] Starting dynamic subtest: bcs0
[265.952406] Dynamic subtest bcs0: SUCCESS (0.224s)
[265.962680] Starting dynamic subtest: vcs0
[266.172047] Dynamic subtest vcs0: SUCCESS (0.217s)
[266.181197] Starting dynamic subtest: vecs0
[266.394282] Dynamic subtest vecs0: SUCCESS (0.224s)
[266.395140] Subtest dirty-create: SUCCESS (0.837s)
[266.606218] [064/130] (733s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[266.672328] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[266.673960] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[266.755482] [065/130] (733s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[266.818531] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[266.819428] Starting dynamic subtest: pipe-A-default-mode
[272.042354] Dynamic subtest pipe-A-default-mode: SUCCESS (5.221s)
[272.043617] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SUCCESS (5.224s)
[272.929610] [066/130] (727s left) kms_chamelium_edid (dp-edid-resolution-list)
[273.293493] Subtest dp-edid-resolution-list: SKIP (0.000s)
[274.195759] [067/130] (725s left) kms_psr (pr-sprite-render)
[274.259921] Starting subtest: pr-sprite-render
[274.262204] Subtest pr-sprite-render: SKIP (0.000s)
[274.314840] [068/130] (725s left) syncobj_wait (invalid-reset-bad-pad)
[274.347430] Starting subtest: invalid-reset-bad-pad
[274.348180] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[274.400222] [069/130] (725s left) i915_hangman (error-state-basic)
[274.435238] Starting subtest: error-state-basic
[274.562602] Subtest error-state-basic: SUCCESS (0.128s)
[274.612539] [070/130] (725s left) perf_pmu (render-node-busy)
[274.646687] Starting subtest: render-node-busy
[274.647772] Starting dynamic subtest: rcs0
[275.232340] Dynamic subtest rcs0: SUCCESS (0.584s)
[275.249566] Starting dynamic subtest: bcs0
[275.798249] Dynamic subtest bcs0: SUCCESS (0.564s)
[275.810107] Starting dynamic subtest: vcs0
[276.361246] Dynamic subtest vcs0: SUCCESS (0.561s)
[276.382184] Starting dynamic subtest: vecs0
[276.914256] Dynamic subtest vecs0: SUCCESS (0.551s)
[276.916194] Subtest render-node-busy: SUCCESS (2.268s)
[276.971781] [071/130] (723s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[277.044617] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[277.046165] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[277.110028] [072/130] (723s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[277.173623] Starting subtest: 4-tiled-32bpp-rotate-180
[277.175422] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.000s)
[277.257336] [073/130] (723s left) kms_flip (2x-plain-flip)
[277.313973] Starting subtest: 2x-plain-flip
[277.318429] Subtest 2x-plain-flip: SKIP (0.004s)
[277.377242] [074/130] (722s left) gem_mmap_gtt (basic-copy)
[277.407906] Starting subtest: basic-copy
[278.303144] Subtest basic-copy: SUCCESS (0.887s)
[278.360558] [075/130] (721s left) gem_exec_schedule (preempt-user)
[278.401304] Starting subtest: preempt-user
[278.402207] Starting dynamic subtest: rcs0
[278.420201] Dynamic subtest rcs0: SUCCESS (0.018s)
[278.429861] Starting dynamic subtest: bcs0
[278.437796] Dynamic subtest bcs0: SUCCESS (0.017s)
[278.451820] Starting dynamic subtest: vcs0
[278.459221] Dynamic subtest vcs0: SUCCESS (0.021s)
[278.469182] Starting dynamic subtest: vecs0
[278.477033] Dynamic subtest vecs0: SUCCESS (0.017s)
[278.477975] Subtest preempt-user: SUCCESS (0.076s)
[278.780983] [076/130] (721s left) gem_exec_balancer (full-late)
[278.817779] Starting subtest: full-late
[279.893311] Subtest full-late: SUCCESS (1.076s)
[280.902715] [077/130] (719s left) gem_exec_reloc (basic-gtt-cpu-active)
[280.937733] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[280.987163] [078/130] (719s left) core_auth (basic-auth)
[281.017602] Starting subtest: basic-auth
[281.018466] Subtest basic-auth: SUCCESS (0.001s)
[281.068374] [079/130] (719s left) gem_workarounds (suspend-resume-fd)
[281.107059] Starting subtest: suspend-resume-fd
[296.866744] Subtest suspend-resume-fd: SUCCESS (1.912s)
[296.936024] [080/130] (717s left) gem_render_copy_redux (normal)
[296.971006] Starting subtest: normal
[297.427734] Subtest normal: SUCCESS (0.455s)
[297.530336] [081/130] (716s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[297.590200] Starting subtest: compare-crc-sanitycheck-xr24
[297.591063] Starting dynamic subtest: pipe-A-eDP-1
[298.209980] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.599s)
[298.228158] Starting dynamic subtest: pipe-B-eDP-1
[299.649855] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.401s)
[299.667428] Starting dynamic subtest: pipe-C-eDP-1
[301.042302] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.357s)
[301.043600] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.362s)
[301.964401] [082/130] (712s left) gem_tiled_partial_pwrite_pread (reads)
[302.006409] Subtest reads: SKIP (0.000s)
[302.065826] [083/130] (712s left) gem_exec_params (dr1-dirt)
[302.102204] Starting subtest: DR1-dirt
[302.103099] Subtest DR1-dirt: SUCCESS (0.000s)
[302.174594] [084/130] (712s left) gen7_exec_parse (cmd-crossing-page)
[302.207736] Subtest cmd-crossing-page: SKIP (0.000s)
[302.241691] [085/130] (712s left) kms_cdclk (mode-transition)
[302.293018] Starting subtest: mode-transition
[302.293844] Starting dynamic subtest: pipe-A-eDP-1
[302.585117] Dynamic subtest pipe-A-eDP-1: SKIP (0.287s)
[302.595041] Starting dynamic subtest: pipe-B-eDP-1
[302.596670] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[302.608535] Starting dynamic subtest: pipe-C-eDP-1
[302.610189] Dynamic subtest pipe-C-eDP-1: SKIP (0.002s)
[302.611678] Subtest mode-transition: SKIP (0.293s)
[303.502567] [086/130] (711s left) gem_exec_whisper (basic-fds-forked)
[303.541380] Starting subtest: basic-fds-forked
[311.109169] Subtest basic-fds-forked: SUCCESS (7.495s)
[311.276361] [087/130] (703s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[311.341458] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[311.343098] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[311.409779] [088/130] (703s left) gem_exec_balancer (bonded-false-hang)
[312.411038] Starting subtest: bonded-false-hang
[312.412559] Subtest bonded-false-hang: SKIP (0.000s)
[312.459512] [089/130] (702s left) gem_exec_fence (basic-busy)
[312.616747] Starting subtest: basic-busy
[312.618101] Starting dynamic subtest: rcs0
[312.663291] Dynamic subtest rcs0: SUCCESS (0.049s)
[312.684068] Starting dynamic subtest: bcs0
[312.711648] Dynamic subtest bcs0: SUCCESS (0.046s)
[312.730010] Starting dynamic subtest: vcs0
[312.761180] Dynamic subtest vcs0: SUCCESS (0.047s)
[312.778248] Starting dynamic subtest: vecs0
[312.815653] Dynamic subtest vecs0: SUCCESS (0.053s)
[312.816829] Subtest basic-busy: SUCCESS (0.203s)
[313.271676] [090/130] (701s left) gem_reg_read (bad-register)
[313.301342] Starting subtest: bad-register
[313.302637] Subtest bad-register: SUCCESS (0.000s)
[313.355307] [091/130] (701s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[313.415658] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[313.417170] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[313.499360] [092/130] (701s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[313.552965] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[313.553702] Starting dynamic subtest: pipe-A-default-mode
[313.858628] Dynamic subtest pipe-A-default-mode: SKIP (0.302s)
[313.861245] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.304s)
[314.763280] [093/130] (700s left) perf (create-destroy-userspace-config)
[314.856536] Starting subtest: create-destroy-userspace-config
[314.874148] Subtest create-destroy-userspace-config: SUCCESS (0.018s)
[314.897122] [094/130] (699s left) kms_pm_backlight (fade-with-dpms)
[314.954662] Starting subtest: fade-with-dpms
[317.119362] Starting dynamic subtest: eDP-1
[319.834117] Dynamic subtest eDP-1: SUCCESS (2.708s)
[319.835002] Subtest fade-with-dpms: SUCCESS (4.866s)
[320.719358] [095/130] (694s left) gem_spin_batch (engines)
[320.760215] Starting subtest: engines
[320.761213] Starting dynamic subtest: rcs0
[324.008363] Dynamic subtest rcs0: SUCCESS (3.232s)
[324.032575] Starting dynamic subtest: bcs0
[327.274494] Dynamic subtest bcs0: SUCCESS (3.234s)
[327.298477] Starting dynamic subtest: vcs0
[330.514626] Dynamic subtest vcs0: SUCCESS (3.235s)
[330.527586] Starting dynamic subtest: vecs0
[333.727361] Dynamic subtest vecs0: SUCCESS (3.235s)
[333.729229] Subtest engines: SUCCESS (12.944s)
[333.891865] [096/130] (680s left) kms_vrr (flip-basic)
[333.956349] Starting subtest: flip-basic
[333.958893] Starting dynamic subtest: pipe-A-eDP-1
[346.542744] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.572s)
[346.849370] Subtest flip-basic: SUCCESS (12.891s)
[347.771954] [097/130] (667s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[347.859524] Starting subtest: short-busy-flip-before-cursor-toggle
[347.861104] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[347.938894] [098/130] (666s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[348.022866] Starting subtest: cursorA-vs-flipA-legacy
[359.106404] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.079s)
[360.009900] [099/130] (654s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[360.042468] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[360.043267] Starting dynamic subtest: smem
[360.137715] Dynamic subtest smem: SUCCESS (0.095s)
[360.138651] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (0.095s)
[360.335126] [100/130] (654s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[360.387728] Starting subtest: crc-primary-basic-y-tiled-ccs
[360.389712] Starting dynamic subtest: pipe-A-eDP-1
[360.397539] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[360.407912] Starting dynamic subtest: pipe-B-eDP-1
[360.415656] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[360.426500] Starting dynamic subtest: pipe-C-eDP-1
[360.433326] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[360.434665] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.005s)
[360.447190] [101/130] (654s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[360.506350] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[360.508035] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[360.572990] [102/130] (654s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[360.631190] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[361.543135] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.911s)
[361.666504] [103/130] (653s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[361.727894] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[361.729327] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[361.800207] [104/130] (653s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[361.855081] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[361.856075] Starting dynamic subtest: pipe-A-eDP-1
[361.864900] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[361.877229] Starting dynamic subtest: pipe-B-eDP-1
[361.885582] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[361.896290] Starting dynamic subtest: pipe-C-eDP-1
[361.903362] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[361.904802] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[361.917471] [105/130] (653s left) perf_pmu (busy-double-start)
[361.946822] Starting subtest: busy-double-start
[361.947688] Starting dynamic subtest: rcs0
[365.557661] Dynamic subtest rcs0: FAIL (3.586s)
[365.575461] Starting dynamic subtest: bcs0
[369.099258] Dynamic subtest bcs0: SUCCESS (3.559s)
[369.118265] Starting dynamic subtest: vcs0
[372.698659] Dynamic subtest vcs0: FAIL (3.558s)
[372.715360] Starting dynamic subtest: vecs0
[376.254126] Dynamic subtest vecs0: FAIL (3.556s)
[376.255454] Subtest busy-double-start: FAIL (14.263s)
[376.293115] [106/130] (638s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[376.357592] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[376.358807] Starting dynamic subtest: pipe-A-eDP-1
[376.367036] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[376.376672] Starting dynamic subtest: pipe-B-eDP-1
[376.383864] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[376.393856] Starting dynamic subtest: pipe-C-eDP-1
[376.401240] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[376.402606] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SKIP (0.005s)
[376.416461] [107/130] (638s left) gem_mmap_gtt (basic)
[376.444257] Starting subtest: basic
[376.445268] Subtest basic: SUCCESS (0.001s)
[376.502508] [108/130] (638s left) gem_exec_params (sol-reset-not-gen7)
[376.534200] Starting subtest: sol-reset-not-gen7
[376.536544] Subtest sol-reset-not-gen7: SUCCESS (0.001s)
[376.628078] [109/130] (638s left) kms_prop_blob (basic)
[376.660353] Starting subtest: basic
[376.661300] Subtest basic: SUCCESS (0.000s)
[376.715053] [110/130] (638s left) perf_pmu (semaphore-wait-idle)
[376.747125] Starting subtest: semaphore-wait-idle
[376.747961] Starting dynamic subtest: rcs0
[377.266454] Dynamic subtest rcs0: SUCCESS (0.509s)
[377.278702] Starting dynamic subtest: bcs0
[377.777752] Dynamic subtest bcs0: SUCCESS (0.509s)
[377.800111] Starting dynamic subtest: vcs0
[378.288754] Dynamic subtest vcs0: SUCCESS (0.509s)
[378.310701] Starting dynamic subtest: vecs0
[378.800521] Dynamic subtest vecs0: SUCCESS (0.509s)
[378.802421] Subtest semaphore-wait-idle: SUCCESS (2.044s)
[378.888169] [111/130] (636s left) gem_exec_fence (syncobj-timeline-signal)
[379.300928] Starting subtest: syncobj-timeline-signal
[379.302326] Subtest syncobj-timeline-signal: SUCCESS (0.005s)
[379.501219] [112/130] (635s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[379.568110] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[379.569853] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[379.634128] [113/130] (635s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[379.687663] Starting subtest: sprite-rotation-90-pos-100-0
[379.690827] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[379.741061] [114/130] (635s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[382.304024] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[382.352126] [115/130] (632s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[382.410577] Starting subtest: multiplane-rotation-cropping-bottom
[388.811659] Subtest multiplane-rotation-cropping-bottom: SUCCESS (6.400s)
[388.929359] [116/130] (626s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[388.963595] Starting subtest: invalid-single-wait-unsubmitted
[388.964339] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[389.017377] [117/130] (626s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[389.078394] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[394.288653] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (5.208s)
[394.377201] [118/130] (620s left) syncobj_timeline (multi-wait-all-signaled)
[394.409996] Starting subtest: multi-wait-all-signaled
[394.410870] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[394.462519] [119/130] (620s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[394.545894] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[402.039619] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (7.497s)
[402.948300] [120/130] (612s left) kms_flip (basic-flip-vs-modeset)
[403.008470] Starting subtest: basic-flip-vs-modeset
[403.010618] Starting dynamic subtest: A-eDP1
[405.773998] Dynamic subtest A-eDP1: SUCCESS (2.763s)
[405.791685] Starting dynamic subtest: B-eDP1
[408.148285] Dynamic subtest B-eDP1: SUCCESS (2.374s)
[408.165222] Starting dynamic subtest: C-eDP1
[410.500844] Dynamic subtest C-eDP1: SUCCESS (2.349s)
[410.502245] Subtest basic-flip-vs-modeset: SUCCESS (7.492s)
[411.399498] [121/130] (603s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[411.495270] Starting subtest: flip-vs-cursor-crc-atomic
[412.197517] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.703s)
[413.128323] [122/130] (602s left) gem_exec_whisper (basic-queues-all)
[413.171273] Starting subtest: basic-queues-all
[420.027847] Subtest basic-queues-all: SUCCESS (6.855s)
[420.196681] [123/130] (595s left) prime_mmap (test_forked_cpu_write)
[420.230230] Starting subtest: test_forked_cpu_write
[420.231092] Starting dynamic subtest: test_forked_cpu_write-smem
[420.237169] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[420.238150] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[420.319452] [124/130] (595s left) kms_color (invalid-ctm-matrix-sizes)
[420.384051] Starting subtest: invalid-ctm-matrix-sizes
[420.385195] Starting dynamic subtest: pipe-A
[420.388715] Dynamic subtest pipe-A: SUCCESS (0.004s)
[420.398878] Starting dynamic subtest: pipe-B
[420.399825] Dynamic subtest pipe-B: SUCCESS (0.004s)
[420.408519] Starting dynamic subtest: pipe-C
[420.409435] Dynamic subtest pipe-C: SUCCESS (0.005s)
[420.410429] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[420.447848] [125/130] (595s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[420.504283] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[420.505773] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[420.574740] [126/130] (594s left) kms_pm_backlight (basic-brightness)
[420.628668] Starting subtest: basic-brightness
[420.656111] Starting dynamic subtest: eDP-1
[421.170192] Dynamic subtest eDP-1: SUCCESS (0.513s)
[421.171367] Subtest basic-brightness: SUCCESS (0.541s)
[422.086981] [127/130] (593s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[422.149855] Starting subtest: extended-pageflip-modeset-hang-oldfb
[422.153032] Starting dynamic subtest: pipe-A
[442.883179] Dynamic subtest pipe-A: SUCCESS (20.728s)
[442.913604] Starting dynamic subtest: pipe-C
[451.601524] Dynamic subtest pipe-C: SUCCESS (8.710s)
[451.602445] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (29.450s)
[452.475353] [128/130] (563s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[452.539535] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[457.960160] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (5.419s)
[458.068276] [129/130] (557s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[458.103968] Starting subtest: etime-single-wait-all-available-unsubmitted
[458.206428] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[458.264733] [130/130] (557s left) gem_ctx_persistence (engines-hostile-preempt)
[458.296889] Starting subtest: engines-hostile-preempt
[458.297725] Starting dynamic subtest: rcs0
[458.358102] Dynamic subtest rcs0: SUCCESS (0.060s)
[458.380061] Starting dynamic subtest: bcs0
[458.380974] Dynamic subtest bcs0: SUCCESS (0.016s)
[458.390423] Starting dynamic subtest: vcs0
[458.421089] Dynamic subtest vcs0: SUCCESS (0.044s)
[458.439117] Starting dynamic subtest: vecs0
[458.493201] Dynamic subtest vecs0: SUCCESS (0.070s)
[458.494882] Subtest engines-hostile-preempt: SUCCESS (0.196s)
[458.551832] Closing watchdogs
Done.