[38.059442] Initializing watchdogs
[38.059565]   /dev/watchdog0
[38.071734] [001/133] (960s left) kms_cursor_crc (cursor-random-512x512)
[38.363479] Starting subtest: cursor-random-512x512
[38.368414] Subtest cursor-random-512x512: SKIP (0.001s)
[38.503242] [002/133] (960s left) kms_pm_rpm (modeset-non-lpsp-stress)
[40.598135] Starting subtest: modeset-non-lpsp-stress
[44.033218] Subtest modeset-non-lpsp-stress: SKIP (3.433s)
[44.825886] [003/133] (953s left) gem_exec_schedule (preempt-other)
[44.907270] Starting subtest: preempt-other
[44.909611] Starting dynamic subtest: rcs0
[44.972557] Dynamic subtest rcs0: SUCCESS (0.072s)
[44.985034] Starting dynamic subtest: bcs0
[45.037803] Dynamic subtest bcs0: SUCCESS (0.065s)
[45.050443] Starting dynamic subtest: vcs0
[45.096509] Dynamic subtest vcs0: SUCCESS (0.059s)
[45.108329] Starting dynamic subtest: vcs1
[45.155750] Dynamic subtest vcs1: SUCCESS (0.059s)
[45.168040] Starting dynamic subtest: vecs0
[45.214917] Dynamic subtest vecs0: SUCCESS (0.059s)
[45.227373] Starting dynamic subtest: ccs0
[45.275204] Dynamic subtest ccs0: SUCCESS (0.060s)
[45.277765] Subtest preempt-other: SUCCESS (0.374s)
[46.546946] [004/133] (952s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[46.630685] Starting subtest: multi-wait-all-for-submit-submitted
[46.739062] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.107s)
[46.802959] [005/133] (951s left) kms_dsc (dsc-with-output-formats-with-bpc)
[46.928562] Starting subtest: dsc-with-output-formats-with-bpc
[46.933312] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[46.989446] [006/133] (951s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[47.128581] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[47.133193] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[47.196465] [007/133] (951s left) gem_exec_schedule (u-semaphore-user)
[47.281113] Starting subtest: u-semaphore-user
[47.304322] Subtest u-semaphore-user: SUCCESS (0.028s)
[48.461318] [008/133] (950s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[48.852527] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[49.741978] [009/133] (948s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[49.795711] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[49.898168] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[49.961982] [010/133] (948s left) v3d/v3d_get_param (get-bad-param)
[50.055174] Subtest get-bad-param: SKIP (0.000s)
[50.101103] [011/133] (948s left) kms_flip (2x-nonexisting-fb)
[50.214867] Starting subtest: 2x-nonexisting-fb
[50.230495] Subtest 2x-nonexisting-fb: SKIP (0.015s)
[50.296369] [012/133] (948s left) kms_cursor_crc (cursor-sliding-32x10)
[50.468804] Starting subtest: cursor-sliding-32x10
[50.497332] Subtest cursor-sliding-32x10: SKIP (0.027s)
[50.733986] [013/133] (948s left) kms_invalid_mode (bad-hsync-start)
[50.846609] Starting subtest: bad-hsync-start
[50.849037] Starting dynamic subtest: pipe-A-eDP-1
[51.131841] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[51.143183] Starting dynamic subtest: pipe-B-eDP-1
[51.145409] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[51.152048] Starting dynamic subtest: pipe-C-eDP-1
[51.154886] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[51.166619] Starting dynamic subtest: pipe-D-eDP-1
[51.169738] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[51.172693] Subtest bad-hsync-start: SUCCESS (0.294s)
[52.011379] [014/133] (946s left) gem_exec_reloc (basic-wc-read-noreloc)
[52.081561] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[52.127296] [015/133] (946s left) kms_vrr (flip-basic)
[52.251809] Starting subtest: flip-basic
[52.256504] Subtest flip-basic: SKIP (0.000s)
[52.305860] [016/133] (946s left) gem_exec_balancer (bonded-chain)
[52.389263] Starting subtest: bonded-chain
[59.129632] Subtest bonded-chain: SUCCESS (6.738s)
[60.437530] [017/133] (938s left) v3d/v3d_submit_cl (bad-multisync-out-sync)
[60.512450] Subtest bad-multisync-out-sync: SKIP (0.000s)
[60.555433] [018/133] (938s left) gem_userptr_blits (dmabuf-unsync)
[60.632373] Subtest dmabuf-unsync: SKIP (0.000s)
[60.737470] [019/133] (938s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[60.862810] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[60.867407] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[60.934114] [020/133] (938s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[61.063203] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[72.234770] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.170s)
[73.104838] [021/133] (925s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[73.175914] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[73.178559] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[73.253102] [022/133] (925s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[73.381684] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt
[73.386032] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[73.452473] [023/133] (925s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[73.579103] Starting subtest: disable-crc-after-crtc
[73.581509] Starting dynamic subtest: pipe-A-eDP-1
[75.332645] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.746s)
[75.342179] Starting dynamic subtest: pipe-B-eDP-1
[77.825322] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.495s)
[77.839033] Starting dynamic subtest: pipe-C-eDP-1
[80.296605] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.469s)
[80.306772] Starting dynamic subtest: pipe-D-eDP-1
[82.728105] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.428s)
[82.730930] Subtest disable-crc-after-crtc: SUCCESS (9.147s)
[83.630322] [024/133] (915s left) kms_psr (pr-cursor-mmap-cpu)
[83.764394] Starting subtest: pr-cursor-mmap-cpu
[83.769352] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[83.827312] [025/133] (915s left) syncobj_wait (invalid-signal-zero-handles)
[83.898584] Starting subtest: invalid-signal-zero-handles
[83.901177] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[83.984438] [026/133] (915s left) gem_tiled_blits (basic)
[84.052817] Subtest basic: SKIP (0.000s)
[84.106474] [027/133] (915s left) sw_sync (sync_multi_consumer)
[84.151606] Starting subtest: sync_multi_consumer
[84.626680] Subtest sync_multi_consumer: SUCCESS (0.476s)
[84.670241] [028/133] (914s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[84.798746] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[84.803460] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[84.868852] [029/133] (914s left) perf_pmu (render-node-busy-idle)
[84.950185] Starting subtest: render-node-busy-idle
[84.952787] Starting dynamic subtest: rcs0
[85.640815] Dynamic subtest rcs0: SUCCESS (0.690s)
[85.653727] Starting dynamic subtest: bcs0
[86.306356] Dynamic subtest bcs0: SUCCESS (0.664s)
[86.320780] Starting dynamic subtest: vcs0
[87.478469] Dynamic subtest vcs0: SUCCESS (1.170s)
[87.491996] Starting dynamic subtest: vcs1
[89.462734] Dynamic subtest vcs1: SUCCESS (1.983s)
[89.476423] Starting dynamic subtest: vecs0
[91.446106] Dynamic subtest vecs0: SUCCESS (1.983s)
[91.459600] Starting dynamic subtest: ccs0
[92.110258] Dynamic subtest ccs0: SUCCESS (0.664s)
[92.113765] Subtest render-node-busy-idle: SUCCESS (7.160s)
[92.177927] [030/133] (907s left) kms_rotation_crc (multiplane-rotation)
[92.299953] Starting subtest: multiplane-rotation
[99.881759] Subtest multiplane-rotation: SUCCESS (7.582s)
[99.990408] [031/133] (899s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[100.132952] Starting subtest: x-tiled-8bpp-rotate-90
[100.152225] Subtest x-tiled-8bpp-rotate-90: SKIP (0.018s)
[100.257055] [032/133] (899s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[100.372555] Starting subtest: psr-rgb101010-draw-mmap-gtt
[100.376917] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[100.449544] [033/133] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[100.565593] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[105.200977] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.640s)
[105.327087] [034/133] (894s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[105.469163] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[105.473495] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[105.574944] [035/133] (893s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[105.648663] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[105.651343] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[105.733840] [036/133] (893s left) kms_flip (flip-vs-absolute-wf_vblank)
[105.855066] Starting subtest: flip-vs-absolute-wf_vblank
[105.857493] Starting dynamic subtest: A-eDP1
[114.842587] Dynamic subtest A-eDP1: SUCCESS (8.986s)
[114.855813] Starting dynamic subtest: B-eDP1
[123.867516] Dynamic subtest B-eDP1: SUCCESS (9.023s)
[123.880853] Starting dynamic subtest: C-eDP1
[132.881391] Dynamic subtest C-eDP1: SUCCESS (9.012s)
[132.895814] Starting dynamic subtest: D-eDP1
[141.884404] Dynamic subtest D-eDP1: SUCCESS (8.955s)
[141.886479] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.996s)
[143.166334] [037/133] (856s left) gem_ctx_isolation (dirty-switch)
[143.230228] Starting subtest: dirty-switch
[143.232717] Starting dynamic subtest: rcs0
[143.364339] Dynamic subtest rcs0: SUCCESS (0.134s)
[143.374747] Starting dynamic subtest: bcs0
[143.564170] Dynamic subtest bcs0: SUCCESS (0.199s)
[143.577704] Starting dynamic subtest: vcs0
[143.780862] Dynamic subtest vcs0: SUCCESS (0.216s)
[143.794770] Starting dynamic subtest: vcs1
[145.657067] Dynamic subtest vcs1: SUCCESS (1.875s)
[145.670428] Starting dynamic subtest: vecs0
[147.667944] Dynamic subtest vecs0: SUCCESS (2.011s)
[147.682220] Starting dynamic subtest: ccs0
[149.652658] Dynamic subtest ccs0: SUCCESS (1.984s)
[149.655031] Subtest dirty-switch: SUCCESS (6.423s)
[149.906618] [038/133] (849s left) gem_eio (suspend)
[150.159441] Starting subtest: suspend
[162.161298] Subtest suspend: SUCCESS (12.001s)
[162.293672] [039/133] (837s left) kms_writeback (writeback-fb-id)
[162.408820] Subtest writeback-fb-id: SKIP (0.000s)
[162.433682] [040/133] (837s left) syncobj_timeline (wait-all-delayed-signal)
[162.497611] Starting subtest: wait-all-delayed-signal
[162.602314] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[162.674959] [041/133] (836s left) gem_softpin (noreloc-s3)
[162.753923] Starting subtest: noreloc-S3
[183.003913] Subtest noreloc-S3: SUCCESS (5.393s)
[183.105124] [042/133] (831s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[183.207490] Starting subtest: yf-tiled-64bpp-rotate-0
[183.211751] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[183.289712] [043/133] (831s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[183.360756] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[183.466001] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.106s)
[183.535656] [044/133] (830s left) device_reset (unbind-reset-rebind)
[183.638891] Starting subtest: unbind-reset-rebind
[185.643611] Subtest unbind-reset-rebind: SUCCESS (2.005s)
[185.989430] [045/133] (828s left) gem_bad_reloc (negative-reloc-bltcopy)
[186.088448] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[186.136862] [046/133] (828s left) vc4/vc4_label_bo (set-bad-handle)
[186.207603] Subtest set-bad-handle: SKIP (0.000s)
[186.254027] [047/133] (828s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[186.370235] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[186.374856] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[186.463086] [048/133] (828s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[186.521747] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[186.524187] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[186.604883] [049/133] (827s left) gem_eio (wait-immediate)
[186.749284] Starting subtest: wait-immediate
[186.945450] Subtest wait-immediate: SUCCESS (0.198s)
[187.061789] [050/133] (827s left) kms_prop_blob (blob-multiple)
[187.107600] Starting subtest: blob-multiple
[187.109677] Subtest blob-multiple: SUCCESS (0.003s)
[187.216767] [051/133] (827s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[189.481895] Starting subtest: gem-execbuf-stress-pc8
[189.523573] Subtest gem-execbuf-stress-pc8: SKIP (0.040s)
[190.064040] [052/133] (824s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[190.185700] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[190.190980] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[190.267474] [053/133] (824s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[190.388180] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[190.393243] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[190.477211] [054/133] (824s left) gem_create (create-clear)
[190.543485] Starting subtest: create-clear
[190.546555] Starting dynamic subtest: smem0
[223.625195] Dynamic subtest smem0: SUCCESS (33.080s)
[223.627209] Subtest create-clear: SUCCESS (33.081s)
[223.804422] [055/133] (790s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[223.935249] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[223.940066] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[224.009264] [056/133] (790s left) kms_busy (basic)
[224.145619] Starting subtest: basic
[224.148093] Starting dynamic subtest: flip
[224.811938] Dynamic subtest flip: SUCCESS (0.663s)
[224.828305] Starting dynamic subtest: modeset
[228.689281] Dynamic subtest modeset: SUCCESS (3.876s)
[228.692020] Subtest basic: SUCCESS (4.542s)
[229.614501] [057/133] (785s left) kms_rotation_crc (sprite-rotation-90)
[229.725363] Starting subtest: sprite-rotation-90
[229.730389] Subtest sprite-rotation-90: SKIP (0.001s)
[229.781299] [058/133] (784s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[229.893247] Starting subtest: random-ccs-data-yf-tiled-ccs
[229.896455] Starting dynamic subtest: pipe-A-eDP-1
[229.904310] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[229.913370] Starting dynamic subtest: pipe-B-eDP-1
[229.920784] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[229.927435] Starting dynamic subtest: pipe-C-eDP-1
[229.934849] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[229.951597] Starting dynamic subtest: pipe-D-eDP-1
[229.959931] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[229.964786] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.004s)
[229.989519] [059/133] (784s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[230.050632] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[230.052667] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[230.143832] [060/133] (784s left) gem_exec_fence (invalid-fence-array)
[230.649717] Starting subtest: invalid-fence-array
[230.657585] Subtest invalid-fence-array: SUCCESS (0.005s)
[232.381307] [061/133] (782s left) gem_exec_schedule (in-order)
[232.465870] Starting subtest: in-order
[232.467919] Starting dynamic subtest: rcs0
[232.558793] Dynamic subtest rcs0: SUCCESS (0.095s)
[232.576953] Starting dynamic subtest: vcs0
[232.649863] Dynamic subtest vcs0: SUCCESS (0.089s)
[232.667259] Starting dynamic subtest: vcs1
[232.727477] Dynamic subtest vcs1: SUCCESS (0.077s)
[232.745190] Starting dynamic subtest: vecs0
[232.813780] Dynamic subtest vecs0: SUCCESS (0.086s)
[232.831226] Starting dynamic subtest: ccs0
[232.915639] Dynamic subtest ccs0: SUCCESS (0.100s)
[232.918836] Subtest in-order: SUCCESS (0.453s)
[234.403621] [062/133] (780s left) kms_psr (psr2-primary-page-flip)
[234.524086] Starting subtest: psr2-primary-page-flip
[234.526718] Starting dynamic subtest: eDP-1
[236.308660] Dynamic subtest eDP-1: SUCCESS (1.783s)
[236.311168] Subtest psr2-primary-page-flip: SUCCESS (1.785s)
[237.236782] [063/133] (777s left) api_intel_bb (misplaced-blitter)
[237.314225] Starting subtest: misplaced-blitter
[237.324926] Subtest misplaced-blitter: SUCCESS (0.012s)
[237.448652] [064/133] (777s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[237.511942] Starting subtest: framebuffer-vs-set-tiling
[237.516768] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[237.633374] [065/133] (777s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[237.740410] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[237.742782] Starting dynamic subtest: pipe-A-eDP-1
[238.243849] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.504s)
[238.256435] Starting dynamic subtest: pipe-B-eDP-1
[239.844894] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.599s)
[239.866624] Starting dynamic subtest: pipe-C-eDP-1
[241.538690] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.689s)
[241.561111] Starting dynamic subtest: pipe-D-eDP-1
[243.268245] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.727s)
[243.272482] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (5.531s)
[244.553485] [066/133] (770s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[244.674316] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[250.649486] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (5.980s)
[250.743179] [067/133] (764s left) gem_mmap_offset (close-race)
[250.807718] Starting subtest: close-race
[270.956330] Subtest close-race: SUCCESS (20.099s)
[271.006587] [068/133] (744s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[271.113174] Starting subtest: bad-rotation-90-y-tiled-ccs
[271.115916] Starting dynamic subtest: pipe-A-eDP-1
[271.121097] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[271.132301] Starting dynamic subtest: pipe-B-eDP-1
[271.136726] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[271.144257] Starting dynamic subtest: pipe-C-eDP-1
[271.149039] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[271.156053] Starting dynamic subtest: pipe-D-eDP-1
[271.160555] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[271.165018] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.003s)
[271.186154] [069/133] (743s left) i915_pm_rps (reset)
[271.264478] Starting subtest: reset
[271.323887] Subtest reset: FAIL (0.027s)
[271.365165] [070/133] (743s left) gem_ctx_persistence (legacy-engines-cleanup)
[271.460096] Starting subtest: legacy-engines-cleanup
[271.462573] Starting dynamic subtest: render
[271.546209] Dynamic subtest render: SUCCESS (0.085s)
[271.565314] Starting dynamic subtest: bsd1
[271.571792] Dynamic subtest bsd1: SUCCESS (0.024s)
[271.585542] Starting dynamic subtest: bsd2
[271.658061] Dynamic subtest bsd2: SUCCESS (0.084s)
[271.675366] Starting dynamic subtest: blt
[271.714322] Dynamic subtest blt: SUCCESS (0.054s)
[271.731165] Starting dynamic subtest: vebox
[271.771934] Dynamic subtest vebox: SUCCESS (0.056s)
[271.774666] Subtest legacy-engines-cleanup: SUCCESS (0.312s)
[271.846410] [071/133] (743s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[271.969937] Starting subtest: fbcpsr-tiling-y
[271.973793] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[272.058124] [072/133] (743s left) prime_self_import (basic-with_one_bo)
[272.102638] Starting subtest: basic-with_one_bo
[272.132067] Subtest basic-with_one_bo: SUCCESS (0.027s)
[272.238683] [073/133] (742s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[272.365096] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[272.369122] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[272.448793] [074/133] (742s left) gem_pxp (verify-pxp-stale-buf-execution)
[275.055445] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[275.115410] [075/133] (740s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[275.235740] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[275.238206] Starting dynamic subtest: pipe-A-eDP-1
[275.319425] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.086s)
[275.333460] Starting dynamic subtest: pipe-B-eDP-1
[276.571638] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.251s)
[276.582570] Starting dynamic subtest: pipe-C-eDP-1
[277.772111] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.198s)
[277.781900] Starting dynamic subtest: pipe-D-eDP-1
[278.998925] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.226s)
[279.001553] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (3.768s)
[280.249791] [076/133] (735s left) vc4/vc4_tiling (get-bad-flags)
[280.310462] Subtest get-bad-flags: SKIP (0.000s)
[280.357790] [077/133] (734s left) gem_softpin (allocator-fork)
[280.431190] Starting subtest: allocator-fork
[282.709264] Subtest allocator-fork: SUCCESS (2.225s)
[282.789481] [078/133] (732s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[282.869039] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[282.924608] [079/133] (732s left) gem_media_vme
[283.063540] [080/133] (732s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[283.216783] Starting subtest: short-busy-flip-before-cursor-toggle
[283.221470] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[283.358221] [081/133] (732s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[283.488632] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[283.491055] Starting dynamic subtest: pipe-A-eDP-1
[283.495807] Dynamic subtest pipe-A-eDP-1: SKIP (0.004s)
[283.511741] Starting dynamic subtest: pipe-B-eDP-1
[283.516590] Dynamic subtest pipe-B-eDP-1: SKIP (0.013s)
[283.526604] Starting dynamic subtest: pipe-C-eDP-1
[283.531284] Dynamic subtest pipe-C-eDP-1: SKIP (0.013s)
[283.539339] Starting dynamic subtest: pipe-D-eDP-1
[283.543104] Dynamic subtest pipe-D-eDP-1: SKIP (0.013s)
[283.546945] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.049s)
[283.632010] [082/133] (731s left) kms_plane_lowres (tiling-none)
[283.763920] Starting subtest: tiling-none
[283.766045] Starting dynamic subtest: pipe-A-eDP-1
[283.770200] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[284.070095] Starting dynamic subtest: pipe-B-eDP-1
[284.076013] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[284.087183] Starting dynamic subtest: pipe-C-eDP-1
[284.091679] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[284.100190] Starting dynamic subtest: pipe-D-eDP-1
[284.104434] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[284.108671] Subtest tiling-none: SKIP (0.297s)
[284.981936] [083/133] (730s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[285.123330] Starting subtest: flip-vs-cursor-atomic
[291.954369] Subtest flip-vs-cursor-atomic: SUCCESS (6.831s)
[292.854496] [084/133] (722s left) kms_flip (2x-plain-flip-ts-check)
[292.977961] Starting subtest: 2x-plain-flip-ts-check
[292.993854] Subtest 2x-plain-flip-ts-check: SKIP (0.015s)
[293.057738] [085/133] (722s left) gem_lmem_swapping (heavy-verify-random)
[293.108945] Subtest heavy-verify-random: SKIP (0.000s)
[293.132481] [086/133] (722s left) gem_exec_flush (basic-uc-pro-default)
[293.193264] Starting subtest: basic-uc-pro-default
[298.676377] Subtest basic-uc-pro-default: SUCCESS (5.445s)
[298.817047] [087/133] (716s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[298.951745] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[298.956052] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[299.036348] [088/133] (716s left) kms_big_fb (4-tiled-addfb)
[299.154808] Starting subtest: 4-tiled-addfb
[299.157212] Subtest 4-tiled-addfb: SUCCESS (0.001s)
[299.239321] [089/133] (716s left) gem_ctx_persistence (heartbeat-stop)
[299.336188] Starting subtest: heartbeat-stop
[299.341400] Subtest heartbeat-stop: SKIP (0.000s)
[299.434422] [090/133] (716s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[299.532811] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[299.536037] Starting dynamic subtest: pipe-A-eDP-1
[299.540800] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[299.551216] Starting dynamic subtest: pipe-B-eDP-1
[299.555541] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[299.562922] Starting dynamic subtest: pipe-C-eDP-1
[299.567171] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[299.574480] Starting dynamic subtest: pipe-D-eDP-1
[299.578909] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[299.583245] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[299.607307] [091/133] (716s left) sysfs_heartbeat_interval (nopreempt)
[299.687757] Starting subtest: nopreempt
[299.689933] Starting dynamic subtest: vecs0
[309.305527] Dynamic subtest vecs0: SUCCESS (9.613s)
[309.321518] Starting dynamic subtest: ccs0
[313.194933] Dynamic subtest ccs0: SUCCESS (3.891s)
[313.211925] Starting dynamic subtest: vcs0
[322.302975] Dynamic subtest vcs0: SUCCESS (9.107s)
[322.320205] Starting dynamic subtest: bcs0
[326.167898] Dynamic subtest bcs0: SUCCESS (3.863s)
[326.186129] Starting dynamic subtest: rcs0
[329.944411] Dynamic subtest rcs0: SUCCESS (3.775s)
[329.961890] Starting dynamic subtest: vcs1
[339.336243] Dynamic subtest vcs1: SUCCESS (9.387s)
[339.339436] Subtest nopreempt: SUCCESS (39.645s)
[339.412196] [092/133] (676s left) tools_test (sysfs_l3_parity)
[339.481693] Starting subtest: sysfs_l3_parity
[339.572678] Subtest sysfs_l3_parity: SKIP (0.107s)
[339.623555] [093/133] (676s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[339.754745] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[339.759372] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[339.834642] [094/133] (675s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[339.962275] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[344.660534] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.701s)
[344.802752] [095/133] (670s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[344.885874] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[344.945126] [096/133] (670s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[345.074445] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[345.078730] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[345.157459] [097/133] (670s left) gem_mmap_wc (close)
[345.228964] Subtest close: SKIP (0.000s)
[345.272797] [098/133] (670s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[345.386232] Starting subtest: addfb25-yf-tiled-legacy
[345.388655] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[345.470506] [099/133] (670s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[345.595977] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[345.601206] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[345.682044] [100/133] (670s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[345.803225] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[345.805630] Starting dynamic subtest: pipe-A-default-mode
[346.099952] Dynamic subtest pipe-A-default-mode: SKIP (0.292s)
[346.105624] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.293s)
[347.014709] [101/133] (668s left) drm_read (short-buffer-wakeup)
[347.182888] Starting subtest: short-buffer-wakeup
[379.397417] Subtest short-buffer-wakeup: SUCCESS (32.214s)
[379.516584] [102/133] (636s left) v3d/v3d_submit_csd (valid-multisync-submission)
[379.584221] Subtest valid-multisync-submission: SKIP (0.000s)
[379.633445] [103/133] (636s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[379.753748] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[379.758576] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[379.859701] [104/133] (636s left) syncobj_timeline (multi-wait-signaled)
[379.924644] Starting subtest: multi-wait-signaled
[379.927226] Subtest multi-wait-signaled: SUCCESS (0.001s)
[380.002972] [105/133] (636s left) perf_pmu (busy-double-start)
[380.052172] Starting subtest: busy-double-start
[380.054628] Starting dynamic subtest: rcs0
[383.673299] Dynamic subtest rcs0: FAIL (3.568s)
[383.689555] Starting dynamic subtest: bcs0
[387.248722] Dynamic subtest bcs0: FAIL (3.567s)
[387.265777] Starting dynamic subtest: vcs0
[390.872280] Dynamic subtest vcs0: SUCCESS (3.682s)
[390.888468] Starting dynamic subtest: vcs1
[394.836479] Dynamic subtest vcs1: SUCCESS (3.964s)
[394.854351] Starting dynamic subtest: vecs0
[398.852044] Dynamic subtest vecs0: SUCCESS (4.015s)
[398.869873] Starting dynamic subtest: ccs0
[402.481611] Dynamic subtest ccs0: FAIL (3.568s)
[402.484506] Subtest busy-double-start: FAIL (22.373s)
[402.512682] [106/133] (613s left) gem_vm_create (create-ext)
[402.576946] Starting subtest: create-ext
[402.582352] Subtest create-ext: SUCCESS (0.004s)
[402.680360] [107/133] (613s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[402.791823] Starting subtest: 4-tiled-16bpp-rotate-90
[402.818035] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.026s)
[402.926942] [108/133] (613s left) kms_setmode (clone-exclusive-crtc)
[403.007368] Starting subtest: clone-exclusive-crtc
[403.015167] Subtest clone-exclusive-crtc: SKIP (0.000s)
[403.098440] [109/133] (613s left) gen9_exec_parse (bb-start-cmd)
[403.172901] Subtest bb-start-cmd: SKIP (0.000s)
[403.253218] [110/133] (612s left) gem_exec_reloc (basic-gtt-wc-active)
[403.334175] Subtest basic-gtt-wc-active: SKIP (0.000s)
[403.377976] [111/133] (612s left) gem_exec_capture (capture-invisible)
[403.449415] Starting subtest: capture-invisible
[403.451606] Starting dynamic subtest: smem0
[403.455862] Dynamic subtest smem0: SKIP (0.000s)
[403.460202] Subtest capture-invisible: SKIP (0.001s)
[403.538292] [112/133] (612s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[403.642223] Starting subtest: fbc-rgb101010-draw-pwrite
[407.835625] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (4.194s)
[407.953741] [113/133] (608s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[408.085466] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[408.088050] Starting dynamic subtest: pipe-A-default-mode
[409.303073] Dynamic subtest pipe-A-default-mode: SKIP (1.211s)
[409.307407] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.213s)
[409.470589] [114/133] (606s left) kms_psr (fbc-psr2-cursor-render)
[409.593374] Starting subtest: fbc-psr2-cursor-render
[409.596020] Starting dynamic subtest: eDP-1
[409.600601] Dynamic subtest eDP-1: SKIP (0.000s)
[409.605035] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[409.660566] [115/133] (606s left) i915_fb_tiling
[409.787512] [116/133] (606s left) gem_exec_params (invalid-batch-start-offset)
[409.861992] Starting subtest: invalid-batch-start-offset
[409.868273] Subtest invalid-batch-start-offset: SUCCESS (0.005s)
[409.970126] [117/133] (606s left) api_intel_allocator (reopen-fork)
[410.039405] Starting subtest: reopen-fork
[413.505877] Subtest reopen-fork: SUCCESS (3.468s)
[413.578374] [118/133] (602s left) kms_psr (fbc-pr-primary-blt)
[413.686867] Starting subtest: fbc-pr-primary-blt
[413.691936] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[413.743471] [119/133] (602s left) kms_force_connector_basic (force-connector-state)
[413.824701] Starting subtest: force-connector-state
[414.429446] Subtest force-connector-state: SUCCESS (0.605s)
[415.429678] [120/133] (601s left) kms_chamelium_frames (hdmi-aspect-ratio)
[415.843888] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[416.739128] [121/133] (599s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[416.852171] Starting subtest: y-tiled-addfb-size-offset-overflow
[416.856760] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[416.942762] [122/133] (599s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[417.009464] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[417.014536] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[417.182018] [123/133] (599s left) kms_plane_scaling (plane-downscale-factor-0-25-with-rotation)
[417.292764] Starting subtest: plane-downscale-factor-0-25-with-rotation
[417.295257] Starting dynamic subtest: pipe-A-eDP-1
[417.327304] Dynamic subtest pipe-A-eDP-1: SKIP (0.029s)
[417.339631] Starting dynamic subtest: pipe-B-eDP-1
[417.366115] Dynamic subtest pipe-B-eDP-1: SKIP (0.038s)
[417.384410] Starting dynamic subtest: pipe-C-eDP-1
[417.410184] Dynamic subtest pipe-C-eDP-1: SKIP (0.043s)
[417.417760] Starting dynamic subtest: pipe-D-eDP-1
[417.450269] Dynamic subtest pipe-D-eDP-1: SKIP (0.038s)
[417.454098] Subtest plane-downscale-factor-0-25-with-rotation: SKIP (0.153s)
[417.566484] [124/133] (598s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[417.686633] Starting subtest: x-tiled-32bpp-rotate-270
[417.703863] Subtest x-tiled-32bpp-rotate-270: SKIP (0.018s)
[417.822706] [125/133] (598s left) gem_exec_balancer (parallel-balancer)
[418.068423] Starting subtest: parallel-balancer
[418.692542] Subtest parallel-balancer: SUCCESS (0.625s)
[418.783298] [126/133] (597s left) vgem_basic (dmabuf-fence)
[418.849230] Starting subtest: dmabuf-fence
[418.851207] Subtest dmabuf-fence: SUCCESS (0.000s)
[418.897087] [127/133] (597s left) kms_vblank (query-busy)
[419.038995] Starting subtest: query-busy
[419.041627] Starting dynamic subtest: pipe-A-eDP-1
[420.942371] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.904s)
[420.962536] Starting dynamic subtest: pipe-D-eDP-1
[423.636632] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.686s)
[423.638836] Subtest query-busy: SUCCESS (4.599s)
[424.560544] [128/133] (592s left) kms_cursor_crc (cursor-offscreen-512x512)
[424.811559] Starting subtest: cursor-offscreen-512x512
[424.817873] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[424.956766] [129/133] (591s left) gem_pwrite (basic-self)
[425.035546] Subtest basic-self: SKIP (0.000s)
[425.095559] [130/133] (591s left) gem_render_copy (linear)
[425.168599] Starting subtest: linear
[425.171711] Starting dynamic subtest: smem
[425.213189] Dynamic subtest smem: SUCCESS (0.044s)
[425.215937] Subtest linear: SUCCESS (0.045s)
[425.396302] [131/133] (591s left) kms_content_protection (legacy)
[425.566666] Starting subtest: legacy
[429.595657] Subtest legacy: SKIP (4.022s)
[430.842028] [132/133] (585s left) kms_getfb (getfb-addfb-different-handles)
[430.904549] Starting subtest: getfb-addfb-different-handles
[430.906621] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[431.004779] [133/133] (585s left) kms_psr (fbc-psr-cursor-blt)
[431.138590] Starting subtest: fbc-psr-cursor-blt
[431.141356] Starting dynamic subtest: eDP-1
[431.146291] Dynamic subtest eDP-1: SKIP (0.000s)
[431.151197] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[431.192435] Closing watchdogs
Done.