[60.848102] Initializing watchdogs
[60.848260]   /dev/watchdog0
[60.865220] [001/142] (960s left) kms_chamelium_color (ctm-limited-range)
[61.037781] Subtest ctm-limited-range: SKIP (0.000s)
[61.088208] [002/142] (960s left) api_intel_bb (misplaced-blitter)
[61.194855] Starting subtest: misplaced-blitter
[61.210256] Subtest misplaced-blitter: SUCCESS (0.017s)
[61.320906] [003/142] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[61.481543] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[66.626371] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (5.148s)
[66.765968] [004/142] (954s left) syncobj_timeline (wait-all-snapshot)
[66.872809] Starting subtest: wait-all-snapshot
[66.956354] Subtest wait-all-snapshot: SUCCESS (0.084s)
[67.027704] [005/142] (954s left) i915_query (query-topology-kernel-writes)
[67.129373] Starting subtest: query-topology-kernel-writes
[67.132199] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[67.198451] [006/142] (954s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs)
[67.697451] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs
[76.050063] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SUCCESS (8.353s)
[77.249608] [007/142] (944s left) gem_mmap_offset (bad-object)
[77.364118] Starting subtest: bad-object
[77.367109] Subtest bad-object: SUCCESS (0.002s)
[77.463850] [008/142] (944s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[77.620101] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[77.625335] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[77.699073] [009/142] (943s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[77.869653] Starting subtest: x-tiled-8bpp-rotate-0
[79.720169] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.852s)
[79.869879] [010/142] (941s left) gem_eio (wait-10ms)
[80.306223] Starting subtest: wait-10ms
[80.748583] Subtest wait-10ms: SUCCESS (0.444s)
[81.502078] [011/142] (940s left) kms_cursor_crc (cursor-sliding-64x64)
[81.746387] Starting subtest: cursor-sliding-64x64
[81.755752] Starting dynamic subtest: pipe-A-eDP-1
[85.680668] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.923s)
[85.746469] Starting dynamic subtest: pipe-D-eDP-1
[90.238484] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.504s)
[90.241476] Subtest cursor-sliding-64x64: SUCCESS (8.494s)
[91.141474] [012/142] (930s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[91.608098] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[92.764942] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.149s)
[93.968904] [013/142] (927s left) core_getstats
[94.142565] [014/142] (927s left) gem_barrier_race (remote-request)
[94.278825] Starting subtest: remote-request
[94.281365] Starting dynamic subtest: rcs0
[99.724634] Dynamic subtest rcs0: SUCCESS (5.448s)
[99.727271] Subtest remote-request: SUCCESS (5.449s)
[99.847705] [015/142] (921s left) kms_ccs (pipe-b-bad-aux-stride-4_tiled_mtl_rc_ccs)
[100.397446] Starting subtest: pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs
[100.401201] Subtest pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs: SUCCESS (0.002s)
[101.194671] [016/142] (920s left) gem_exec_reloc (basic-softpin)
[101.310214] Subtest basic-softpin: SKIP (0.000s)
[101.373341] [017/142] (920s left) gem_mmap_wc (write-wc-read-gtt)
[101.477912] Subtest write-wc-read-gtt: SKIP (0.000s)
[101.521949] [018/142] (920s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_mtl_rc_ccs)
[102.128745] Starting subtest: pipe-C-random-ccs-data-4_tiled_mtl_rc_ccs
[102.837081] Subtest pipe-C-random-ccs-data-4_tiled_mtl_rc_ccs: SUCCESS (0.710s)
[104.049988] [019/142] (917s left) v3d/v3d_submit_cl (bad-bo)
[104.210654] Subtest bad-bo: SKIP (0.000s)
[104.266991] [020/142] (917s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[104.382567] Subtest untiled-to-tiled: SKIP (0.000s)
[104.426363] [021/142] (917s left) perf (oa-exponents)
[104.638430] Starting subtest: oa-exponents
[104.641339] Starting dynamic subtest: 0-rcs0
[106.536456] Dynamic subtest 0-rcs0: SUCCESS (1.896s)
[106.553568] Starting dynamic subtest: 1-vcs0
[108.446619] Dynamic subtest 1-vcs0: SUCCESS (1.910s)
[108.449578] Subtest oa-exponents: SUCCESS (3.808s)
[108.502711] [022/142] (913s left) syncobj_timeline (32bits-limit)
[108.611262] Starting subtest: 32bits-limit
[108.615452] Subtest 32bits-limit: SUCCESS (0.003s)
[108.691260] [023/142] (913s left) kms_psr (psr2_sprite_render)
[108.851313] Starting subtest: psr2_sprite_render
[110.723860] Subtest psr2_sprite_render: SUCCESS (1.873s)
[111.615065] [024/142] (910s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[111.763427] Starting subtest: primary-y-tiled-reflect-x-180
[111.846377] Subtest primary-y-tiled-reflect-x-180: SKIP (0.082s)
[111.965581] [025/142] (910s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-blt)
[112.126275] Starting subtest: psr-1p-offscren-pri-indfb-draw-blt
[117.756274] Subtest psr-1p-offscren-pri-indfb-draw-blt: SUCCESS (5.634s)
[117.897247] [026/142] (904s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[118.014413] Starting subtest: y-tiled-to-vebox-x-tiled
[118.019736] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[118.204134] [027/142] (904s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[118.351765] Starting subtest: sprite-rotation-90-pos-100-0
[118.356735] Subtest sprite-rotation-90-pos-100-0: SKIP (0.002s)
[118.427203] [028/142] (903s left) kms_chamelium_edid (vga-edid-read)
[118.889876] Subtest vga-edid-read: SKIP (0.000s)
[119.738364] [029/142] (902s left) kms_hdr (static-toggle-suspend)
[119.887316] Starting subtest: static-toggle-suspend
[119.892604] Subtest static-toggle-suspend: SKIP (0.000s)
[119.962448] [030/142] (902s left) kms_big_fb (linear-32bpp-rotate-270)
[120.130723] Starting subtest: linear-32bpp-rotate-270
[120.163682] Subtest linear-32bpp-rotate-270: SKIP (0.029s)
[120.264225] [031/142] (902s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[120.914472] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc
[120.920115] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s)
[121.626392] [032/142] (900s left) gem_exec_schedule (out-order)
[121.756879] Starting subtest: out-order
[121.759538] Starting dynamic subtest: rcs0
[121.921308] Dynamic subtest rcs0: SUCCESS (0.164s)
[121.937175] Starting dynamic subtest: bcs0
[122.044101] Dynamic subtest bcs0: SUCCESS (0.123s)
[122.060081] Starting dynamic subtest: vcs0
[122.166172] Dynamic subtest vcs0: SUCCESS (0.122s)
[122.181531] Starting dynamic subtest: vcs1
[122.287533] Dynamic subtest vcs1: SUCCESS (0.120s)
[122.303742] Starting dynamic subtest: vecs0
[122.409280] Dynamic subtest vecs0: SUCCESS (0.121s)
[122.425753] Starting dynamic subtest: ccs0
[122.538278] Dynamic subtest ccs0: SUCCESS (0.128s)
[122.541763] Subtest out-order: SUCCESS (0.783s)
[123.537417] [033/142] (898s left) kms_pm_backlight (fade-with-suspend)
[123.689138] Starting subtest: fade-with-suspend
[141.339534] Starting dynamic subtest: eDP-1
[144.065285] Dynamic subtest eDP-1: SUCCESS (2.725s)
[144.068163] Subtest fade-with-suspend: SUCCESS (4.379s)
[144.964918] [034/142] (893s left) gem_ctx_persistence (processes)
[145.076035] Starting subtest: processes
[146.491885] Subtest processes: SUCCESS (1.416s)
[146.574723] [035/142] (891s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[146.698336] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[146.733537] [036/142] (891s left) perf_pmu (init-busy)
[146.841494] Starting subtest: init-busy
[146.844765] Starting dynamic subtest: rcs0
[146.847444] Dynamic subtest rcs0: SUCCESS (0.002s)
[146.865171] Starting dynamic subtest: bcs0
[146.867860] Dynamic subtest bcs0: SUCCESS (0.001s)
[146.887039] Starting dynamic subtest: vcs0
[146.889932] Dynamic subtest vcs0: SUCCESS (0.001s)
[146.909855] Starting dynamic subtest: vcs1
[146.912844] Dynamic subtest vcs1: SUCCESS (0.001s)
[146.930458] Starting dynamic subtest: vecs0
[146.933118] Dynamic subtest vecs0: SUCCESS (0.001s)
[146.950748] Starting dynamic subtest: ccs0
[146.953447] Dynamic subtest ccs0: SUCCESS (0.001s)
[146.956051] Subtest init-busy: SUCCESS (0.012s)
[146.987089] [037/142] (891s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[147.146348] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[147.151461] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[147.239232] [038/142] (891s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[147.838433] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[147.845452] Subtest pipe-C-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.001s)
[148.610502] [039/142] (890s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[148.769431] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[148.773761] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[148.862529] [040/142] (889s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs)
[149.383473] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs
[149.390601] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[150.220197] [041/142] (888s left) gem_exec_schedule (timeslicing)
[150.348566] Starting subtest: timeslicing
[150.350874] Starting dynamic subtest: rcs0
[150.362701] Dynamic subtest rcs0: SUCCESS (0.017s)
[150.381650] Starting dynamic subtest: bcs0
[150.384698] Dynamic subtest bcs0: SUCCESS (0.016s)
[150.403229] Starting dynamic subtest: vcs0
[150.406047] Dynamic subtest vcs0: SUCCESS (0.021s)
[150.430199] Starting dynamic subtest: vcs1
[150.432862] Dynamic subtest vcs1: SUCCESS (0.020s)
[150.451470] Starting dynamic subtest: vecs0
[150.453746] Dynamic subtest vecs0: SUCCESS (0.021s)
[150.471418] Starting dynamic subtest: ccs0
[150.474074] Dynamic subtest ccs0: SUCCESS (0.016s)
[150.476641] Subtest timeslicing: SUCCESS (0.118s)
[152.475680] [042/142] (886s left) kms_flip (wf_vblank-ts-check-interruptible)
[152.600914] Starting subtest: wf_vblank-ts-check-interruptible
[152.613625] Starting dynamic subtest: A-eDP1
[161.762675] Dynamic subtest A-eDP1: SUCCESS (9.149s)
[161.785838] Starting dynamic subtest: B-eDP1
[170.849570] Dynamic subtest B-eDP1: SUCCESS (9.086s)
[170.872630] Starting dynamic subtest: C-eDP1
[179.926133] Dynamic subtest C-eDP1: SUCCESS (9.075s)
[179.945402] Starting dynamic subtest: D-eDP1
[189.014662] Dynamic subtest D-eDP1: SUCCESS (9.086s)
[189.023618] Subtest wf_vblank-ts-check-interruptible: SUCCESS (36.422s)
[190.341215] [043/142] (848s left) kms_flip (2x-flip-vs-fences-interruptible)
[190.472579] Starting subtest: 2x-flip-vs-fences-interruptible
[190.480275] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[190.560702] [044/142] (848s left) kms_pipe_crc_basic (nonblocking-crc)
[190.708560] Starting subtest: nonblocking-crc
[190.711339] Starting dynamic subtest: pipe-A-eDP-1
[191.581977] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.874s)
[191.606850] Starting dynamic subtest: pipe-B-eDP-1
[193.172732] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.586s)
[193.195549] Starting dynamic subtest: pipe-C-eDP-1
[194.698091] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.520s)
[194.716886] Starting dynamic subtest: pipe-D-eDP-1
[196.254284] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.550s)
[196.257077] Subtest nonblocking-crc: SUCCESS (5.547s)
[197.153139] [045/142] (841s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[197.623131] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[198.479907] [046/142] (840s left) gem_exec_fence (syncobj-signal)
[199.342349] Starting subtest: syncobj-signal
[199.349331] Subtest syncobj-signal: SUCCESS (0.006s)
[200.506672] [047/142] (838s left) kms_plane_scaling (plane-upscale-with-modifiers-20x20)
[200.658310] Starting subtest: plane-upscale-with-modifiers-20x20
[200.661375] Starting dynamic subtest: pipe-A-eDP-1
[201.233608] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.573s)
[201.256452] Starting dynamic subtest: pipe-B-eDP-1
[203.018705] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.780s)
[203.037952] Starting dynamic subtest: pipe-C-eDP-1
[204.736287] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.713s)
[204.756197] Starting dynamic subtest: pipe-D-eDP-1
[206.485601] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.746s)
[206.488325] Subtest plane-upscale-with-modifiers-20x20: SUCCESS (5.829s)
[207.811717] [048/142] (831s left) gem_render_copy (y-tiled)
[207.929108] Starting subtest: y-tiled
[207.935834] Subtest y-tiled: SKIP (0.000s)
[208.123915] [049/142] (830s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[208.286140] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[214.070426] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.792s)
[214.204149] [050/142] (824s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt)
[214.368909] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-gtt
[214.374234] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[214.461641] [051/142] (824s left) v3d/v3d_perfmon (destroy-invalid-perfmon)
[214.611705] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[214.672888] [052/142] (824s left) v3d/v3d_submit_csd (valid-submission)
[214.823014] Subtest valid-submission: SKIP (0.000s)
[214.882443] [053/142] (824s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[215.429510] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[215.433275] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[216.243956] [054/142] (822s left) vc4/vc4_tiling (set-bad-modifier)
[216.394843] Subtest set-bad-modifier: SKIP (0.000s)
[216.456381] [055/142] (822s left) prime_mmap (test_invalid_sync_flags)
[216.563812] Starting subtest: test_invalid_sync_flags
[216.566834] Starting dynamic subtest: test_invalid_sync_flags-smem
[216.569616] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[216.572493] Subtest test_invalid_sync_flags: SUCCESS (0.002s)
[216.662705] [056/142] (822s left) gem_exec_params (invalid-flag)
[216.776563] Starting subtest: invalid-flag
[216.778920] Subtest invalid-flag: SUCCESS (0.001s)
[216.869280] [057/142] (822s left) kms_cursor_crc (cursor-offscreen-128x128)
[217.154857] Starting subtest: cursor-offscreen-128x128
[217.166722] Starting dynamic subtest: pipe-A-eDP-1
[219.311513] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.143s)
[219.387356] Starting dynamic subtest: pipe-D-eDP-1
[222.114061] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.744s)
[222.119482] Subtest cursor-offscreen-128x128: SUCCESS (4.959s)
[223.019758] [058/142] (816s left) perf_pmu (busy-no-semaphores)
[223.132570] Starting subtest: busy-no-semaphores
[223.134878] Starting dynamic subtest: rcs0
[223.682145] Dynamic subtest rcs0: SUCCESS (0.550s)
[223.704421] Starting dynamic subtest: bcs0
[224.234347] Dynamic subtest bcs0: SUCCESS (0.550s)
[224.254050] Starting dynamic subtest: vcs0
[224.786344] Dynamic subtest vcs0: SUCCESS (0.550s)
[224.807751] Starting dynamic subtest: vcs1
[225.936532] Dynamic subtest vcs1: SUCCESS (1.147s)
[225.956268] Starting dynamic subtest: vecs0
[227.921235] Dynamic subtest vecs0: SUCCESS (1.983s)
[227.941050] Starting dynamic subtest: ccs0
[229.906471] Dynamic subtest ccs0: SUCCESS (1.984s)
[229.909718] Subtest busy-no-semaphores: SUCCESS (6.776s)
[230.029614] [059/142] (809s left) kms_vrr (flip-dpms)
[230.189849] Starting subtest: flip-dpms
[230.195095] Subtest flip-dpms: SKIP (0.000s)
[230.267646] [060/142] (809s left) gem_basic (create-fd-close)
[230.374268] Starting subtest: create-fd-close
[230.380215] Subtest create-fd-close: SUCCESS (0.000s)
[230.472677] [061/142] (809s left) gem_partial_pwrite_pread (writes-after-reads)
[230.588577] Subtest writes-after-reads: SKIP (0.000s)
[230.659867] [062/142] (808s left) kms_ccs (pipe-d-ccs-on-another-bo-yf_tiled_ccs)
[231.318081] Starting subtest: pipe-D-ccs-on-another-bo-yf_tiled_ccs
[231.324935] Subtest pipe-D-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s)
[232.020240] [063/142] (807s left) syncobj_wait (invalid-reset-one-illegal-handle)
[232.129208] Starting subtest: invalid-reset-one-illegal-handle
[232.131938] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[232.206605] [064/142] (807s left) kms_draw_crc (draw-method-pwrite)
[232.357516] Starting subtest: draw-method-pwrite
[232.360207] Starting dynamic subtest: xrgb8888-untiled
[233.107900] Dynamic subtest xrgb8888-untiled: SUCCESS (0.750s)
[233.116129] Starting dynamic subtest: rgb565-untiled
[233.862655] Dynamic subtest rgb565-untiled: SUCCESS (0.754s)
[233.884875] Starting dynamic subtest: xrgb2101010-untiled
[234.584362] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.720s)
[234.602997] Starting dynamic subtest: xrgb8888-xtiled
[235.920300] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.332s)
[235.939196] Starting dynamic subtest: rgb565-xtiled
[236.796013] Dynamic subtest rgb565-xtiled: SUCCESS (0.878s)
[236.815322] Starting dynamic subtest: xrgb2101010-xtiled
[238.685031] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.887s)
[238.703201] Starting dynamic subtest: xrgb8888-4tiled
[239.918888] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.233s)
[239.937527] Starting dynamic subtest: rgb565-4tiled
[240.851076] Dynamic subtest rgb565-4tiled: SUCCESS (0.933s)
[240.870805] Starting dynamic subtest: xrgb2101010-4tiled
[242.173293] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.321s)
[242.176109] Subtest draw-method-pwrite: SUCCESS (9.818s)
[242.256465] [065/142] (797s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[242.418524] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[242.423416] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[242.525916] [066/142] (797s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[242.634092] Starting subtest: invalid-single-wait-all-available-unsubmitted
[242.636882] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[242.714145] [067/142] (796s left) kms_ccs (pipe-b-random-ccs-data-yf_tiled_ccs)
[243.250367] Starting subtest: pipe-B-random-ccs-data-yf_tiled_ccs
[243.258797] Subtest pipe-B-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[244.074117] [068/142] (795s left) kms_ccs (pipe-a-bad-rotation-90-yf_tiled_ccs)
[244.541511] Starting subtest: pipe-A-bad-rotation-90-yf_tiled_ccs
[244.546986] Subtest pipe-A-bad-rotation-90-yf_tiled_ccs: SKIP (0.000s)
[245.434255] [069/142] (794s left) gem_eio (wait-wedge-1us)
[245.926597] Starting subtest: wait-wedge-1us
[247.625251] Subtest wait-wedge-1us: SUCCESS (1.697s)
[249.502560] [070/142] (790s left) kms_ccs (pipe-a-bad-aux-stride-4_tiled_mtl_mc_ccs)
[250.002185] Starting subtest: pipe-A-bad-aux-stride-4_tiled_mtl_mc_ccs
[250.214394] Subtest pipe-A-bad-aux-stride-4_tiled_mtl_mc_ccs: SUCCESS (0.212s)
[250.860590] [071/142] (789s left) gem_exec_schedule (preempt-other)
[250.989612] Starting subtest: preempt-other
[250.993112] Starting dynamic subtest: rcs0
[251.125605] Dynamic subtest rcs0: SUCCESS (0.134s)
[251.147281] Starting dynamic subtest: bcs0
[251.259678] Dynamic subtest bcs0: SUCCESS (0.133s)
[251.277676] Starting dynamic subtest: vcs0
[251.371097] Dynamic subtest vcs0: SUCCESS (0.111s)
[251.388591] Starting dynamic subtest: vcs1
[251.460851] Dynamic subtest vcs1: SUCCESS (0.088s)
[251.478605] Starting dynamic subtest: vecs0
[251.539278] Dynamic subtest vecs0: SUCCESS (0.078s)
[251.556685] Starting dynamic subtest: ccs0
[251.611904] Dynamic subtest ccs0: SUCCESS (0.072s)
[251.614669] Subtest preempt-other: SUCCESS (0.624s)
[252.545069] [072/142] (787s left) kms_vblank (pipe-d-query-busy-hang)
[252.697476] Starting subtest: pipe-D-query-busy-hang
[265.594930] Subtest pipe-D-query-busy-hang: SUCCESS (12.896s)
[266.501146] [073/142] (773s left) kms_big_fb (y-tiled-addfb)
[266.669839] Starting subtest: y-tiled-addfb
[266.675122] Subtest y-tiled-addfb: SKIP (0.000s)
[266.770106] [074/142] (773s left) gem_mmap_gtt (basic-small-bo)
[266.881337] Subtest basic-small-bo: SKIP (0.000s)
[266.925052] [075/142] (773s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[267.094470] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[267.099886] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[267.202497] [076/142] (772s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[267.816136] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_mc_ccs
[267.893130] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SUCCESS (0.079s)
[268.571002] [077/142] (771s left) i915_pm_rpm (dpms-non-lpsp)
[270.567491] Starting subtest: dpms-non-lpsp
[270.573806] Subtest dpms-non-lpsp: SKIP (0.001s)
[271.071646] [078/142] (769s left) kms_addfb_basic (unused-pitches)
[271.175930] Starting subtest: unused-pitches
[271.178769] Subtest unused-pitches: SUCCESS (0.000s)
[271.328425] [079/142] (768s left) gem_exec_balancer (hog)
[271.450092] Starting subtest: hog
[271.456520] Subtest hog: SKIP (0.000s)
[271.894868] [080/142] (768s left) gem_madvise (dontneed-before-exec)
[271.995282] Starting subtest: dontneed-before-exec
[272.003522] Subtest dontneed-before-exec: SKIP (0.003s)
[272.062573] [081/142] (768s left) gem_mmap_gtt (medium-copy)
[272.166564] Subtest medium-copy: SKIP (0.000s)
[272.214564] [082/142] (768s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[272.328998] Starting subtest: yf-tiled-ccs-to-x-tiled
[272.334366] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[272.524019] [083/142] (767s left) kms_vblank (pipe-c-wait-forked)
[272.678632] Starting subtest: pipe-C-wait-forked
[275.847333] Subtest pipe-C-wait-forked: SUCCESS (3.168s)
[276.747620] [084/142] (763s left) gem_exec_fair (basic-pace)
[276.880089] Subtest basic-pace: SKIP (0.000s)
[276.935743] [085/142] (763s left) syncobj_timeline (wait-all-delayed-signal)
[277.041005] Starting subtest: wait-all-delayed-signal
[277.143308] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[277.224832] [086/142] (763s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_mc_ccs)
[277.790232] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs
[277.795691] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[278.586617] [087/142] (761s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[278.760162] Starting subtest: 4-tiled-8bpp-rotate-180
[280.335631] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.574s)
[280.478045] [088/142] (760s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[280.630100] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[280.632484] Starting dynamic subtest: pipe-A-default-mode
[280.978848] Dynamic subtest pipe-A-default-mode: SKIP (0.343s)
[280.984728] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.348s)
[281.845999] [089/142] (758s left) gem_mmap_gtt (big-bo-tiledy)
[281.951190] Subtest big-bo-tiledY: SKIP (0.000s)
[281.999202] [090/142] (758s left) vgem_basic (dmabuf-fence-before)
[282.113099] Starting subtest: dmabuf-fence-before
[282.116493] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[282.173985] [091/142] (758s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[282.336517] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[282.340953] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[282.435557] [092/142] (758s left) gen7_exec_parse (bitmasks)
[282.549943] Subtest bitmasks: SKIP (0.000s)
[282.598348] [093/142] (758s left) kms_atomic (plane-cursor-legacy)
[282.850559] Starting subtest: plane-cursor-legacy
[282.939080] Subtest plane-cursor-legacy: SUCCESS (0.087s)
[284.156357] [094/142] (756s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[284.273441] Starting subtest: 2x-flip-vs-modeset-vs-hang
[284.337828] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.062s)
[284.439379] [095/142] (756s left) kms_addfb_basic (basic)
[284.545641] Starting subtest: basic
[284.547865] Subtest basic: SUCCESS (0.000s)
[284.686546] [096/142] (756s left) dmabuf (all-tests)
[284.792556] Starting subtest: all-tests
[284.795937] Starting dynamic subtest: sanitycheck
[284.861303] Dynamic subtest sanitycheck: SUCCESS (0.069s)
[284.894606] Starting dynamic subtest: dma_fence
[285.060105] Dynamic subtest dma_fence: SUCCESS (0.197s)
[285.093375] Starting dynamic subtest: dma_fence_chain
[294.451341] Dynamic subtest dma_fence_chain: SUCCESS (9.388s)
[294.478678] Starting dynamic subtest: dma_fence_unwrap
[294.535788] Dynamic subtest dma_fence_unwrap: SUCCESS (0.083s)
[294.565440] Starting dynamic subtest: dma_resv
[294.624016] Dynamic subtest dma_resv: SUCCESS (0.087s)
[294.627592] Subtest all-tests: SUCCESS (9.833s)
[294.701897] [097/142] (746s left) i915_suspend (basic-s3-without-i915)
[294.800727] Starting subtest: basic-s3-without-i915
[296.788053] Subtest basic-s3-without-i915: SKIP (1.979s)
[298.485232] [098/142] (742s left) kms_vblank (pipe-b-query-forked-busy-hang)
[298.663907] Starting subtest: pipe-B-query-forked-busy-hang
[311.610483] Subtest pipe-B-query-forked-busy-hang: SUCCESS (12.947s)
[312.520753] [099/142] (728s left) gem_mmap_wc (read-write-distinct)
[312.635458] Subtest read-write-distinct: SKIP (0.000s)
[312.674484] [100/142] (728s left) kms_content_protection (legacy)
[312.919955] Starting subtest: legacy
[317.080476] Subtest legacy: SKIP (4.156s)
[318.400534] [101/142] (722s left) kms_cursor_crc (cursor-random-512x170)
[318.802837] Starting subtest: cursor-random-512x170
[318.808053] Subtest cursor-random-512x170: SKIP (0.002s)
[318.931143] [102/142] (722s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[319.068098] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[319.072573] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[319.158398] [103/142] (721s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[319.301622] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[319.308105] Starting dynamic subtest: pipe-A-default-mode
[319.622691] Dynamic subtest pipe-A-default-mode: SKIP (0.312s)
[319.628304] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.317s)
[320.515766] [104/142] (720s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[320.662539] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[320.664753] Starting dynamic subtest: pipe-A-eDP-1
[321.554388] Dynamic subtest pipe-A-eDP-1: SKIP (0.883s)
[321.563720] Starting dynamic subtest: pipe-B-eDP-1
[323.655383] Dynamic subtest pipe-B-eDP-1: SKIP (2.098s)
[323.666675] Starting dynamic subtest: pipe-C-eDP-1
[325.096596] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.446s)
[325.119083] Starting dynamic subtest: pipe-D-eDP-1
[326.591883] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.491s)
[326.594613] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (5.930s)
[327.941385] [105/142] (713s left) syncobj_wait (single-wait-all-for-submit-submitted)
[328.050088] Starting subtest: single-wait-all-for-submit-submitted
[328.158283] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.109s)
[328.237836] [106/142] (713s left) gen9_exec_parse (shadow-peek)
[328.358210] Subtest shadow-peek: SKIP (0.000s)
[328.403325] [107/142] (712s left) perf (polling)
[328.710335] Starting subtest: polling
[328.712843] Starting dynamic subtest: 0-rcs0
[338.860894] Dynamic subtest 0-rcs0: FAIL (10.098s)
[338.893103] Starting dynamic subtest: 1-vecs0
[348.927212] Dynamic subtest 1-vecs0: FAIL (10.058s)
[348.930340] Subtest polling: FAIL (20.163s)
[348.963751] [108/142] (692s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[349.072836] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[349.075872] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[349.155426] [109/142] (692s left) kms_lease (lease_invalid_plane)
[349.309222] Starting subtest: lease_invalid_plane
[349.311896] Starting dynamic subtest: pipe-A-eDP-1
[349.314579] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[349.333141] Starting dynamic subtest: pipe-B-eDP-1
[349.336241] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[349.351060] Starting dynamic subtest: pipe-C-eDP-1
[349.353319] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[349.369162] Starting dynamic subtest: pipe-D-eDP-1
[349.371275] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[349.373403] Subtest lease_invalid_plane: SUCCESS (0.012s)
[349.402457] [110/142] (691s left) perf (buffer-fill)
[349.641092] Starting subtest: buffer-fill
[349.644405] Starting dynamic subtest: 0-rcs0
[351.166929] Dynamic subtest 0-rcs0: FAIL (0.823s)
[351.198515] Starting dynamic subtest: 1-vecs0
[356.832333] Dynamic subtest 1-vecs0: SUCCESS (5.836s)
[356.835225] Subtest buffer-fill: FAIL (7.192s)
[356.883507] [111/142] (684s left) gem_ctx_shared (q-in-order)
[357.008436] Starting subtest: Q-in-order
[357.011247] Starting dynamic subtest: rcs0
[357.244177] Dynamic subtest rcs0: SUCCESS (0.233s)
[357.279601] Starting dynamic subtest: bcs0
[357.424345] Dynamic subtest bcs0: SUCCESS (0.178s)
[357.461965] Starting dynamic subtest: vcs0
[357.562575] Dynamic subtest vcs0: SUCCESS (0.137s)
[357.597609] Starting dynamic subtest: vcs1
[357.704402] Dynamic subtest vcs1: SUCCESS (0.139s)
[357.740289] Starting dynamic subtest: vecs0
[357.849852] Dynamic subtest vecs0: SUCCESS (0.144s)
[357.883927] Starting dynamic subtest: ccs0
[358.030134] Dynamic subtest ccs0: SUCCESS (0.178s)
[358.033231] Subtest Q-in-order: SUCCESS (1.021s)
[359.572562] [112/142] (681s left) drm_buddy (drm_buddy_test)
[359.675833] Starting subtest: drm_buddy_test
[359.681211] Subtest drm_buddy_test: SKIP (0.004s)
[359.753295] [113/142] (681s left) syncobj_timeline (invalid-wait-zero-handles)
[359.858237] Starting subtest: invalid-wait-zero-handles
[359.860997] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[359.941762] [114/142] (681s left) kms_plane_cursor (overlay)
[360.101899] Starting subtest: overlay
[360.107121] Starting dynamic subtest: pipe-A-eDP-1-size-64
[364.005580] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (3.903s)
[364.073769] Starting dynamic subtest: pipe-A-eDP-1-size-128
[367.973000] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (3.920s)
[368.036493] Starting dynamic subtest: pipe-A-eDP-1-size-256
[371.906713] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (3.887s)
[372.288241] Starting dynamic subtest: pipe-B-eDP-1-size-64
[376.870014] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (4.600s)
[376.932136] Starting dynamic subtest: pipe-B-eDP-1-size-128
[380.827315] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (3.909s)
[380.895185] Starting dynamic subtest: pipe-B-eDP-1-size-256
[384.761153] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (3.887s)
[385.134716] Starting dynamic subtest: pipe-C-eDP-1-size-64
[389.329076] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (4.216s)
[389.379810] Starting dynamic subtest: pipe-C-eDP-1-size-128
[392.794532] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (3.439s)
[392.840493] Starting dynamic subtest: pipe-C-eDP-1-size-256
[396.287037] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (3.461s)
[396.651182] Starting dynamic subtest: pipe-D-eDP-1-size-64
[400.872875] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (4.243s)
[400.918721] Starting dynamic subtest: pipe-D-eDP-1-size-128
[404.273318] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (3.373s)
[404.320847] Starting dynamic subtest: pipe-D-eDP-1-size-256
[407.729645] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (3.428s)
[408.076474] Subtest overlay: SUCCESS (47.972s)
[408.970057] [115/142] (632s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[409.150116] Starting subtest: yf-tiled-16bpp-rotate-270
[409.155288] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[409.244028] [116/142] (632s left) kms_panel_fitting (atomic-fastset)
[409.394911] Starting subtest: atomic-fastset
[409.397590] Starting dynamic subtest: pipe-A-eDP-1
[409.613272] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.215s)
[409.632479] Starting dynamic subtest: pipe-B-eDP-1
[410.989195] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.373s)
[411.008477] Starting dynamic subtest: pipe-C-eDP-1
[412.364366] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.373s)
[412.382859] Starting dynamic subtest: pipe-D-eDP-1
[413.774510] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.405s)
[413.777871] Subtest atomic-fastset: SUCCESS (4.377s)
[415.106441] [117/142] (626s left) vc4/vc4_perfmon (create-perfmon-0)
[415.258380] Subtest create-perfmon-0: SKIP (0.000s)
[415.318060] [118/142] (626s left) gem_exec_flush (basic-uc-prw-default)
[415.440976] Starting subtest: basic-uc-prw-default
[421.144296] Subtest basic-uc-prw-default: SUCCESS (5.702s)
[421.341738] [119/142] (620s left) kms_vblank (pipe-c-query-forked-hang)
[421.501312] Starting subtest: pipe-C-query-forked-hang
[433.592855] Subtest pipe-C-query-forked-hang: SUCCESS (12.088s)
[434.491141] [120/142] (607s left) gem_ctx_engines (independent)
[434.616277] Starting subtest: independent
[434.619079] Starting dynamic subtest: rcs0
[434.779511] Dynamic subtest rcs0: SUCCESS (0.160s)
[434.797066] Starting dynamic subtest: bcs0
[434.880085] Dynamic subtest bcs0: SUCCESS (0.101s)
[434.897095] Starting dynamic subtest: vcs0
[435.007075] Dynamic subtest vcs0: SUCCESS (0.125s)
[435.026204] Starting dynamic subtest: vcs1
[435.102962] Dynamic subtest vcs1: SUCCESS (0.096s)
[435.122334] Starting dynamic subtest: vecs0
[435.177694] Dynamic subtest vecs0: SUCCESS (0.074s)
[435.196416] Starting dynamic subtest: ccs0
[435.244028] Dynamic subtest ccs0: SUCCESS (0.066s)
[435.262332] Starting dynamic subtest: all-engines
[435.485168] Dynamic subtest all-engines: SUCCESS (0.238s)
[435.488304] Subtest independent: SUCCESS (0.867s)
[436.536642] [121/142] (605s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[436.712161] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[436.716897] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[436.804221] [122/142] (605s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[436.966156] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[436.970858] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[437.062041] [123/142] (604s left) gem_ctx_param (invalid-get-no-zeromap)
[437.170264] Starting subtest: invalid-get-no-zeromap
[437.173066] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[437.249087] [124/142] (604s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[437.418979] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[441.991239] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.577s)
[442.133807] [125/142] (599s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[442.788470] Starting subtest: pipe-D-missing-ccs-buffer-y_tiled_ccs
[442.795045] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[443.491793] [126/142] (598s left) gem_exec_params (no-bsd)
[443.602569] Starting subtest: no-bsd
[443.605255] Subtest no-bsd: SUCCESS (0.002s)
[445.474582] [127/142] (596s left) i915_pm_rpm (system-suspend-modeset)
[447.671222] Starting subtest: system-suspend-modeset
[475.292869] Subtest system-suspend-modeset: SUCCESS (13.000s)
[476.174339] [128/142] (580s left) gem_spin_batch (resubmit)
[476.298698] Starting subtest: resubmit
[476.301304] Starting dynamic subtest: rcs0
[476.304169] Dynamic subtest rcs0: SUCCESS (0.007s)
[476.326039] Starting dynamic subtest: bcs0
[476.328345] Dynamic subtest bcs0: SUCCESS (0.003s)
[476.347116] Starting dynamic subtest: vcs0
[476.349398] Dynamic subtest vcs0: SUCCESS (0.003s)
[476.367828] Starting dynamic subtest: vcs1
[476.370086] Dynamic subtest vcs1: SUCCESS (0.002s)
[476.389166] Starting dynamic subtest: vecs0
[476.391792] Dynamic subtest vecs0: SUCCESS (0.003s)
[476.409836] Starting dynamic subtest: ccs0
[476.412551] Dynamic subtest ccs0: SUCCESS (0.003s)
[476.414939] Subtest resubmit: SUCCESS (0.030s)
[478.140197] [129/142] (578s left) gem_eio (wait-immediate)
[478.833985] Starting subtest: wait-immediate
[479.363191] Subtest wait-immediate: SUCCESS (0.530s)
[480.124134] [130/142] (576s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[480.239520] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[480.288221] [131/142] (576s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[480.481679] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[481.649533] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.164s)
[482.549198] [132/142] (574s left) gem_exec_endless (dispatch)
[482.690803] Starting subtest: dispatch
[482.694283] Starting dynamic subtest: rcs0
[482.703813] Dynamic subtest rcs0: SUCCESS (0.007s)
[482.728588] Starting dynamic subtest: bcs0
[482.733931] Dynamic subtest bcs0: SUCCESS (0.007s)
[482.758075] Starting dynamic subtest: vcs0
[482.762940] Dynamic subtest vcs0: SUCCESS (0.006s)
[482.783510] Starting dynamic subtest: vcs1
[482.788362] Dynamic subtest vcs1: SUCCESS (0.008s)
[482.808547] Starting dynamic subtest: vecs0
[482.814720] Dynamic subtest vecs0: SUCCESS (0.006s)
[482.834851] Starting dynamic subtest: ccs0
[482.840374] Dynamic subtest ccs0: SUCCESS (0.007s)
[482.842876] Subtest dispatch: SUCCESS (0.052s)
[484.098866] [133/142] (572s left) kms_psr (cursor_plane_move)
[484.250818] Starting subtest: cursor_plane_move
[486.089630] Subtest cursor_plane_move: SUCCESS (1.839s)
[486.986876] [134/142] (569s left) gem_exec_whisper (basic-sync-all)
[487.114026] Starting subtest: basic-sync-all
[501.712806] Subtest basic-sync-all: SUCCESS (14.597s)
[501.910836] [135/142] (555s left) kms_rmfb (close-fd)
[502.067167] Starting subtest: close-fd
[502.069848] Starting dynamic subtest: pipe-A-eDP-1
[502.246802] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.177s)
[502.268251] Starting dynamic subtest: pipe-B-eDP-1
[503.621622] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.370s)
[503.642358] Starting dynamic subtest: pipe-C-eDP-1
[505.021803] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.397s)
[505.043066] Starting dynamic subtest: pipe-D-eDP-1
[506.408730] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.384s)
[506.411523] Subtest close-fd: SUCCESS (4.343s)
[507.745369] [136/142] (549s left) kms_flip (nonexisting-fb)
[507.866718] Starting subtest: nonexisting-fb
[507.873726] Starting dynamic subtest: A-eDP1
[509.302358] Dynamic subtest A-eDP1: SUCCESS (1.428s)
[509.324350] Starting dynamic subtest: B-eDP1
[510.645308] Dynamic subtest B-eDP1: SUCCESS (1.342s)
[510.666757] Starting dynamic subtest: C-eDP1
[511.988592] Dynamic subtest C-eDP1: SUCCESS (1.341s)
[512.009368] Starting dynamic subtest: D-eDP1
[513.333753] Dynamic subtest D-eDP1: SUCCESS (1.342s)
[513.342695] Subtest nonexisting-fb: SUCCESS (5.481s)
[514.663839] [137/142] (542s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[514.825445] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[514.829776] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[514.932749] [138/142] (542s left) gem_pxp (regular-baseline-src-copy-readible)
[517.585938] Subtest regular-baseline-src-copy-readible: SKIP (0.000s)
[517.660214] [139/142] (539s left) i915_pm_rc6_residency (rc6-fence)
[517.770303] Starting subtest: rc6-fence
[544.085999] Subtest rc6-fence: SUCCESS (26.318s)
[545.415342] [140/142] (511s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[545.581116] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[545.586252] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[545.677968] [141/142] (511s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[545.844985] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[551.425842] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (5.583s)
[551.582923] [142/142] (505s left) kms_psr2_sf (cursor-plane-move-continuous-sf)
[557.195856] Starting subtest: cursor-plane-move-continuous-sf
[557.198932] Starting dynamic subtest: pipe-A-eDP-1
[568.366031] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.169s)
[568.392057] Starting dynamic subtest: pipe-B-eDP-1
[579.556934] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.184s)
[579.559783] Subtest cursor-plane-move-continuous-sf: SUCCESS (22.362s)
[580.452150] Closing watchdogs
Done.