[42.771410] Initializing watchdogs
[42.771527]   /dev/watchdog0
[42.785479] [001/133] (960s left) gem_exec_fair (basic-none-vip)
[42.890172] Subtest basic-none-vip: SKIP (0.000s)
[42.931211] [002/133] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[43.053564] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[47.916380] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (4.865s)
[48.043368] [003/133] (955s left) gem_exec_schedule (fairslice-all)
[48.144131] Starting subtest: fairslice-all
[50.321112] Subtest fairslice-all: SUCCESS (2.173s)
[51.529909] [004/133] (951s left) gen9_exec_parse (bb-start-far)
[51.600935] Subtest bb-start-far: SKIP (0.000s)
[51.639555] [005/133] (951s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[51.738351] Starting subtest: cursorA-vs-flipB-varying-size
[51.743189] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[51.840838] [006/133] (951s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[51.927779] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[51.930211] Starting dynamic subtest: pipe-A-eDP-1
[53.636846] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.707s)
[53.973075] Starting dynamic subtest: pipe-B-eDP-1
[55.370189] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.409s)
[55.672690] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (3.743s)
[56.575294] [007/133] (946s left) kms_flip (absolute-wf_vblank)
[56.659077] Starting subtest: absolute-wf_vblank
[56.661115] Starting dynamic subtest: A-eDP1
[65.596150] Dynamic subtest A-eDP1: SUCCESS (8.934s)
[65.609133] Starting dynamic subtest: B-eDP1
[74.461771] Dynamic subtest B-eDP1: SUCCESS (8.830s)
[74.477495] Starting dynamic subtest: C-eDP1
[83.247151] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[83.260555] Starting dynamic subtest: D-eDP1
[92.123549] Dynamic subtest D-eDP1: SUCCESS (8.831s)
[92.126034] Subtest absolute-wf_vblank: SUCCESS (35.430s)
[93.388557] [008/133] (910s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[93.481193] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[98.193519] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (4.717s)
[98.295358] [009/133] (905s left) kms_psr (fbc-psr2-no-drrs)
[98.378647] Starting subtest: fbc-psr2-no-drrs
[98.381437] Starting dynamic subtest: eDP-1
[98.386248] Dynamic subtest eDP-1: SKIP (0.000s)
[98.390160] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[98.433943] [010/133] (905s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[98.532196] Starting subtest: extended-modeset-hang-oldfb-with-reset
[98.534581] Starting dynamic subtest: pipe-A
[110.171079] Dynamic subtest pipe-A: SUCCESS (11.637s)
[110.191521] Starting dynamic subtest: pipe-D
[122.958704] Dynamic subtest pipe-D: SUCCESS (12.783s)
[122.961823] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.428s)
[123.843895] [011/133] (879s left) drm_fdinfo (all-busy-idle-check-all)
[123.917771] Starting subtest: all-busy-idle-check-all
[123.928161] Subtest all-busy-idle-check-all: SKIP (0.008s)
[123.993585] [012/133] (879s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[124.093257] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[124.097459] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[124.162133] [013/133] (879s left) syncobj_timeline (multi-wait-available-signaled)
[124.230196] Starting subtest: multi-wait-available-signaled
[124.232708] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[124.308448] [014/133] (879s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[124.702536] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[125.599067] [015/133] (878s left) kms_setmode (invalid-clone-single-crtc-stealing)
[125.660069] Starting subtest: invalid-clone-single-crtc-stealing
[125.667136] Subtest invalid-clone-single-crtc-stealing: SKIP (0.001s)
[125.730287] [016/133] (877s left) gem_mmap_gtt (isolation)
[125.799969] Subtest isolation: SKIP (0.000s)
[125.835484] [017/133] (877s left) gem_exec_params (dr4-dirt)
[125.906715] Starting subtest: DR4-dirt
[125.909090] Subtest DR4-dirt: SUCCESS (0.000s)
[125.991938] [018/133] (877s left) kms_vblank (query-forked-hang)
[126.051939] Starting subtest: query-forked-hang
[126.054544] Starting dynamic subtest: pipe-A-eDP-1
[140.324709] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.270s)
[140.342731] Starting dynamic subtest: pipe-D-eDP-1
[165.270911] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.942s)
[165.273438] Subtest query-forked-hang: SUCCESS (39.221s)
[166.205920] [019/133] (837s left) gem_barrier_race (remote-request)
[166.274085] Starting subtest: remote-request
[166.276091] Starting dynamic subtest: rcs0
[171.380096] Dynamic subtest rcs0: SUCCESS (5.108s)
[171.381974] Subtest remote-request: SUCCESS (5.109s)
[171.484136] [020/133] (832s left) gem_render_copy (yf-tiled)
[171.549840] Starting subtest: yf-tiled
[171.554336] Subtest yf-tiled: SKIP (0.000s)
[171.731727] [021/133] (832s left) gem_exec_params (rel-constants-invalid)
[171.806198] Starting subtest: rel-constants-invalid
[171.810054] Subtest rel-constants-invalid: SUCCESS (0.000s)
[171.888071] [022/133] (831s left) kms_getfb (getfb2-accept-ccs)
[171.953062] Starting subtest: getfb2-accept-ccs
[171.955532] Subtest getfb2-accept-ccs: SUCCESS (0.001s)
[172.044836] [023/133] (831s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[172.132147] Subtest tiled-to-tiled: SKIP (0.000s)
[172.189183] [024/133] (831s left) syncobj_timeline (invalid-query-zero-handles)
[172.263938] Starting subtest: invalid-query-zero-handles
[172.266743] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[172.350151] [025/133] (831s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[172.413778] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[172.521039] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[172.587473] [026/133] (831s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[172.668159] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[172.672177] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[172.746385] [027/133] (831s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[172.831912] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[175.313714] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.487s)
[175.430123] [028/133] (828s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[175.529824] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[175.533271] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[175.628416] [029/133] (828s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[175.714299] Starting subtest: y-tiled-64bpp-rotate-0
[175.717917] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[175.790739] [030/133] (828s left) kms_flip (plain-flip-ts-check)
[175.877542] Starting subtest: plain-flip-ts-check
[175.879619] Starting dynamic subtest: A-eDP1
[184.866068] Dynamic subtest A-eDP1: SUCCESS (8.953s)
[184.876057] Starting dynamic subtest: B-eDP1
[193.825923] Dynamic subtest B-eDP1: SUCCESS (8.960s)
[193.842697] Starting dynamic subtest: C-eDP1
[202.741649] Dynamic subtest C-eDP1: SUCCESS (8.948s)
[202.758761] Starting dynamic subtest: D-eDP1
[211.700916] Dynamic subtest D-eDP1: SUCCESS (8.960s)
[211.707250] Subtest plain-flip-ts-check: SUCCESS (35.833s)
[212.987223] [031/133] (791s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[213.051232] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[213.055782] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[213.130974] [032/133] (790s left) gem_exec_fence (syncobj-invalid-flags)
[213.657466] Starting subtest: syncobj-invalid-flags
[213.660239] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[215.551337] [033/133] (788s left) gem_mmap_gtt (cpuset-basic-small-copy)
[215.617286] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[215.646770] [034/133] (788s left) v3d/v3d_get_param (base-params)
[215.749845] Subtest base-params: SKIP (0.000s)
[215.800731] [035/133] (788s left) gem_pread (self)
[215.874049] Subtest self: SKIP (0.000s)
[215.921950] [036/133] (788s left) kms_pm_rpm (fences)
[217.668613] Starting subtest: fences
[217.674799] Subtest fences: SKIP (0.000s)
[218.252518] [037/133] (785s left) kms_lease (cursor-implicit-plane)
[218.340193] Starting subtest: cursor-implicit-plane
[218.342507] Starting dynamic subtest: pipe-A-eDP-1
[218.822892] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.482s)
[218.839029] Starting dynamic subtest: pipe-B-eDP-1
[220.025961] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.199s)
[220.041949] Starting dynamic subtest: pipe-C-eDP-1
[221.229914] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.202s)
[221.245212] Starting dynamic subtest: pipe-D-eDP-1
[222.448173] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.214s)
[222.450256] Subtest cursor-implicit-plane: SUCCESS (4.106s)
[223.362374] [038/133] (780s left) kms_flip (flip-vs-panning-vs-hang)
[223.473058] Starting subtest: flip-vs-panning-vs-hang
[223.475564] Starting dynamic subtest: A-eDP1
[261.999862] Dynamic subtest A-eDP1: SUCCESS (38.526s)
[262.015514] Starting dynamic subtest: D-eDP1
[312.005705] Dynamic subtest D-eDP1: SUCCESS (50.005s)
[312.008612] Subtest flip-vs-panning-vs-hang: SUCCESS (88.539s)
[313.281645] [039/133] (690s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[313.354389] Starting subtest: multi-wait-for-submit-unsubmitted
[313.456142] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[313.529634] [040/133] (690s left) api_intel_bb (reset-flags)
[313.602672] Starting subtest: reset-flags
[313.614207] Subtest reset-flags: SUCCESS (0.014s)
[313.715274] [041/133] (690s left) kms_psr (psr-sprite-blt)
[313.782206] Starting subtest: psr-sprite-blt
[313.785122] Starting dynamic subtest: eDP-1
[315.598147] Dynamic subtest eDP-1: SUCCESS (1.815s)
[315.600835] Subtest psr-sprite-blt: SUCCESS (1.816s)
[316.479608] [042/133] (687s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[316.589517] Starting subtest: short-flip-after-cursor-toggle
[317.549262] Subtest short-flip-after-cursor-toggle: SUCCESS (0.960s)
[318.467782] [043/133] (685s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[318.575682] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[323.213442] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (4.643s)
[323.336755] [044/133] (681s left) gem_exec_fence (syncobj-unused-fence)
[323.822602] Starting subtest: syncobj-unused-fence
[323.829191] Subtest syncobj-unused-fence: SUCCESS (0.010s)
[325.495412] [045/133] (678s left) gem_exec_parallel (basic)
[325.578963] Starting subtest: basic
[325.581584] Starting dynamic subtest: rcs0
[327.294909] Dynamic subtest rcs0: SUCCESS (1.714s)
[327.312501] Starting dynamic subtest: bcs0
[329.346133] Dynamic subtest bcs0: SUCCESS (2.050s)
[329.362840] Starting dynamic subtest: vcs0
[331.496663] Dynamic subtest vcs0: SUCCESS (2.148s)
[331.513157] Starting dynamic subtest: vcs1
[333.475986] Dynamic subtest vcs1: SUCCESS (1.978s)
[333.492089] Starting dynamic subtest: vecs0
[335.443297] Dynamic subtest vecs0: SUCCESS (1.966s)
[335.461693] Starting dynamic subtest: ccs0
[337.450924] Dynamic subtest ccs0: SUCCESS (2.007s)
[337.453627] Subtest basic: SUCCESS (11.872s)
[337.640464] [046/133] (666s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[337.724361] Starting subtest: yf-tiled-64bpp-rotate-270
[337.729619] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[337.812202] [047/133] (666s left) kms_content_protection (dp-mst-type-1)
[337.984051] Starting subtest: dp-mst-type-1
[337.989064] Subtest dp-mst-type-1: SKIP (0.000s)
[338.089744] [048/133] (666s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[338.205064] Starting subtest: crc-primary-basic-y-tiled-ccs
[338.207696] Starting dynamic subtest: pipe-A-eDP-1
[338.212928] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[338.222573] Starting dynamic subtest: pipe-B-eDP-1
[338.227305] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[338.235985] Starting dynamic subtest: pipe-C-eDP-1
[338.240663] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[338.249446] Starting dynamic subtest: pipe-D-eDP-1
[338.254944] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[338.259502] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.011s)
[338.279147] [049/133] (666s left) kms_writeback (writeback-invalid-parameters)
[338.368379] Subtest writeback-invalid-parameters: SKIP (0.000s)
[338.384777] [050/133] (666s left) dumb_buffer (map-invalid-size)
[338.447561] Starting subtest: map-invalid-size
[338.450038] Subtest map-invalid-size: SUCCESS (0.000s)
[338.537527] [051/133] (666s left) vgem_basic (debugfs)
[338.609653] Starting subtest: debugfs
[338.612235] Subtest debugfs: SUCCESS (0.001s)
[338.661726] [052/133] (665s left) kms_cursor_crc (cursor-onscreen-64x64)
[338.818220] Starting subtest: cursor-onscreen-64x64
[338.822732] Starting dynamic subtest: pipe-A-eDP-1
[341.290051] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.467s)
[341.325050] Starting dynamic subtest: pipe-D-eDP-1
[344.519678] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.207s)
[344.522265] Subtest cursor-onscreen-64x64: SUCCESS (5.699s)
[345.395930] [053/133] (659s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[345.512168] Starting subtest: psr-1p-primscrn-cur-indfb-move
[350.985250] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (5.476s)
[351.103896] [054/133] (653s left) prime_vgem (basic-read)
[351.181762] Starting subtest: basic-read
[351.190658] Subtest basic-read: SKIP (0.007s)
[351.280099] [055/133] (653s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[351.378679] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[351.382519] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[351.464963] [056/133] (653s left) kms_atomic (plane-invalid-params)
[351.555353] Starting subtest: plane-invalid-params
[351.557811] Starting dynamic subtest: pipe-A-eDP-1
[351.971717] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.416s)
[351.973969] Subtest plane-invalid-params: SUCCESS (0.417s)
[352.890477] [057/133] (651s left) gem_ctx_exec (basic-nohangcheck)
[352.961610] Starting subtest: basic-nohangcheck
[353.235471] Subtest basic-nohangcheck: SUCCESS (0.275s)
[353.331654] [058/133] (651s left) gem_vm_create (create-ext)
[353.409799] Starting subtest: create-ext
[353.418064] Subtest create-ext: SUCCESS (0.009s)
[353.506884] [059/133] (651s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[353.601595] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[353.606272] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[353.683294] [060/133] (651s left) v3d/v3d_submit_csd (multisync-out-syncs)
[353.782735] Subtest multisync-out-syncs: SKIP (0.000s)
[353.835965] [061/133] (651s left) gem_readwrite (read-bad-handle)
[353.899480] Subtest read-bad-handle: SKIP (0.000s)
[353.949560] [062/133] (650s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[354.051333] Starting subtest: flip-vs-cursor-varying-size
[360.822718] Subtest flip-vs-cursor-varying-size: SUCCESS (6.772s)
[361.733221] [063/133] (643s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-render)
[361.841506] Starting subtest: fbc-1p-offscren-pri-indfb-draw-render
[364.806620] Subtest fbc-1p-offscren-pri-indfb-draw-render: SUCCESS (2.967s)
[364.925779] [064/133] (640s left) kms_psr (psr-cursor-mmap-gtt)
[364.987461] Starting subtest: psr-cursor-mmap-gtt
[364.989616] Starting dynamic subtest: eDP-1
[366.766037] Dynamic subtest eDP-1: SUCCESS (1.778s)
[366.768564] Subtest psr-cursor-mmap-gtt: SUCCESS (1.779s)
[367.677045] [065/133] (637s left) gem_lmem_swapping (heavy-multi)
[367.745618] Subtest heavy-multi: SKIP (0.000s)
[367.774966] [066/133] (637s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[367.872370] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[367.877080] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[367.956833] [067/133] (637s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[368.049871] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[368.101336] [068/133] (636s left) v3d/v3d_submit_csd (bad-extension)
[368.205099] Subtest bad-extension: SKIP (0.000s)
[368.256963] [069/133] (636s left) kms_flip (nonexisting-fb)
[368.364373] Starting subtest: nonexisting-fb
[368.366866] Starting dynamic subtest: A-eDP1
[369.664689] Dynamic subtest A-eDP1: SUCCESS (1.302s)
[369.682240] Starting dynamic subtest: B-eDP1
[371.009400] Dynamic subtest B-eDP1: SUCCESS (1.344s)
[371.031309] Starting dynamic subtest: C-eDP1
[372.267881] Dynamic subtest C-eDP1: SUCCESS (1.256s)
[372.284367] Starting dynamic subtest: D-eDP1
[373.549244] Dynamic subtest D-eDP1: SUCCESS (1.280s)
[373.557339] Subtest nonexisting-fb: SUCCESS (5.198s)
[374.867430] [070/133] (630s left) kms_lease (simple-lease)
[374.961203] Starting subtest: simple-lease
[374.963952] Starting dynamic subtest: pipe-A-eDP-1
[375.484559] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.520s)
[375.501697] Starting dynamic subtest: pipe-B-eDP-1
[377.018852] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.530s)
[377.035220] Starting dynamic subtest: pipe-C-eDP-1
[378.540602] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.520s)
[378.556538] Starting dynamic subtest: pipe-D-eDP-1
[380.071354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.526s)
[380.074177] Subtest simple-lease: SUCCESS (5.109s)
[380.994402] [071/133] (624s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[381.073062] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[384.259949] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.192s)
[384.381043] [072/133] (620s left) i915_pm_rpm (system-suspend-execbuf)
[386.607369] Starting subtest: system-suspend-execbuf
[485.668462] Subtest system-suspend-execbuf: SUCCESS (84.743s)
[486.556122] [073/133] (532s left) kms_addfb_basic (bo-too-small)
[486.620754] Starting subtest: bo-too-small
[486.622739] Subtest bo-too-small: SUCCESS (0.000s)
[486.684848] [074/133] (532s left) syncobj_wait (single-wait-all-for-submit-submitted)
[486.754209] Starting subtest: single-wait-all-for-submit-submitted
[486.859377] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.105s)
[486.936423] [075/133] (532s left) gem_eio (kms)
[487.192081] Starting subtest: kms
[509.497133] Subtest kms: SUCCESS (22.309s)
[510.749960] [076/133] (508s left) api_intel_bb (blit-noreloc-purge-cache)
[510.826249] Starting subtest: blit-noreloc-purge-cache
[510.846257] Subtest blit-noreloc-purge-cache: SUCCESS (0.023s)
[510.946281] [077/133] (508s left) gem_softpin (allocator-evict)
[510.996671] Starting subtest: allocator-evict
[510.999306] Starting dynamic subtest: rcs0
[534.486550] Dynamic subtest rcs0: SUCCESS (23.486s)
[534.500569] Starting dynamic subtest: bcs0
[558.128166] Dynamic subtest bcs0: SUCCESS (23.644s)
[558.143855] Starting dynamic subtest: vcs0
[581.845881] Dynamic subtest vcs0: SUCCESS (23.717s)
[581.864343] Starting dynamic subtest: vcs1
[605.142308] Dynamic subtest vcs1: SUCCESS (23.295s)
[605.160717] Starting dynamic subtest: vecs0
[628.584868] Dynamic subtest vecs0: SUCCESS (23.442s)
[628.602240] Starting dynamic subtest: ccs0
[651.974355] Dynamic subtest ccs0: SUCCESS (23.387s)
[651.977580] Subtest allocator-evict: SUCCESS (140.978s)
[652.050167] [078/133] (367s left) gem_exec_schedule (u-semaphore-resolve)
[652.229085] Starting subtest: u-semaphore-resolve
[652.302656] Subtest u-semaphore-resolve: SUCCESS (0.073s)
[652.648006] [079/133] (366s left) vc4/vc4_wait_bo (bad-bo)
[652.729662] Subtest bad-bo: SKIP (0.000s)
[652.780610] [080/133] (366s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[652.883609] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[652.886017] Starting dynamic subtest: pipe-A-eDP-1
[653.420097] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.537s)
[653.432995] Starting dynamic subtest: pipe-B-eDP-1
[655.110288] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.688s)
[655.130250] Starting dynamic subtest: pipe-C-eDP-1
[656.830557] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.717s)
[656.852340] Starting dynamic subtest: pipe-D-eDP-1
[658.581366] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.748s)
[658.583787] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (5.699s)
[659.868376] [081/133] (359s left) syncobj_timeline (transfer-timeline-point)
[659.932860] Starting subtest: transfer-timeline-point
[659.935655] Subtest transfer-timeline-point: SUCCESS (0.001s)
[660.006819] [082/133] (359s left) gem_exec_flush (basic-wb-ro-before-default)
[660.100174] Starting subtest: basic-wb-ro-before-default
[665.576823] Subtest basic-wb-ro-before-default: SUCCESS (5.476s)
[665.767251] [083/133] (353s left) kms_prop_blob (blob-prop-lifetime)
[665.827790] Starting subtest: blob-prop-lifetime
[665.830464] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[665.906913] [084/133] (353s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[666.023911] Starting subtest: short-busy-flip-before-cursor-toggle
[666.028493] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[666.119094] [085/133] (353s left) kms_psr (fbc-psr-sprite-render)
[666.208610] Starting subtest: fbc-psr-sprite-render
[666.211238] Starting dynamic subtest: eDP-1
[666.215820] Dynamic subtest eDP-1: SKIP (0.000s)
[666.220328] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[666.275989] [086/133] (353s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[666.324011] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[666.427490] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[666.501502] [087/133] (353s left) i915_pm_freq_api (freq-reset)
[666.575786] Starting subtest: freq-reset
[666.578273] Starting dynamic subtest: gt0
[666.779591] Dynamic subtest gt0: SUCCESS (0.202s)
[666.797594] Starting dynamic subtest: gt1
[666.965962] Dynamic subtest gt1: SUCCESS (0.185s)
[666.968352] Subtest freq-reset: SUCCESS (0.389s)
[667.026314] [088/133] (352s left) kms_addfb_basic (unused-pitches)
[667.088144] Starting subtest: unused-pitches
[667.090422] Subtest unused-pitches: SUCCESS (0.000s)
[667.185950] [089/133] (352s left) sysfs_timeslice_duration (idempotent)
[667.259806] Starting subtest: idempotent
[667.262481] Starting dynamic subtest: vecs0
[667.265015] Dynamic subtest vecs0: SUCCESS (0.001s)
[667.284473] Starting dynamic subtest: ccs0
[667.287203] Dynamic subtest ccs0: SUCCESS (0.001s)
[667.301825] Starting dynamic subtest: vcs0
[667.304020] Dynamic subtest vcs0: SUCCESS (0.001s)
[667.313839] Starting dynamic subtest: bcs0
[667.315936] Dynamic subtest bcs0: SUCCESS (0.000s)
[667.323668] Starting dynamic subtest: rcs0
[667.325832] Dynamic subtest rcs0: SUCCESS (0.000s)
[667.332881] Starting dynamic subtest: vcs1
[667.335241] Dynamic subtest vcs1: SUCCESS (0.000s)
[667.337291] Subtest idempotent: SUCCESS (0.010s)
[667.354267] [090/133] (352s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[667.459137] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[667.461600] Starting dynamic subtest: pipe-A-eDP-1
[667.466214] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[667.484980] Starting dynamic subtest: pipe-B-eDP-1
[667.489733] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[667.508581] Starting dynamic subtest: pipe-C-eDP-1
[667.513801] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[667.527873] Starting dynamic subtest: pipe-D-eDP-1
[667.532628] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[667.537429] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[667.559984] [091/133] (352s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[667.641524] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[667.644462] Starting dynamic subtest: pipe-A-eDP-1
[668.106213] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.467s)
[668.125112] Starting dynamic subtest: pipe-B-eDP-1
[669.700440] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.593s)
[669.719349] Starting dynamic subtest: pipe-C-eDP-1
[671.289425] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.587s)
[671.308955] Starting dynamic subtest: pipe-D-eDP-1
[672.876567] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.587s)
[672.878923] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (5.240s)
[674.118929] [092/133] (345s left) gem_exec_balancer (parallel-balancer)
[674.375538] Starting subtest: parallel-balancer
[674.994861] Subtest parallel-balancer: SUCCESS (0.620s)
[675.078511] [093/133] (344s left) kms_sequence (queue-idle)
[675.170262] Starting subtest: queue-idle
[675.172770] Starting dynamic subtest: pipe-A-eDP-1
[676.992918] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.822s)
[677.014971] Starting dynamic subtest: pipe-B-eDP-1
[679.568881] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.572s)
[679.587625] Starting dynamic subtest: pipe-C-eDP-1
[682.109572] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.537s)
[682.128395] Starting dynamic subtest: pipe-D-eDP-1
[684.676274] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.567s)
[684.679123] Subtest queue-idle: SUCCESS (9.509s)
[685.585962] [094/133] (334s left) gem_userptr_blits (readonly-pwrite-unsync)
[685.662986] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[685.755039] [095/133] (334s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[685.849313] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[685.851585] Starting dynamic subtest: pipe-A-eDP-1
[686.430203] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.579s)
[686.446810] Starting dynamic subtest: pipe-B-eDP-1
[688.237175] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.803s)
[688.257140] Starting dynamic subtest: pipe-C-eDP-1
[690.050653] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.811s)
[690.064544] Starting dynamic subtest: pipe-D-eDP-1
[691.764289] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.711s)
[691.766768] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (5.916s)
[693.025861] [096/133] (327s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[693.128550] Starting subtest: fbcpsr-rgb101010-draw-render
[697.940868] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (4.819s)
[698.055009] [097/133] (322s left) kms_plane_lowres (tiling-y)
[698.138787] Starting subtest: tiling-y
[698.143495] Subtest tiling-y: SKIP (0.000s)
[698.213263] [098/133] (321s left) gem_mmap_gtt (medium-copy-xy)
[698.273740] Subtest medium-copy-XY: SKIP (0.000s)
[698.317293] [099/133] (321s left) kms_psr (fbc-pr-cursor-render)
[698.410654] Starting subtest: fbc-pr-cursor-render
[698.415969] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[698.475993] [100/133] (321s left) drm_read (short-buffer-block)
[698.759656] Starting subtest: short-buffer-block
[698.762587] Subtest short-buffer-block: SUCCESS (0.000s)
[698.886914] [101/133] (321s left) kms_cursor_edge_walk (128x128-top-edge)
[698.970186] Starting subtest: 128x128-top-edge
[698.972323] Starting dynamic subtest: pipe-A-eDP-1
[702.558664] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.584s)
[702.578033] Starting dynamic subtest: pipe-D-eDP-1
[705.783364] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.223s)
[705.786155] Subtest 128x128-top-edge: SUCCESS (6.812s)
[706.693192] [102/133] (313s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[706.808622] Starting subtest: y-tiled-8bpp-rotate-270
[706.813536] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[706.909324] [103/133] (313s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[706.984996] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[706.989651] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[707.071813] [104/133] (313s left) gem_exec_schedule (semaphore-resolve)
[707.162729] Starting subtest: semaphore-resolve
[707.230988] Subtest semaphore-resolve: SUCCESS (0.072s)
[707.562149] [105/133] (312s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[707.671334] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[707.681972] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.013s)
[707.755427] [106/133] (312s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[707.832101] Starting subtest: x-tiled-8bpp-rotate-90
[707.852764] Subtest x-tiled-8bpp-rotate-90: SKIP (0.021s)
[707.965234] [107/133] (312s left) kms_atomic_interruptible (legacy-setmode)
[708.051363] Starting subtest: legacy-setmode
[708.054652] Starting dynamic subtest: pipe-A-eDP-1
[714.980262] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.926s)
[714.983756] Subtest legacy-setmode: SUCCESS (6.927s)
[715.920843] [108/133] (304s left) kms_chamelium_edid (vga-edid-read)
[716.282146] Subtest vga-edid-read: SKIP (0.000s)
[717.170215] [109/133] (303s left) gem_ctx_shared (create-shared-gtt)
[717.254828] Starting subtest: create-shared-gtt
[719.471436] Subtest create-shared-gtt: SUCCESS (2.154s)
[719.698933] [110/133] (300s left) gem_mmap_wc (read-write-distinct)
[719.766175] Subtest read-write-distinct: SKIP (0.000s)
[719.821508] [111/133] (300s left) kms_psr (fbc-psr-sprite-plane-move)
[719.882969] Starting subtest: fbc-psr-sprite-plane-move
[719.885313] Starting dynamic subtest: eDP-1
[719.889587] Dynamic subtest eDP-1: SKIP (0.000s)
[719.893507] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[719.952034] [112/133] (300s left) syncobj_wait (invalid-wait-illegal-handle)
[720.019333] Starting subtest: invalid-wait-illegal-handle
[720.021315] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[720.095211] [113/133] (300s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[720.185082] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[720.188967] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[720.274932] [114/133] (300s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[720.371588] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[723.648455] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.279s)
[723.779610] [115/133] (296s left) kms_chamelium_edid (dp-edid-read)
[724.141244] Subtest dp-edid-read: SKIP (0.000s)
[725.045527] [116/133] (295s left) i915_pm_rps (thresholds-idle)
[725.119459] Starting subtest: thresholds-idle
[725.122142] Starting dynamic subtest: gt0
[725.127236] Dynamic subtest gt0: SKIP (0.004s)
[725.138283] Starting dynamic subtest: gt1
[725.142983] Dynamic subtest gt1: SKIP (0.000s)
[725.147814] Subtest thresholds-idle: SKIP (0.006s)
[725.190168] [117/133] (295s left) kms_rotation_crc (sprite-rotation-270)
[725.297801] Starting subtest: sprite-rotation-270
[725.302650] Subtest sprite-rotation-270: SKIP (0.002s)
[725.374127] [118/133] (295s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[725.463251] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[726.286637] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.825s)
[726.433178] [119/133] (294s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[726.512137] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[726.514792] Starting dynamic subtest: pipe-A-default-mode
[726.805890] Dynamic subtest pipe-A-default-mode: SKIP (0.286s)
[726.811329] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.288s)
[727.693730] [120/133] (293s left) kms_frontbuffer_tracking (psr-slowdraw)
[727.808199] Starting subtest: psr-slowdraw
[750.820861] Subtest psr-slowdraw: SUCCESS (23.015s)
[750.930691] [121/133] (269s left) kms_feature_discovery (psr1)
[751.020487] Starting subtest: psr1
[751.023265] Subtest psr1: SUCCESS (0.000s)
[751.085061] [122/133] (269s left) perf_pmu (idle)
[751.140980] Starting subtest: idle
[751.143189] Starting dynamic subtest: rcs0
[751.668201] Dynamic subtest rcs0: SUCCESS (0.531s)
[751.689727] Starting dynamic subtest: bcs0
[752.178194] Dynamic subtest bcs0: SUCCESS (0.509s)
[752.198168] Starting dynamic subtest: vcs0
[752.693169] Dynamic subtest vcs0: SUCCESS (0.513s)
[752.713790] Starting dynamic subtest: vcs1
[753.208128] Dynamic subtest vcs1: SUCCESS (0.513s)
[753.229192] Starting dynamic subtest: vecs0
[753.726926] Dynamic subtest vecs0: SUCCESS (0.517s)
[753.747422] Starting dynamic subtest: ccs0
[754.238240] Dynamic subtest ccs0: SUCCESS (0.509s)
[754.241339] Subtest idle: SUCCESS (3.103s)
[754.336877] [123/133] (266s left) gem_exec_params (secure-non-master)
[754.411194] Starting subtest: secure-non-master
[754.416104] Subtest secure-non-master: SKIP (0.000s)
[754.508438] [124/133] (266s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[754.579346] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[754.584565] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[754.767581] [125/133] (266s left) kms_big_fb (linear-32bpp-rotate-90)
[754.869289] Starting subtest: linear-32bpp-rotate-90
[754.889366] Subtest linear-32bpp-rotate-90: SKIP (0.020s)
[754.995030] [126/133] (266s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[755.083392] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[755.085845] Starting dynamic subtest: pipe-A-eDP-1
[755.104423] Dynamic subtest pipe-A-eDP-1: SKIP (0.017s)
[755.122736] Starting dynamic subtest: pipe-B-eDP-1
[755.127497] Dynamic subtest pipe-B-eDP-1: SKIP (0.018s)
[755.140607] Starting dynamic subtest: pipe-C-eDP-1
[755.145300] Dynamic subtest pipe-C-eDP-1: SKIP (0.018s)
[755.155927] Starting dynamic subtest: pipe-D-eDP-1
[755.160558] Dynamic subtest pipe-D-eDP-1: SKIP (0.018s)
[755.164432] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.077s)
[755.243931] [127/133] (265s left) kms_content_protection (type1)
[755.382405] Starting subtest: type1
[758.880769] Subtest type1: SKIP (3.500s)
[760.144822] [128/133] (260s left) kms_atomic (crtc-invalid-params)
[760.235662] Starting subtest: crtc-invalid-params
[760.238334] Starting dynamic subtest: pipe-A-eDP-1
[760.601870] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.364s)
[760.604666] Subtest crtc-invalid-params: SUCCESS (0.366s)
[761.492666] [129/133] (259s left) gem_ctx_persistence (heartbeat-hostile)
[761.559140] Starting subtest: heartbeat-hostile
[761.563679] Subtest heartbeat-hostile: SKIP (0.000s)
[761.661150] [130/133] (259s left) perf_pmu (semaphore-wait)
[761.725422] Starting subtest: semaphore-wait
[761.727928] Starting dynamic subtest: rcs0
[762.250653] Dynamic subtest rcs0: SUCCESS (0.523s)
[762.271397] Starting dynamic subtest: bcs0
[762.766501] Dynamic subtest bcs0: SUCCESS (0.514s)
[762.787019] Starting dynamic subtest: vcs0
[763.282889] Dynamic subtest vcs0: SUCCESS (0.514s)
[763.303235] Starting dynamic subtest: vcs1
[763.797473] Dynamic subtest vcs1: SUCCESS (0.513s)
[763.818144] Starting dynamic subtest: vecs0
[764.311495] Dynamic subtest vecs0: SUCCESS (0.513s)
[764.333189] Starting dynamic subtest: ccs0
[764.825026] Dynamic subtest ccs0: SUCCESS (0.513s)
[764.828068] Subtest semaphore-wait: SUCCESS (3.099s)
[764.946043] [131/133] (256s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[765.064230] Starting subtest: basic-flip-before-cursor-varying-size
[766.028424] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.963s)
[766.911358] [132/133] (254s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[767.005566] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[770.814678] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.814s)
[770.915940] [133/133] (250s left) gem_softpin (evict-active)
[770.986436] Starting subtest: evict-active
[771.001189] Subtest evict-active: SUCCESS (0.015s)
[771.100280] Closing watchdogs
Done.