[40.607228] Initializing watchdogs
[40.607280]   /dev/watchdog0
[40.612218] [001/137] (960s left) kms_cursor_crc (cursor-random-64x64)
[40.668972] Starting subtest: cursor-random-64x64
[40.675032] Starting dynamic subtest: pipe-A-eDP-1
[44.671665] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.997s)
[44.686771] Starting dynamic subtest: pipe-B-eDP-1
[49.360463] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.675s)
[49.361462] Subtest cursor-random-64x64: SUCCESS (8.691s)
[50.268347] [002/137] (950s left) kms_addfb_basic (invalid-set-prop-any)
[50.346043] Starting subtest: invalid-set-prop-any
[50.346779] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[50.425210] [003/137] (950s left) perf_pmu (frequency)
[50.479338] Starting subtest: frequency
[52.561821] Subtest frequency: SUCCESS (2.078s)
[52.647781] [004/137] (948s left) prime_busy (before-wait)
[52.697400] Starting subtest: before-wait
[52.699745] Starting dynamic subtest: rcs0
[52.722966] Dynamic subtest rcs0: SUCCESS (0.023s)
[52.727758] Starting dynamic subtest: bcs0
[52.759697] Dynamic subtest bcs0: SUCCESS (0.036s)
[52.772281] Starting dynamic subtest: vcs0
[52.782660] Dynamic subtest vcs0: SUCCESS (0.023s)
[52.793077] Starting dynamic subtest: vecs0
[52.802994] Dynamic subtest vecs0: SUCCESS (0.021s)
[52.918670] Subtest before-wait: SUCCESS (0.212s)
[52.993237] [005/137] (948s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[53.050345] Starting subtest: multi-wait-all-for-submit-submitted
[53.155448] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[53.239222] [006/137] (947s left) gem_exec_schedule (u-lateslice)
[53.302786] Starting subtest: u-lateslice
[53.303503] Starting dynamic subtest: rcs0
[53.317371] Dynamic subtest rcs0: SUCCESS (0.017s)
[53.320852] Starting dynamic subtest: bcs0
[53.332682] Dynamic subtest bcs0: SUCCESS (0.015s)
[53.339421] Starting dynamic subtest: vcs0
[53.350486] Dynamic subtest vcs0: SUCCESS (0.018s)
[53.357705] Starting dynamic subtest: vecs0
[53.370837] Dynamic subtest vecs0: SUCCESS (0.020s)
[53.372062] Subtest u-lateslice: SUCCESS (0.070s)
[53.679029] [007/137] (947s left) i915_pm_rpm (modeset-non-lpsp)
[55.286052] Starting subtest: modeset-non-lpsp
[58.975997] Subtest modeset-non-lpsp: SKIP (3.688s)
[60.068466] [008/137] (941s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[60.135442] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[60.592895] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.458s)
[60.727875] [009/137] (940s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_rc_ccs_cc)
[61.105276] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[62.050455] [010/137] (939s left) gem_mmap_offset (bad-extensions)
[62.106963] Starting subtest: bad-extensions
[62.107742] Subtest bad-extensions: SUCCESS (0.000s)
[62.181090] [011/137] (939s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[62.239395] Starting subtest: primary-yf-tiled-reflect-x-180
[62.323837] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.083s)
[62.412668] [012/137] (938s left) gem_softpin (allocator-evict)
[62.450729] Starting subtest: allocator-evict
[62.451424] Starting dynamic subtest: rcs0
[86.046099] Dynamic subtest rcs0: SUCCESS (23.595s)
[86.050444] Starting dynamic subtest: bcs0
[109.377114] Dynamic subtest bcs0: SUCCESS (23.331s)
[109.381428] Starting dynamic subtest: vcs0
[132.393094] Dynamic subtest vcs0: SUCCESS (23.016s)
[132.397392] Starting dynamic subtest: vecs0
[155.602443] Dynamic subtest vecs0: SUCCESS (23.209s)
[155.603328] Subtest allocator-evict: SUCCESS (93.151s)
[155.674174] [013/137] (845s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[155.758309] Starting subtest: short-flip-after-cursor-toggle
[157.030511] Subtest short-flip-after-cursor-toggle: SUCCESS (1.271s)
[157.986530] [014/137] (843s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[158.050960] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[161.180102] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.129s)
[161.299832] [015/137] (839s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[161.356759] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[161.358870] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.001s)
[161.456606] [016/137] (839s left) kms_vblank (pipe-d-query-forked-busy-hang)
[161.519199] Subtest pipe-D-query-forked-busy-hang: SKIP (0.000s)
[161.586624] [017/137] (839s left) kms_addfb_basic (invalid-get-prop)
[161.666930] Starting subtest: invalid-get-prop
[161.667637] Subtest invalid-get-prop: SUCCESS (0.000s)
[161.745122] [018/137] (839s left) kms_flip (plain-flip-fb-recreate-interruptible)
[161.803947] Starting subtest: plain-flip-fb-recreate-interruptible
[161.805561] Starting dynamic subtest: A-eDP1
[178.377102] Dynamic subtest A-eDP1: SUCCESS (16.571s)
[178.383141] Starting dynamic subtest: B-eDP1
[194.954715] Dynamic subtest B-eDP1: SUCCESS (16.578s)
[194.955968] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (33.152s)
[196.254915] [019/137] (805s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted)
[196.310526] Starting subtest: invalid-multi-wait-available-unsubmitted
[196.311237] Subtest invalid-multi-wait-available-unsubmitted: SUCCESS (0.000s)
[196.388377] [020/137] (804s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[196.458939] Starting subtest: 4-tiled-16bpp-rotate-90
[196.460335] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.000s)
[196.555574] [021/137] (804s left) v3d/v3d_submit_cl (bad-bo)
[196.644451] Subtest bad-bo: SKIP (0.000s)
[196.701686] [022/137] (804s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[196.764811] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[196.765566] Starting dynamic subtest: pipe-A-default-mode
[197.060449] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[197.061962] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.294s)
[197.983388] [023/137] (803s left) kms_flip (2x-plain-flip)
[198.032417] Starting subtest: 2x-plain-flip
[198.033928] Subtest 2x-plain-flip: SKIP (0.001s)
[198.117015] [024/137] (803s left) kms_addfb_basic (small-bo)
[198.180538] Starting subtest: small-bo
[198.181453] Subtest small-bo: SUCCESS (0.000s)
[198.272378] [025/137] (803s left) kms_setmode (basic-clone-single-crtc)
[198.323774] Starting subtest: basic-clone-single-crtc
[198.326102] Subtest basic-clone-single-crtc: SKIP (0.000s)
[198.400518] [026/137] (803s left) gem_ppgtt (shrink-vs-evict-pinned)
[198.450481] Starting subtest: shrink-vs-evict-pinned
[203.560061] Subtest shrink-vs-evict-pinned: SUCCESS (5.074s)
[203.612240] [027/137] (797s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[203.676596] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[208.986517] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (5.311s)
[209.087158] [028/137] (792s left) gem_exec_params (readonly)
[209.139103] Starting subtest: readonly
[209.139880] Subtest readonly: SUCCESS (0.001s)
[209.230655] [029/137] (792s left) syncobj_wait (invalid-reset-illegal-handle)
[209.279522] Starting subtest: invalid-reset-illegal-handle
[209.280336] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[209.344165] [030/137] (792s left) kms_atomic (plane-cursor-legacy)
[209.503443] Starting subtest: plane-cursor-legacy
[209.566543] Subtest plane-cursor-legacy: SUCCESS (0.062s)
[210.788204] [031/137] (790s left) kms_big_fb (linear-16bpp-rotate-270)
[210.847587] Starting subtest: linear-16bpp-rotate-270
[210.873494] Subtest linear-16bpp-rotate-270: SKIP (0.025s)
[210.971666] [032/137] (790s left) gem_exec_fair (basic-pace-share)
[211.016959] Starting subtest: basic-pace-share
[211.017783] Starting dynamic subtest: rcs0
[215.379710] Dynamic subtest rcs0: FAIL (4.351s)
[215.380396] Subtest basic-pace-share: FAIL (4.351s)
[215.590541] [033/137] (785s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[215.659901] Starting subtest: yf-tiled-16bpp-rotate-90
[215.661391] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[215.758232] [034/137] (785s left) kms_psr (psr2_cursor_blt)
[215.822130] Starting subtest: psr2_cursor_blt
[217.984543] Subtest psr2_cursor_blt: FAIL (2.105s)
[218.089893] [035/137] (783s left) i915_query (query-garbage)
[218.138314] Starting subtest: query-garbage
[218.139151] Subtest query-garbage: SUCCESS (0.000s)
[218.204201] [036/137] (783s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[218.268949] Starting subtest: extended-modeset-hang-oldfb-with-reset
[218.269886] Starting dynamic subtest: pipe-A
[221.337068] Dynamic subtest pipe-A: SUCCESS (3.067s)
[221.344922] Starting dynamic subtest: pipe-B
[225.062867] Dynamic subtest pipe-B: SUCCESS (3.723s)
[225.063922] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (6.793s)
[226.020466] [037/137] (775s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_gen12_mc_ccs)
[226.401545] Subtest pipe-D-ccs-on-another-bo-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[227.299864] [038/137] (774s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[227.353610] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[227.355112] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[227.452329] [039/137] (774s left) gem_ctx_exec (basic)
[227.499886] Starting subtest: basic
[227.501492] Subtest basic: SUCCESS (0.002s)
[227.625231] [040/137] (774s left) vc4/vc4_perfmon (create-single-perfmon)
[227.705042] Subtest create-single-perfmon: SKIP (0.000s)
[227.764615] [041/137] (773s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[227.818727] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[227.930947] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[228.009910] [042/137] (773s left) gen9_exec_parse (bb-start-far)
[228.066880] Subtest bb-start-far: SKIP (0.000s)
[228.085934] [043/137] (773s left) kms_color (ctm-max)
[228.133710] Starting subtest: ctm-max
[228.134537] Starting dynamic subtest: pipe-A-eDP-1
[228.866696] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.732s)
[228.875341] Starting dynamic subtest: pipe-B-eDP-1
[230.352587] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.483s)
[230.353877] Subtest ctm-max: SUCCESS (2.218s)
[231.272789] [044/137] (770s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[231.637510] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[231.640091] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.001s)
[232.547251] [045/137] (769s left) gem_ctx_exec (basic-norecovery)
[232.592526] Starting subtest: basic-norecovery
[232.600789] Subtest basic-norecovery: SUCCESS (0.008s)
[232.704724] [046/137] (769s left) kms_cursor_legacy (torture-bo)
[232.765054] Starting subtest: torture-bo
[232.765910] Starting dynamic subtest: pipe-A
[254.345813] Dynamic subtest pipe-A: SUCCESS (21.546s)
[254.351335] Starting dynamic subtest: pipe-B
[275.937204] Dynamic subtest pipe-B: SUCCESS (21.590s)
[275.952131] Starting dynamic subtest: all-pipes
[297.503992] Dynamic subtest all-pipes: SUCCESS (21.602s)
[297.504750] Subtest torture-bo: SUCCESS (64.739s)
[297.591905] [047/137] (704s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[297.684044] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[297.684935] Starting dynamic subtest: pipe-A-default-mode
[300.646892] Dynamic subtest pipe-A-default-mode: SUCCESS (2.962s)
[300.648029] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.963s)
[301.605582] [048/137] (700s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[301.968418] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[302.885285] [049/137] (698s left) perf_pmu (rc6-runtime-pm-long)
[302.936905] Starting subtest: rc6-runtime-pm-long
[315.684112] Subtest rc6-runtime-pm-long: SUCCESS (12.746s)
[316.024956] [050/137] (685s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[316.079414] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[316.080887] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[316.174904] [051/137] (685s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[316.239382] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[320.029576] Subtest fbc-rgb101010-draw-mmap-gtt: SUCCESS (3.790s)
[320.152032] [052/137] (681s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[320.205792] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[320.207119] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[320.293813] [053/137] (681s left) kms_psr (sprite_plane_onoff)
[320.347619] Starting subtest: sprite_plane_onoff
[322.337948] Subtest sprite_plane_onoff: SUCCESS (1.990s)
[323.295064] [054/137] (678s left) gem_create (create-ext-placement-each)
[323.453654] Starting subtest: create-ext-placement-each
[323.455257] Subtest create-ext-placement-each: SUCCESS (0.000s)
[323.549396] [055/137] (678s left) dmabuf (all-tests)
[323.610864] Starting subtest: all-tests
[323.611702] Starting dynamic subtest: sanitycheck
[323.633657] Dynamic subtest sanitycheck: SUCCESS (0.023s)
[323.639894] Starting dynamic subtest: dma_fence
[323.771806] Dynamic subtest dma_fence: SUCCESS (0.138s)
[323.780453] Starting dynamic subtest: dma_fence_chain
[333.726607] Dynamic subtest dma_fence_chain: SUCCESS (9.955s)
[333.731741] Starting dynamic subtest: dma_fence_unwrap
[333.749837] Dynamic subtest dma_fence_unwrap: SUCCESS (0.023s)
[333.756802] Starting dynamic subtest: dma_resv
[333.785222] Dynamic subtest dma_resv: SUCCESS (0.035s)
[333.787173] Subtest all-tests: SUCCESS (10.174s)
[333.856481] [056/137] (668s left) sw_sync (sync_multi_consumer_producer)
[333.907944] Starting subtest: sync_multi_consumer_producer
[334.149297] Subtest sync_multi_consumer_producer: SUCCESS (0.233s)
[334.208824] [057/137] (667s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-25)
[334.272567] Starting subtest: planes-upscale-20x20-downscale-factor-0-25
[334.273288] Starting dynamic subtest: pipe-A-eDP-1
[334.313752] Dynamic subtest pipe-A-eDP-1: SKIP (0.040s)
[334.318412] Starting dynamic subtest: pipe-B-eDP-1
[334.349521] Dynamic subtest pipe-B-eDP-1: SKIP (0.035s)
[334.350903] Subtest planes-upscale-20x20-downscale-factor-0-25: SKIP (0.077s)
[334.446355] [058/137] (667s left) vgem_basic (sysfs)
[334.532191] Starting subtest: sysfs
[334.533450] Subtest sysfs: SUCCESS (0.001s)
[334.596555] [059/137] (667s left) gem_mmap_wc (close)
[334.645282] Starting subtest: close
[334.647435] Subtest close: SUCCESS (0.003s)
[334.743801] [060/137] (667s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[334.811982] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[338.773671] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (3.962s)
[338.918516] [061/137] (663s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_gen12_mc_ccs)
[339.285999] Subtest pipe-D-missing-ccs-buffer-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[340.197653] [062/137] (661s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[340.247831] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[340.248676] Starting dynamic subtest: smem
[340.318277] Dynamic subtest smem: SUCCESS (0.070s)
[340.318974] Subtest mixed-tiled-to-yf-tiled-ccs: SUCCESS (0.070s)
[340.513982] [063/137] (661s left) perf_pmu (busy-no-semaphores)
[340.569379] Starting subtest: busy-no-semaphores
[340.570174] Starting dynamic subtest: rcs0
[341.082690] Dynamic subtest rcs0: SUCCESS (0.504s)
[341.091646] Starting dynamic subtest: bcs0
[341.581831] Dynamic subtest bcs0: SUCCESS (0.507s)
[341.589276] Starting dynamic subtest: vcs0
[342.098130] Dynamic subtest vcs0: SUCCESS (0.507s)
[342.107250] Starting dynamic subtest: vecs0
[342.605243] Dynamic subtest vecs0: SUCCESS (0.507s)
[342.606829] Subtest busy-no-semaphores: SUCCESS (2.026s)
[342.715933] [064/137] (659s left) kms_vblank (pipe-d-ts-continuation-modeset-hang)
[342.770476] Subtest pipe-D-ts-continuation-modeset-hang: SKIP (0.000s)
[342.844826] [065/137] (659s left) gem_basic (create-close)
[342.897833] Starting subtest: create-close
[342.899201] Subtest create-close: SUCCESS (0.000s)
[342.998246] [066/137] (659s left) gem_exec_suspend (basic-s3)
[343.044351] Starting subtest: basic-S3
[343.045097] Starting dynamic subtest: smem
[362.576368] Dynamic subtest smem: SUCCESS (3.532s)
[362.577555] Subtest basic-S3: SUCCESS (3.532s)
[367.786027] [067/137] (655s left) gen9_exec_parse (basic-rejected)
[367.831679] Subtest basic-rejected: SKIP (0.000s)
[367.861795] [068/137] (655s left) syncobj_wait (invalid-reset-bad-pad)
[367.902588] Starting subtest: invalid-reset-bad-pad
[367.903398] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[367.961518] [069/137] (655s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[368.022381] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[368.023738] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[368.120337] [070/137] (655s left) gem_exec_reloc (basic-range-active)
[368.175108] Subtest basic-range-active: SKIP (0.000s)
[368.213319] [071/137] (654s left) i915_pm_dc (dc6-psr)
[369.271480] Starting subtest: dc6-psr
[372.805288] Subtest dc6-psr: SUCCESS (3.534s)
[373.726079] [072/137] (649s left) gem_ctx_persistence (legacy-engines-mixed)
[373.768840] Starting subtest: legacy-engines-mixed
[373.769574] Starting dynamic subtest: render
[373.807380] Dynamic subtest render: SUCCESS (0.037s)
[373.816947] Starting dynamic subtest: bsd
[373.850142] Dynamic subtest bsd: SUCCESS (0.042s)
[373.867431] Starting dynamic subtest: blt
[373.897158] Dynamic subtest blt: SUCCESS (0.046s)
[373.914352] Starting dynamic subtest: vebox
[373.944088] Dynamic subtest vebox: SUCCESS (0.046s)
[373.945788] Subtest legacy-engines-mixed: SUCCESS (0.174s)
[374.037862] [073/137] (649s left) kms_prop_blob (basic)
[374.090608] Starting subtest: basic
[374.091402] Subtest basic: SUCCESS (0.000s)
[374.167525] [074/137] (649s left) gem_exec_balancer (parallel-balancer)
[375.051389] Subtest parallel-balancer: SKIP (0.000s)
[375.136948] [075/137] (648s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[375.189433] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[375.190785] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[375.272192] [076/137] (648s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[375.333996] Starting subtest: cursorB-vs-flipA-atomic-transitions
[375.335390] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[375.396837] [077/137] (647s left) gem_exec_whisper (basic-contexts-forked-all)
[375.453131] Starting subtest: basic-contexts-forked-all
[389.812198] Subtest basic-contexts-forked-all: SUCCESS (14.358s)
[390.009272] [078/137] (633s left) syncobj_timeline (single-wait-all-signaled)
[390.050136] Starting subtest: single-wait-all-signaled
[390.050948] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[390.121691] [079/137] (633s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_dg2_mc_ccs)
[390.487896] Subtest pipe-C-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[391.398466] [080/137] (631s left) syncobj_basic (bad-flags-fd-to-handle)
[391.439385] Starting subtest: bad-flags-fd-to-handle
[391.440130] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[391.516664] [081/137] (631s left) vc4/vc4_wait_bo (used-bo)
[391.584201] Subtest used-bo: SKIP (0.000s)
[391.644770] [082/137] (631s left) gem_exec_whisper (basic-queues-priority-all)
[391.700767] Starting subtest: basic-queues-priority-all
[404.733009] Subtest basic-queues-priority-all: SUCCESS (13.031s)
[404.933984] [083/137] (618s left) kms_content_protection (legacy)
[405.013159] Starting subtest: legacy
[406.537468] Subtest legacy: SKIP (1.522s)
[407.782871] [084/137] (615s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[408.134901] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[409.063184] [085/137] (614s left) kms_selftest (all-tests)
[409.112222] Starting subtest: all-tests
[409.113739] Subtest all-tests: SKIP (0.000s)
[409.173693] [086/137] (614s left) kms_chamelium_frames (dp-crc-multiple)
[409.519310] Subtest dp-crc-multiple: SKIP (0.000s)
[410.423064] [087/137] (613s left) gem_exec_fence (syncobj-timeline-signal)
[410.843189] Starting subtest: syncobj-timeline-signal
[410.852494] Subtest syncobj-timeline-signal: SUCCESS (0.010s)
[411.035557] [088/137] (612s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[411.087489] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[414.773218] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.685s)
[414.925401] [089/137] (608s left) syncobj_wait (single-wait-for-submit-submitted)
[414.966577] Starting subtest: single-wait-for-submit-submitted
[415.073818] Subtest single-wait-for-submit-submitted: SUCCESS (0.107s)
[415.166321] [090/137] (608s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[415.220636] Starting subtest: y-tiled-16bpp-rotate-270
[416.582351] Subtest y-tiled-16bpp-rotate-270: SUCCESS (1.362s)
[416.703852] [091/137] (606s left) prime_self_import (reimport-vs-gem_close-race)
[416.744996] Starting subtest: reimport-vs-gem_close-race
[421.773862] Subtest reimport-vs-gem_close-race: SUCCESS (5.027s)
[421.880844] [092/137] (601s left) i915_pm_rps (min-max-config-idle)
[421.966633] Starting subtest: min-max-config-idle
[422.108476] Subtest min-max-config-idle: SUCCESS (0.142s)
[422.218408] [093/137] (601s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[422.268446] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[422.269801] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[422.354271] [094/137] (601s left) drm_fdinfo (idle)
[422.400374] Starting subtest: idle
[422.401115] Starting dynamic subtest: rcs0
[422.904102] Dynamic subtest rcs0: SUCCESS (0.502s)
[422.912167] Starting dynamic subtest: bcs0
[423.407369] Dynamic subtest bcs0: SUCCESS (0.502s)
[423.416691] Starting dynamic subtest: vcs0
[423.910597] Dynamic subtest vcs0: SUCCESS (0.502s)
[423.918715] Starting dynamic subtest: vecs0
[424.414206] Dynamic subtest vecs0: SUCCESS (0.502s)
[424.415997] Subtest idle: SUCCESS (2.012s)
[424.490767] [095/137] (599s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[424.542783] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[424.544563] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[424.636352] [096/137] (598s left) kms_flip (2x-nonexisting-fb)
[424.683499] Starting subtest: 2x-nonexisting-fb
[424.685300] Subtest 2x-nonexisting-fb: SKIP (0.001s)
[424.784507] [097/137] (598s left) vc4/vc4_create_bo (create-bo-4096)
[424.853900] Subtest create-bo-4096: SKIP (0.000s)
[424.915430] [098/137] (598s left) kms_lease (lease_invalid_crtc)
[424.974604] Starting subtest: lease_invalid_crtc
[424.975518] Starting dynamic subtest: pipe-A-eDP-1
[424.976436] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[424.981764] Starting dynamic subtest: pipe-B-eDP-1
[424.982692] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[424.983590] Subtest lease_invalid_crtc: SUCCESS (0.001s)
[425.047723] [099/137] (598s left) kms_getfb (getfb2-into-addfb2)
[425.097045] Starting subtest: getfb2-into-addfb2
[425.097846] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[425.208834] [100/137] (598s left) kms_flip (2x-flip-vs-fences)
[425.260290] Starting subtest: 2x-flip-vs-fences
[425.262053] Subtest 2x-flip-vs-fences: SKIP (0.001s)
[425.351290] [101/137] (598s left) kms_ccs (pipe-a-ccs-on-another-bo-y_tiled_gen12_rc_ccs)
[425.698059] Starting subtest: pipe-A-ccs-on-another-bo-y_tiled_gen12_rc_ccs
[425.698855] Subtest pipe-A-ccs-on-another-bo-y_tiled_gen12_rc_ccs: SUCCESS (0.000s)
[426.661978] [102/137] (597s left) drm_read (invalid-buffer)
[426.770804] Starting subtest: invalid-buffer
[426.771918] Subtest invalid-buffer: SUCCESS (0.000s)
[426.878887] [103/137] (596s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[426.940460] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[432.253875] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (5.314s)
[432.373264] [104/137] (591s left) gem_render_copy (x-tiled)
[432.424092] Starting subtest: x-tiled
[432.424775] Starting dynamic subtest: smem
[432.535034] Dynamic subtest smem: SUCCESS (0.109s)
[432.536243] Subtest x-tiled: SUCCESS (0.109s)
[432.723679] [105/137] (591s left) v3d/v3d_submit_cl (valid-multisync-submission)
[432.825636] Subtest valid-multisync-submission: SKIP (0.000s)
[432.893499] [106/137] (590s left) syncobj_timeline (multi-wait-available-submitted)
[432.942177] Starting subtest: multi-wait-available-submitted
[432.942948] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[433.019923] [107/137] (590s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[433.085339] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[433.506302] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.422s)
[433.639999] [108/137] (590s left) kms_getfb (getfb2-accept-ccs)
[433.682022] Starting subtest: getfb2-accept-ccs
[433.682805] Subtest getfb2-accept-ccs: SUCCESS (0.000s)
[433.779982] [109/137] (590s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[433.836802] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[433.838236] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[433.919660] [110/137] (589s left) kms_cursor_edge_walk (256x256-top-edge)
[433.980577] Starting subtest: 256x256-top-edge
[433.981503] Starting dynamic subtest: pipe-A-eDP-1
[438.560188] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.578s)
[438.568486] Starting dynamic subtest: pipe-B-eDP-1
[442.839033] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.278s)
[442.839829] Subtest 256x256-top-edge: SUCCESS (8.859s)
[443.815370] [111/137] (580s left) gem_ctx_sseu (invalid-args)
[443.868155] Subtest invalid-args: SKIP (0.000s)
[443.889846] [112/137] (580s left) kms_big_fb (linear-8bpp-rotate-0)
[443.941501] Starting subtest: linear-8bpp-rotate-0
[445.334444] Subtest linear-8bpp-rotate-0: SUCCESS (1.393s)
[445.447134] [113/137] (578s left) gem_userptr_blits (sync-unmap-cycles)
[445.503398] Starting subtest: sync-unmap-cycles
[450.876737] Subtest sync-unmap-cycles: SUCCESS (5.372s)
[450.998806] [114/137] (572s left) fbdev (unaligned-write)
[451.064311] Starting subtest: unaligned-write
[451.474426] Subtest unaligned-write: SUCCESS (0.400s)
[451.537344] [115/137] (572s left) gem_userptr_blits (create-destroy-sync)
[451.583831] Starting subtest: create-destroy-sync
[456.597554] Subtest create-destroy-sync: SUCCESS (5.013s)
[456.714363] [116/137] (567s left) gem_tiled_partial_pwrite_pread (writes)
[456.767825] Subtest writes: SKIP (0.000s)
[456.810086] [117/137] (567s left) kms_plane (pixel-format-source-clamping)
[456.869201] Starting subtest: pixel-format-source-clamping
[456.870131] Starting dynamic subtest: pipe-A-planes
[466.857389] Dynamic subtest pipe-A-planes: SUCCESS (9.987s)
[466.864266] Starting dynamic subtest: pipe-B-planes
[477.680127] Dynamic subtest pipe-B-planes: SUCCESS (10.821s)
[477.681350] Subtest pixel-format-source-clamping: SUCCESS (20.811s)
[478.631608] [118/137] (545s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_mc_ccs)
[478.995942] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_mc_ccs
[478.998225] Subtest pipe-A-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[479.911688] [119/137] (544s left) perf (i915-ref-count)
[480.016912] Starting subtest: i915-ref-count
[480.047254] Subtest i915-ref-count: SUCCESS (0.030s)
[480.075898] [120/137] (543s left) gem_exec_params (no-blt)
[480.117970] Starting subtest: no-blt
[480.118824] Subtest no-blt: SUCCESS (0.000s)
[480.229560] [121/137] (543s left) syncobj_wait (invalid-reset-zero-handles)
[480.270544] Starting subtest: invalid-reset-zero-handles
[480.271321] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[480.341049] [122/137] (543s left) sysfs_heartbeat_interval (mixed)
[480.393792] Starting subtest: mixed
[480.394586] Starting dynamic subtest: vecs0
[485.566054] Dynamic subtest vecs0: SUCCESS (5.170s)
[485.575928] Starting dynamic subtest: vcs0
[490.692766] Dynamic subtest vcs0: SUCCESS (5.126s)
[490.712593] Starting dynamic subtest: bcs0
[495.835829] Dynamic subtest bcs0: SUCCESS (5.141s)
[495.844877] Starting dynamic subtest: rcs0
[500.962735] Dynamic subtest rcs0: SUCCESS (5.125s)
[500.964627] Subtest mixed: SUCCESS (20.568s)
[501.055359] [123/137] (523s left) api_intel_allocator (reopen-fork)
[501.097514] Starting subtest: reopen-fork
[504.331404] Subtest reopen-fork: SUCCESS (3.233s)
[504.417712] [124/137] (519s left) syncobj_timeline (invalid-wait-zero-handles)
[504.467801] Starting subtest: invalid-wait-zero-handles
[504.468576] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[504.535636] [125/137] (519s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs)
[504.889389] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs
[504.890232] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs: SUCCESS (0.000s)
[505.828066] [126/137] (518s left) kms_vblank (pipe-c-accuracy-idle)
[505.878269] Subtest pipe-C-accuracy-idle: SKIP (0.000s)
[505.935563] [127/137] (518s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[505.983243] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[511.867640] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (5.884s)
[511.996695] [128/137] (512s left) gem_exec_schedule (preempt-queue)
[512.054261] Starting subtest: preempt-queue
[512.054970] Starting dynamic subtest: rcs0
[512.098581] Dynamic subtest rcs0: SUCCESS (0.044s)
[512.104138] Starting dynamic subtest: bcs0
[512.139585] Dynamic subtest bcs0: SUCCESS (0.041s)
[512.145132] Starting dynamic subtest: vcs0
[512.181337] Dynamic subtest vcs0: SUCCESS (0.041s)
[512.186790] Starting dynamic subtest: vecs0
[512.222439] Dynamic subtest vecs0: SUCCESS (0.041s)
[512.223150] Subtest preempt-queue: SUCCESS (0.168s)
[512.537246] [129/137] (511s left) kms_rotation_crc (sprite-rotation-90)
[512.595886] Starting subtest: sprite-rotation-90
[520.018517] Subtest sprite-rotation-90: SUCCESS (7.423s)
[521.316526] [130/137] (502s left) gem_exec_flush (basic-uc-prw-default)
[521.369632] Starting subtest: basic-uc-prw-default
[526.785479] Subtest basic-uc-prw-default: SUCCESS (5.416s)
[527.009887] [131/137] (497s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[527.401437] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[528.310398] [132/137] (495s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[528.363108] Starting subtest: 2x-cursor-vs-flip-atomic
[528.364908] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[528.434488] [133/137] (495s left) gem_exec_balancer (hog)
[528.478613] Starting subtest: hog
[529.174251] Subtest hog: SUCCESS (0.695s)
[530.087061] [134/137] (494s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[530.140091] Starting subtest: read-crc-frame-sequence
[530.141015] Starting dynamic subtest: pipe-A-eDP-1
[530.894265] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.753s)
[530.903930] Starting dynamic subtest: pipe-B-eDP-1
[532.385829] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.488s)
[532.386984] Subtest read-crc-frame-sequence: SUCCESS (2.244s)
[533.352648] [135/137] (490s left) gem_spin_batch (engines)
[533.399923] Starting subtest: engines
[533.400751] Starting dynamic subtest: rcs0
[536.719720] Dynamic subtest rcs0: SUCCESS (3.317s)
[536.727259] Starting dynamic subtest: bcs0
[540.041971] Dynamic subtest bcs0: SUCCESS (3.320s)
[540.050238] Starting dynamic subtest: vcs0
[543.363212] Dynamic subtest vcs0: SUCCESS (3.321s)
[543.369690] Starting dynamic subtest: vecs0
[546.684260] Dynamic subtest vecs0: SUCCESS (3.320s)
[546.685623] Subtest engines: SUCCESS (13.282s)
[546.866710] [136/137] (477s left) gem_mmap_gtt (cpuset-big-copy)
[546.902969] Starting subtest: cpuset-big-copy
[562.506963] Subtest cpuset-big-copy: SUCCESS (15.604s)
[562.611056] [137/137] (461s left) i915_pm_dc (dc5-dpms-negative)
[563.722676] Starting subtest: dc5-dpms-negative
[563.724239] Subtest dc5-dpms-negative: SKIP (0.000s)
[563.875220] Closing watchdogs
Done.