[52.707235] Initializing watchdogs
[52.707709]   /dev/watchdog0
[52.739319] [001/142] (960s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite)
[53.296288] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite
[63.563139] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (10.271s)
[63.863264] [002/142] (949s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[64.357943] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[64.364675] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[64.515506] [003/142] (948s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[65.062454] Starting subtest: y-tiled-16bpp-rotate-270
[65.070614] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[65.183605] [004/142] (948s left) v3d/v3d_submit_cl (bad-multisync-out-sync)
[65.644622] Subtest bad-multisync-out-sync: SKIP (0.000s)
[65.747973] [005/142] (947s left) gem_lmem_swapping (verify-ccs)
[66.145875] Subtest verify-ccs: SKIP (0.000s)
[66.249772] [006/142] (947s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[66.762219] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[66.769063] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[66.927660] [007/142] (946s left) gem_mmap_wc (invalid-flags)
[67.293145] Subtest invalid-flags: SKIP (0.000s)
[67.381924] [008/142] (946s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[67.748727] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[67.752857] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.004s)
[67.876147] [009/142] (945s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[68.380552] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[68.387489] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[68.534342] [010/142] (945s left) kms_flip (2x-wf_vblank-ts-check)
[68.933114] Starting subtest: 2x-wf_vblank-ts-check
[69.092949] Subtest 2x-wf_vblank-ts-check: SKIP (0.160s)
[69.277158] [011/142] (944s left) gem_mmap_wc (write-cpu-read-wc)
[69.656414] Subtest write-cpu-read-wc: SKIP (0.000s)
[69.748921] [012/142] (944s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[70.259689] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[70.266165] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[70.415742] [013/142] (943s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[71.500878] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[71.513776] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.013s)
[72.799535] [014/142] (941s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[73.360394] Starting subtest: cursorA-vs-flipA-atomic-transitions
[85.652053] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (12.291s)
[86.580565] [015/142] (927s left) gem_exec_schedule (semaphore-noskip)
[87.043614] Starting subtest: semaphore-noskip
[87.917669] Subtest semaphore-noskip: SUCCESS (0.877s)
[88.379406] [016/142] (925s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs)
[90.050536] Starting subtest: pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs
[90.060421] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[90.725481] [017/142] (923s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[91.222441] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[91.229429] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[91.378413] [018/142] (923s left) gem_eio (wait-wedge-immediate)
[92.453250] Starting subtest: wait-wedge-immediate
[93.918969] Subtest wait-wedge-immediate: SUCCESS (1.468s)
[95.671714] [019/142] (918s left) gem_pxp (reject-modify-context-protection-off-1)
[98.575435] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[98.734438] [020/142] (915s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[99.256173] Starting subtest: fbc-rgb565-draw-blt
[109.084123] Subtest fbc-rgb565-draw-blt: SUCCESS (9.831s)
[109.385073] [021/142] (905s left) kms_color (invalid-ctm-matrix-sizes)
[109.868821] Starting subtest: invalid-ctm-matrix-sizes
[109.871557] Starting dynamic subtest: pipe-A
[109.897840] Dynamic subtest pipe-A: SUCCESS (0.030s)
[109.931759] Starting dynamic subtest: pipe-B
[109.939082] Dynamic subtest pipe-B: SUCCESS (0.036s)
[109.972258] Starting dynamic subtest: pipe-C
[109.987311] Dynamic subtest pipe-C: SUCCESS (0.049s)
[110.020394] Starting dynamic subtest: pipe-D
[110.041843] Dynamic subtest pipe-D: SUCCESS (0.052s)
[110.044873] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.176s)
[110.180490] [022/142] (904s left) i915_pm_freq_mult (media-freq)
[110.546776] Starting subtest: media-freq
[110.549876] Starting dynamic subtest: gt0
[110.555716] Dynamic subtest gt0: SKIP (0.000s)
[110.590276] Starting dynamic subtest: gt1
[110.596671] Dynamic subtest gt1: SKIP (0.000s)
[110.602844] Subtest media-freq: SKIP (0.007s)
[110.687199] [023/142] (904s left) gem_exec_parallel (fds)
[111.098096] Starting subtest: fds
[111.101349] Starting dynamic subtest: rcs0
[118.043467] Dynamic subtest rcs0: SUCCESS (6.942s)
[118.079683] Starting dynamic subtest: bcs0
[124.625469] Dynamic subtest bcs0: SUCCESS (6.580s)
[124.663599] Starting dynamic subtest: vcs0
[130.494936] Dynamic subtest vcs0: SUCCESS (5.867s)
[130.533177] Starting dynamic subtest: vcs1
[137.177552] Dynamic subtest vcs1: SUCCESS (6.680s)
[137.215898] Starting dynamic subtest: vecs0
[143.474480] Dynamic subtest vecs0: SUCCESS (6.291s)
[143.513637] Starting dynamic subtest: ccs0
[151.115610] Dynamic subtest ccs0: SUCCESS (7.640s)
[151.119407] Subtest fds: SUCCESS (40.017s)
[151.442809] [024/142] (863s left) kms_hdr (static-swap)
[151.935110] Starting subtest: static-swap
[151.942452] Subtest static-swap: SKIP (0.001s)
[152.077874] [025/142] (862s left) gem_userptr_blits (set-cache-level)
[152.479364] Starting subtest: set-cache-level
[152.485175] Subtest set-cache-level: SKIP (0.000s)
[152.757119] [026/142] (862s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[153.282417] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[175.618600] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (22.341s)
[175.884706] [027/142] (839s left) kms_vblank (pipe-b-wait-idle)
[176.379628] Starting subtest: pipe-B-wait-idle
[179.541954] Subtest pipe-B-wait-idle: SUCCESS (3.158s)
[180.482970] [028/142] (834s left) kms_psr (primary_render)
[180.983076] Starting subtest: primary_render
[183.934898] Subtest primary_render: SUCCESS (2.952s)
[184.875802] [029/142] (830s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[185.374271] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[196.649775] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (11.277s)
[196.930167] [030/142] (818s left) gem_exec_capture (userptr)
[197.362731] Starting subtest: userptr
[198.732323] Subtest userptr: SUCCESS (1.374s)
[198.927416] [031/142] (816s left) kms_vblank (pipe-b-query-forked-hang)
[199.428093] Starting subtest: pipe-B-query-forked-hang
[214.877900] Subtest pipe-B-query-forked-hang: SUCCESS (15.450s)
[215.819242] [032/142] (799s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[216.309776] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[216.320372] Starting dynamic subtest: pipe-A-default-mode
[219.943170] Dynamic subtest pipe-A-default-mode: SUCCESS (3.622s)
[219.946813] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (3.636s)
[220.885529] [033/142] (794s left) kms_chamelium_hpd (vga-hpd-fast)
[221.786698] Subtest vga-hpd-fast: SKIP (0.000s)
[222.652603] [034/142] (793s left) kms_psr2_sf (cursor-plane-update-sf)
[229.383423] Starting subtest: cursor-plane-update-sf
[229.387860] Starting dynamic subtest: pipe-A-eDP-1
[230.888397] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.499s)
[230.937991] Starting dynamic subtest: pipe-B-eDP-1
[232.319357] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.424s)
[232.322674] Subtest cursor-plane-update-sf: SUCCESS (2.941s)
[233.300685] [035/142] (782s left) gem_create (create-ext-placement-each)
[233.820152] Starting subtest: create-ext-placement-each
[233.823887] Subtest create-ext-placement-each: SUCCESS (0.000s)
[233.984769] [036/142] (781s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_mc_ccs)
[235.162498] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs
[243.927231] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs: SUCCESS (8.769s)
[245.618938] [037/142] (770s left) perf_pmu (interrupts-sync)
[246.018408] Starting subtest: interrupts-sync
[247.379200] Subtest interrupts-sync: SUCCESS (1.359s)
[247.554940] [038/142] (768s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[248.042561] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc
[248.046393] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.001s)
[249.317063] [039/142] (766s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[250.488828] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[250.495489] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[251.702518] [040/142] (764s left) gem_exec_schedule (implicit-read-write)
[252.138659] Starting subtest: implicit-read-write
[252.141692] Starting dynamic subtest: rcs0
[252.519389] Dynamic subtest rcs0: SUCCESS (0.378s)
[252.568158] Starting dynamic subtest: bcs0
[252.805436] Dynamic subtest bcs0: SUCCESS (0.281s)
[252.849145] Starting dynamic subtest: vcs0
[253.080585] Dynamic subtest vcs0: SUCCESS (0.270s)
[253.121621] Starting dynamic subtest: vcs1
[253.344289] Dynamic subtest vcs1: SUCCESS (0.260s)
[253.388336] Starting dynamic subtest: vecs0
[253.619941] Dynamic subtest vecs0: SUCCESS (0.271s)
[253.657965] Starting dynamic subtest: ccs0
[253.907775] Dynamic subtest ccs0: SUCCESS (0.283s)
[253.911391] Subtest implicit-read-write: SUCCESS (1.770s)
[254.683152] [041/142] (761s left) kms_cursor_legacy (torture-bo)
[255.248778] Starting subtest: torture-bo
[255.252391] Starting dynamic subtest: pipe-A
[261.352863] Dynamic subtest pipe-A: SUCCESS (6.099s)
[261.389757] Starting dynamic subtest: pipe-B
[267.937143] Dynamic subtest pipe-B: SUCCESS (6.583s)
[267.972169] Starting dynamic subtest: pipe-C
[274.295540] Dynamic subtest pipe-C: SUCCESS (6.353s)
[274.330728] Starting dynamic subtest: pipe-D
[280.970109] Dynamic subtest pipe-D: SUCCESS (6.671s)
[281.004922] Starting dynamic subtest: all-pipes
[287.133219] Dynamic subtest all-pipes: SUCCESS (6.159s)
[287.136608] Subtest torture-bo: SUCCESS (31.887s)
[287.418742] [042/142] (728s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted)
[287.820085] Starting subtest: invalid-multi-wait-all-available-unsubmitted
[287.824145] Subtest invalid-multi-wait-all-available-unsubmitted: SUCCESS (0.001s)
[287.969814] [043/142] (728s left) gem_basic (multigpu-create-close)
[288.342652] Starting subtest: multigpu-create-close
[288.350660] Subtest multigpu-create-close: SKIP (0.000s)
[288.487894] [044/142] (728s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[288.906605] Starting subtest: yf-tiled-ccs-to-yf-tiled
[288.914474] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[289.182466] [045/142] (727s left) i915_pm_rpm (legacy-planes)
[291.716151] Starting subtest: legacy-planes
[291.719952] Starting dynamic subtest: plane-40
[316.020950] Dynamic subtest plane-40: SUCCESS (24.303s)
[316.070192] Starting dynamic subtest: plane-49
[341.051801] Dynamic subtest plane-49: SUCCESS (25.025s)
[341.095951] Starting dynamic subtest: plane-58
[365.990814] Dynamic subtest plane-58: SUCCESS (24.936s)
[366.039988] Starting dynamic subtest: plane-67
[390.851528] Dynamic subtest plane-67: SUCCESS (24.856s)
[390.855779] Subtest legacy-planes: SUCCESS (99.139s)
[391.807162] [046/142] (624s left) syncobj_timeline (wait-all-for-submit-complex)
[392.180356] Starting subtest: wait-all-for-submit-complex
[392.399061] Subtest wait-all-for-submit-complex: SUCCESS (0.218s)
[392.539798] [047/142] (624s left) gem_wait (busy)
[392.995259] Starting subtest: busy
[392.999503] Starting dynamic subtest: all-engines
[394.554667] Dynamic subtest all-engines: SUCCESS (1.556s)
[394.603656] Starting dynamic subtest: rcs0
[395.112599] Dynamic subtest rcs0: SUCCESS (0.553s)
[395.159913] Starting dynamic subtest: bcs0
[395.666529] Dynamic subtest bcs0: SUCCESS (0.550s)
[395.713486] Starting dynamic subtest: vcs0
[397.498567] Dynamic subtest vcs0: SUCCESS (1.826s)
[397.538756] Starting dynamic subtest: vcs1
[399.545691] Dynamic subtest vcs1: SUCCESS (2.044s)
[399.585940] Starting dynamic subtest: vecs0
[401.532615] Dynamic subtest vecs0: SUCCESS (1.979s)
[401.571423] Starting dynamic subtest: ccs0
[402.081330] Dynamic subtest ccs0: SUCCESS (0.548s)
[402.084898] Subtest busy: SUCCESS (9.090s)
[402.466705] [048/142] (614s left) gem_exec_flush (basic-batch-kernel-default-wb)
[402.890137] Starting subtest: basic-batch-kernel-default-wb
[411.666100] Subtest basic-batch-kernel-default-wb: SUCCESS (8.769s)
[411.930321] [049/142] (605s left) gem_create (create-valid-nonaligned)
[412.317432] Starting subtest: create-valid-nonaligned
[412.321335] Subtest create-valid-nonaligned: SUCCESS (0.001s)
[412.661132] [050/142] (604s left) i915_query (query-regions-unallocated)
[413.036809] Starting subtest: query-regions-unallocated
[413.040589] Subtest query-regions-unallocated: SUCCESS (0.000s)
[413.209826] [051/142] (603s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[413.973538] Starting subtest: cursor-rapid-movement-64x64
[414.011829] Starting dynamic subtest: pipe-A-eDP-1
[415.288546] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.281s)
[415.468162] Starting dynamic subtest: pipe-D-eDP-1
[418.080462] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.631s)
[418.084132] Subtest cursor-rapid-movement-64x64: SUCCESS (4.105s)
[419.258922] [052/142] (597s left) gem_exec_reloc (basic-cpu-gtt)
[419.666751] Subtest basic-cpu-gtt: SKIP (0.000s)
[419.815373] [053/142] (597s left) gem_exec_reloc (basic-wc-cpu)
[420.218529] Subtest basic-wc-cpu: SKIP (0.000s)
[420.373516] [054/142] (597s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[420.751916] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[420.755608] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[420.899794] [055/142] (596s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[421.409543] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[421.416058] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[421.562102] [056/142] (595s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[422.075355] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[422.082619] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[422.252467] [057/142] (595s left) kms_big_fb (linear-8bpp-rotate-0)
[422.791672] Starting subtest: linear-8bpp-rotate-0
[426.889638] Subtest linear-8bpp-rotate-0: SUCCESS (4.095s)
[427.202079] [058/142] (590s left) kms_rotation_crc (sprite-rotation-270)
[427.691778] Starting subtest: sprite-rotation-270
[427.698626] Subtest sprite-rotation-270: SKIP (0.007s)
[427.847936] [059/142] (589s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[428.411935] Starting subtest: basic-busy-flip-before-cursor-atomic
[428.418955] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[428.725519] [060/142] (589s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[429.125134] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[429.130997] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[429.400221] [061/142] (588s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[429.792839] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[429.897644] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.109s)
[430.041525] [062/142] (588s left) gem_exec_big (single)
[430.440999] Starting subtest: single
[439.165635] Subtest single: SUCCESS (8.726s)
[440.301465] [063/142] (577s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[440.960206] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[451.156483] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (10.196s)
[451.425804] [064/142] (566s left) perf (non-zero-reason)
[452.371922] Starting subtest: non-zero-reason
[452.375938] Starting dynamic subtest: 0-rcs0
[465.867732] Dynamic subtest 0-rcs0: SUCCESS (13.496s)
[465.886336] Starting dynamic subtest: 1-vcs1
[472.076263] Dynamic subtest 1-vcs1: SUCCESS (6.205s)
[472.078842] Subtest non-zero-reason: SUCCESS (19.705s)
[472.110487] [065/142] (546s left) kms_rotation_crc (sprite-rotation-180)
[472.594068] Starting subtest: sprite-rotation-180
[484.016679] Subtest sprite-rotation-180: SUCCESS (11.434s)
[485.629507] [066/142] (532s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[486.204940] Starting subtest: cursorB-vs-flipA-toggle
[486.221455] Subtest cursorB-vs-flipA-toggle: SKIP (0.016s)
[486.552189] [067/142] (531s left) api_intel_allocator (standalone)
[486.928105] Starting subtest: standalone
[487.008643] Subtest standalone: SUCCESS (0.084s)
[487.167523] [068/142] (531s left) kms_psr (psr2_sprite_mmap_cpu)
[487.660599] Starting subtest: psr2_sprite_mmap_cpu
[490.172129] Subtest psr2_sprite_mmap_cpu: SUCCESS (2.507s)
[491.110067] [069/142] (527s left) vgem_basic (bad-fence)
[491.488118] Starting subtest: bad-fence
[491.491847] Subtest bad-fence: SUCCESS (0.000s)
[491.625339] [070/142] (527s left) gem_huc_copy (huc-copy)
[492.023944] Starting subtest: huc-copy
[492.037335] Subtest huc-copy: SUCCESS (0.014s)
[493.644610] [071/142] (525s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[494.006769] Starting subtest: invalid-smem-bo-on-discrete
[494.015520] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[494.299737] [072/142] (524s left) syncobj_timeline (invalid-query-bad-pad)
[494.677631] Starting subtest: invalid-query-bad-pad
[494.681379] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[494.827140] [073/142] (524s left) kms_chamelium_color (gamma)
[495.316552] Subtest gamma: SKIP (0.000s)
[495.435195] [074/142] (523s left) kms_plane (plane-position-hole)
[495.918066] Starting subtest: plane-position-hole
[495.927246] Starting dynamic subtest: pipe-A-planes
[503.979781] Dynamic subtest pipe-A-planes: SUCCESS (8.057s)
[504.044773] Starting dynamic subtest: pipe-B-planes
[512.779455] Dynamic subtest pipe-B-planes: SUCCESS (8.784s)
[512.783619] Subtest plane-position-hole: SUCCESS (16.867s)
[513.631522] [075/142] (505s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_rc_ccs)
[514.834585] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs
[516.077171] Subtest pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs: SUCCESS (1.242s)
[517.693677] [076/142] (501s left) i915_hwmon (hwmon-write)
[518.067784] Subtest hwmon-write: SKIP (0.000s)
[518.195859] [077/142] (501s left) kms_chamelium_frames (hdmi-crc-fast)
[519.064090] Subtest hdmi-crc-fast: SKIP (0.000s)
[519.930238] [078/142] (499s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[520.412908] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[520.424476] Starting dynamic subtest: pipe-A-default-mode
[522.748496] Dynamic subtest pipe-A-default-mode: SKIP (2.324s)
[522.756149] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (2.338s)
[523.037528] [079/142] (496s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[523.593373] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[527.107677] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (3.517s)
[527.498102] [080/142] (492s left) syncobj_wait (signal)
[527.874739] Starting subtest: signal
[527.878486] Subtest signal: SUCCESS (0.001s)
[528.027625] [081/142] (491s left) kms_addfb_basic (unused-offsets)
[528.402835] Starting subtest: unused-offsets
[528.406532] Subtest unused-offsets: SUCCESS (0.000s)
[528.689882] [082/142] (491s left) syncobj_wait (wait-all-for-submit-complex)
[529.062559] Starting subtest: wait-all-for-submit-complex
[529.278642] Subtest wait-all-for-submit-complex: SUCCESS (0.217s)
[529.425313] [083/142] (490s left) kms_big_fb (y-tiled-addfb-size-overflow)
[529.962500] Starting subtest: y-tiled-addfb-size-overflow
[529.969406] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[530.149463] [084/142] (489s left) drm_fdinfo (virtual-busy-idle-all)
[530.556715] Starting subtest: virtual-busy-idle-all
[530.565519] Subtest virtual-busy-idle-all: SKIP (0.001s)
[530.713524] [085/142] (489s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_ccs)
[531.810276] Starting subtest: pipe-B-bad-pixel-format-y_tiled_ccs
[531.817183] Subtest pipe-B-bad-pixel-format-y_tiled_ccs: SKIP (0.000s)
[533.092284] [086/142] (487s left) kms_vblank (pipe-d-wait-idle-hang)
[533.582363] Starting subtest: pipe-D-wait-idle-hang
[549.832260] Subtest pipe-D-wait-idle-hang: SUCCESS (16.251s)
[550.791140] [087/142] (469s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_ccs)
[552.252453] Starting subtest: pipe-C-random-ccs-data-y_tiled_ccs
[552.262253] Subtest pipe-C-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[553.202575] [088/142] (467s left) gem_ctx_persistence (idempotent)
[553.593977] Starting subtest: idempotent
[553.597889] Subtest idempotent: SUCCESS (0.000s)
[553.770170] [089/142] (466s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[554.293829] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[565.495518] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (11.210s)
[565.773362] [090/142] (454s left) v3d/v3d_submit_csd (multiple-job-submission)
[566.248612] Subtest multiple-job-submission: SKIP (0.000s)
[566.380492] [091/142] (454s left) kms_lease (atomic_implicit_crtc)
[566.836831] Starting subtest: atomic_implicit_crtc
[566.851153] Starting dynamic subtest: pipe-A-eDP-1
[566.855430] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.008s)
[566.905155] Starting dynamic subtest: pipe-B-eDP-1
[566.908283] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.043s)
[566.953181] Starting dynamic subtest: pipe-C-eDP-1
[566.957107] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.047s)
[567.018217] Starting dynamic subtest: pipe-D-eDP-1
[567.021934] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.047s)
[567.025536] Subtest atomic_implicit_crtc: SUCCESS (0.179s)
[567.171161] [092/142] (453s left) kms_universal_plane (cursor-fb-leak-pipe-c)
[567.661659] Starting subtest: cursor-fb-leak-pipe-C
[570.108988] Subtest cursor-fb-leak-pipe-C: SUCCESS (2.447s)
[571.052539] [093/142] (449s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[571.526158] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[571.535157] Starting dynamic subtest: pipe-A-eDP-1
[571.659129] Dynamic subtest pipe-A-eDP-1: SKIP (0.117s)
[571.709231] Starting dynamic subtest: pipe-B-eDP-1
[571.892627] Dynamic subtest pipe-B-eDP-1: SKIP (0.226s)
[571.948245] Starting dynamic subtest: pipe-C-eDP-1
[572.132323] Dynamic subtest pipe-C-eDP-1: SKIP (0.226s)
[572.180880] Starting dynamic subtest: pipe-D-eDP-1
[572.366853] Dynamic subtest pipe-D-eDP-1: SKIP (0.225s)
[572.373950] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.836s)
[572.544389] [094/142] (448s left) kms_vblank (pipe-c-query-forked-busy-hang)
[573.025969] Starting subtest: pipe-C-query-forked-busy-hang
[588.841427] Subtest pipe-C-query-forked-busy-hang: SUCCESS (15.815s)
[589.794229] [095/142] (431s left) gem_mmap_wc (write-wc-read-gtt)
[590.165690] Subtest write-wc-read-gtt: SKIP (0.000s)
[590.292129] [096/142] (430s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[590.799827] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[590.807290] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[590.966111] [097/142] (430s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[592.579281] Starting subtest: pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[593.042782] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.464s)
[594.369139] [098/142] (426s left) gem_ppgtt (blt-vs-render-ctx0)
[594.776406] Starting subtest: blt-vs-render-ctx0
[628.800527] Subtest blt-vs-render-ctx0: SUCCESS (34.021s)
[629.036886] [099/142] (392s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[629.706415] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[643.994485] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (14.286s)
[644.306114] [100/142] (377s left) kms_dsc (dsc-with-formats)
[644.788675] Starting subtest: dsc-with-formats
[644.795891] Subtest dsc-with-formats: SKIP (0.007s)
[644.946941] [101/142] (376s left) gem_exec_schedule (preempt-queue-contexts-chain)
[645.429567] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[645.862327] [102/142] (375s left) prime_self_import (basic-with_one_bo_two_files)
[646.220842] Starting subtest: basic-with_one_bo_two_files
[646.240447] Subtest basic-with_one_bo_two_files: SUCCESS (0.014s)
[646.413449] [103/142] (375s left) gem_exec_fair (basic-pace-solo)
[646.817832] Subtest basic-pace-solo: SKIP (0.000s)
[646.957648] [104/142] (374s left) kms_busy (extended-pageflip-hang-oldfb)
[647.468983] Starting subtest: extended-pageflip-hang-oldfb
[647.481056] Starting dynamic subtest: pipe-A
[648.738840] Dynamic subtest pipe-A: SUCCESS (1.254s)
[648.802728] Starting dynamic subtest: pipe-D
[650.211645] Dynamic subtest pipe-D: SUCCESS (1.452s)
[650.215378] Subtest extended-pageflip-hang-oldfb: SUCCESS (2.746s)
[651.176240] [105/142] (370s left) kms_vblank (pipe-d-query-forked-hang)
[651.660862] Starting subtest: pipe-D-query-forked-hang
[667.835139] Subtest pipe-D-query-forked-hang: SUCCESS (16.175s)
[668.784857] [106/142] (353s left) kms_pipe_crc_basic (hang-read-crc)
[669.266689] Starting subtest: hang-read-crc
[669.275599] Starting dynamic subtest: pipe-A-eDP-1
[673.114769] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.841s)
[673.170971] Starting dynamic subtest: pipe-B-eDP-1
[677.468429] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.343s)
[677.525564] Starting dynamic subtest: pipe-C-eDP-1
[682.615366] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.135s)
[682.671267] Starting dynamic subtest: pipe-D-eDP-1
[688.520317] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.890s)
[688.524659] Subtest hang-read-crc: SUCCESS (19.254s)
[689.408665] [107/142] (332s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[689.806134] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[689.959709] [108/142] (332s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[690.475127] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[690.482306] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[690.645569] [109/142] (331s left) gem_ctx_engines (independent)
[691.063630] Starting subtest: independent
[691.067143] Starting dynamic subtest: rcs0
[691.523771] Dynamic subtest rcs0: SUCCESS (0.456s)
[691.569850] Starting dynamic subtest: bcs0
[691.953893] Dynamic subtest bcs0: SUCCESS (0.425s)
[691.998393] Starting dynamic subtest: vcs0
[692.377179] Dynamic subtest vcs0: SUCCESS (0.419s)
[692.420871] Starting dynamic subtest: vcs1
[692.807181] Dynamic subtest vcs1: SUCCESS (0.423s)
[692.857643] Starting dynamic subtest: vecs0
[693.109823] Dynamic subtest vecs0: SUCCESS (0.300s)
[693.159967] Starting dynamic subtest: ccs0
[693.724092] Dynamic subtest ccs0: SUCCESS (0.609s)
[693.773911] Starting dynamic subtest: all-engines
[694.422583] Dynamic subtest all-engines: SUCCESS (0.693s)
[694.427421] Subtest independent: SUCCESS (3.358s)
[695.638706] [110/142] (326s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[696.161630] Starting subtest: cursorB-vs-flipB-varying-size
[696.175161] Subtest cursorB-vs-flipB-varying-size: SKIP (0.008s)
[696.513511] [111/142] (325s left) kms_scaling_modes (scaling-mode-full)
[697.017824] Starting subtest: scaling-mode-full
[697.027151] Starting dynamic subtest: eDP-1-pipe-A
[697.405349] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.380s)
[697.484364] Starting dynamic subtest: eDP-1-pipe-B
[699.065783] Dynamic subtest eDP-1-pipe-B: SUCCESS (1.648s)
[699.108952] Starting dynamic subtest: eDP-1-pipe-C
[700.683873] Dynamic subtest eDP-1-pipe-C: SUCCESS (1.607s)
[700.745381] Starting dynamic subtest: eDP-1-pipe-D
[702.317459] Dynamic subtest eDP-1-pipe-D: SUCCESS (1.626s)
[702.320988] Subtest scaling-mode-full: SUCCESS (5.300s)
[703.778620] [112/142] (318s left) i915_getparams_basic (basic-eu-total)
[704.161068] Starting subtest: basic-eu-total
[704.168430] Subtest basic-eu-total: SUCCESS (0.000s)
[704.310342] [113/142] (318s left) gem_caching (read-writes)
[704.714376] Subtest read-writes: SKIP (0.000s)
[704.835184] [114/142] (317s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[705.345339] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[705.352250] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[705.516527] [115/142] (317s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[706.083890] Starting subtest: 4-tiled-32bpp-rotate-270
[706.147413] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.060s)
[706.346178] [116/142] (316s left) vc4/vc4_purgeable_bo (mark-purgeable-twice)
[706.815973] Subtest mark-purgeable-twice: SKIP (0.000s)
[706.947582] [117/142] (316s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs)
[707.936781] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs
[707.949726] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[709.376619] [118/142] (313s left) gem_mmap_wc (read-write)
[709.748545] Subtest read-write: SKIP (0.000s)
[709.875713] [119/142] (313s left) gem_exec_balancer (bonded-false-hang)
[711.098788] Starting subtest: bonded-false-hang
[711.106044] Subtest bonded-false-hang: SKIP (0.000s)
[711.277856] [120/142] (311s left) v3d/v3d_create_bo (create-bo-4096)
[711.752877] Subtest create-bo-4096: SKIP (0.000s)
[711.886406] [121/142] (311s left) gem_partial_pwrite_pread (reads-snoop)
[712.286897] Subtest reads-snoop: SKIP (0.000s)
[712.427657] [122/142] (310s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[712.944125] Starting subtest: fbcpsr-modesetfrombusy
[720.730499] Subtest fbcpsr-modesetfrombusy: SUCCESS (7.788s)
[720.909784] [123/142] (302s left) gem_exec_whisper (basic-contexts-all)
[721.331183] Starting subtest: basic-contexts-all
[743.608811] Subtest basic-contexts-all: SUCCESS (22.272s)
[743.916987] [124/142] (279s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[745.631190] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[753.652140] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (8.018s)
[754.991080] [125/142] (268s left) kms_ccs (pipe-c-crc-primary-basic-yf_tiled_ccs)
[756.355427] Starting subtest: pipe-C-crc-primary-basic-yf_tiled_ccs
[756.362348] Subtest pipe-C-crc-primary-basic-yf_tiled_ccs: SKIP (0.002s)
[757.347134] [126/142] (266s left) kms_universal_plane (cursor-fb-leak-pipe-a)
[757.841117] Starting subtest: cursor-fb-leak-pipe-A
[758.961915] Subtest cursor-fb-leak-pipe-A: SUCCESS (1.118s)
[759.905132] [127/142] (263s left) perf_pmu (all-busy-idle-check-all)
[760.298498] Starting subtest: all-busy-idle-check-all
[761.530500] Subtest all-busy-idle-check-all: SUCCESS (1.231s)
[761.690198] [128/142] (262s left) core_setmaster (master-drop-set-root)
[765.318312] Starting subtest: master-drop-set-root
[765.348388] Subtest master-drop-set-root: SUCCESS (0.027s)
[765.436936] [129/142] (258s left) kms_cursor_crc (cursor-random-256x85)
[766.519348] Starting subtest: cursor-random-256x85
[766.550261] Starting dynamic subtest: pipe-A-eDP-1
[772.738142] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.184s)
[772.942475] Starting dynamic subtest: pipe-D-eDP-1
[780.309841] Dynamic subtest pipe-D-eDP-1: SUCCESS (7.411s)
[780.313162] Subtest cursor-random-256x85: SUCCESS (13.794s)
[781.280782] [130/142] (242s left) perf_pmu (module-unload)
[781.700228] Starting subtest: module-unload
[794.160350] Killing the test because the kernel is tainted.
[804.733226] Aborting: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         1633 [max: 8192]
 direct dependencies:                 16076 [max: 32768]
 indirect dependencies:               95695
 all direct dependencies:            301652
 dependency chains:                   22180 [max: 131072]
 dependency chain hlocks used:        88627 [max: 655360]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     173
 in-softirq chains:                     558
 in-process chains:                   21449
 stack-trace entries:                175801 [max: 1048576]
 number of stack traces:               9061
 number of stack hash chains:          6917
 combined max dependencies:      2086355700
 hardirq-safe locks:                     90
 hardirq-unsafe locks:                  978
 softirq-safe locks:                    186
 softirq-unsafe locks:                  889
 irq-safe locks:                        202
 irq-unsafe locks:                      978
 hardirq-read-safe locks:                 3
 hardirq-read-unsafe locks:             298
 softirq-read-safe locks:                 7
 softirq-read-unsafe locks:             293
 irq-read-safe locks:                     7
 irq-read-unsafe locks:                 298
 uncategorized locks:                   268
 unused locks:                            1
 max locking depth:                      16
 max bfs queue depth:                   419
 max lock class index:                 1822
 chain lookup misses:                 28807
 chain lookup hits:               593216528
 cyclic checks:                       28801
 redundant checks:                        0
 redundant links:                         0
 find-mask forwards checks:            4893
 find-mask backwards checks:           2740
 hardirq on events:               389099301
 hardirq off events:              389099280
 redundant hardirq ons:                  78
 redundant hardirq offs:                 43
 softirq on events:                 1552137
 softirq off events:                1552137
 redundant softirq ons:                   0
 redundant softirq offs:                  0
 debug_locks:                             0

 zapped classes:                        200
 zapped lock chains:                   6144
 large chain blocks:                      1

[804.738361] Closing watchdogs
Done.