[57.175913] Initializing watchdogs
[57.176215]   /dev/watchdog0
[57.208515] [001/142] (960s left) kms_sequence (get-busy)
[57.632162] Starting subtest: get-busy
[57.653150] Starting dynamic subtest: DP-1-pipe-A
[60.181438] Dynamic subtest DP-1-pipe-A: SUCCESS (2.555s)
[60.210597] Starting dynamic subtest: DP-1-pipe-B
[62.929714] Dynamic subtest DP-1-pipe-B: SUCCESS (2.744s)
[62.959101] Starting dynamic subtest: DP-1-pipe-C
[65.829445] Dynamic subtest DP-1-pipe-C: SUCCESS (2.890s)
[65.850559] Subtest get-busy: SUCCESS (8.201s)
[66.165842] [002/142] (951s left) kms_plane_multiple (tiling-none)
[66.554554] Starting subtest: tiling-none
[66.575443] Starting dynamic subtest: pipe-A-DP-1
[67.985965] Dynamic subtest pipe-A-DP-1: SUCCESS (1.418s)
[68.019174] Starting dynamic subtest: pipe-B-DP-1
[69.653043] Dynamic subtest pipe-B-DP-1: SUCCESS (1.665s)
[69.686587] Starting dynamic subtest: pipe-C-DP-1
[71.287822] Dynamic subtest pipe-C-DP-1: SUCCESS (1.633s)
[71.306476] Subtest tiling-none: SUCCESS (4.727s)
[71.668814] [003/142] (946s left) gem_barrier_race (remote-request)
[72.019986] Starting subtest: remote-request
[72.045259] Starting dynamic subtest: rcs0
[77.287345] Dynamic subtest rcs0: SUCCESS (5.274s)
[77.305924] Subtest remote-request: SUCCESS (5.275s)
[77.433358] [004/142] (940s left) kms_ccs (pipe-c-bad-aux-stride-yf_tiled_ccs)
[78.306857] Starting subtest: pipe-C-bad-aux-stride-yf_tiled_ccs
[78.357110] Subtest pipe-C-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s)
[78.628660] [005/142] (939s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[79.105128] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[79.153266] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[79.280253] [006/142] (938s left) kms_flip (nonexisting-fb)
[79.666958] Starting subtest: nonexisting-fb
[79.687880] Starting dynamic subtest: A-DP1
[80.412246] Dynamic subtest A-DP1: SUCCESS (0.763s)
[80.443682] Starting dynamic subtest: B-DP1
[80.867924] Dynamic subtest B-DP1: SUCCESS (0.463s)
[80.902272] Starting dynamic subtest: C-DP1
[81.319908] Dynamic subtest C-DP1: SUCCESS (0.442s)
[81.340927] Subtest nonexisting-fb: SUCCESS (1.675s)
[81.744884] [007/142] (936s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[82.053688] Starting subtest: multi-wait-all-for-submit-available-signaled
[82.072555] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.002s)
[82.173739] [008/142] (936s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[82.609947] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[82.648807] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[82.759798] [009/142] (935s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[83.173866] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[83.216090] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[83.320243] [010/142] (935s left) kms_psr2_sf (primary-plane-update-sf-dmg-area)
[83.636876] Subtest primary-plane-update-sf-dmg-area: SKIP (0.000s)
[83.737360] [011/142] (934s left) kms_psr (primary_blt)
[84.051378] Subtest primary_blt: SKIP (0.000s)
[84.155428] [012/142] (934s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc)
[84.952114] Subtest pipe-D-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[85.251866] [013/142] (933s left) kms_dp_aux_dev
[86.112061] [014/142] (932s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[86.554812] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[86.592568] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[86.701198] [015/142] (932s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[87.677759] Starting subtest: syncobj-timeline-invalid-flags
[87.696798] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[87.942312] [016/142] (931s left) vgem_basic (sysfs)
[88.223580] Starting subtest: sysfs
[88.244975] Subtest sysfs: SUCCESS (0.001s)
[88.346375] [017/142] (930s left) kms_flip (2x-nonexisting-fb-interruptible)
[88.723933] Starting subtest: 2x-nonexisting-fb-interruptible
[88.767351] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.003s)
[88.870167] [018/142] (930s left) kms_psr (psr2_cursor_plane_move)
[89.182164] Subtest psr2_cursor_plane_move: SKIP (0.000s)
[89.304078] [019/142] (930s left) kms_cursor_crc (cursor-random-128x128)
[89.719715] Starting subtest: cursor-random-128x128
[89.740714] Starting dynamic subtest: pipe-A-DP-1
[93.557411] Dynamic subtest pipe-A-DP-1: SUCCESS (3.810s)
[93.612409] Starting dynamic subtest: pipe-C-DP-1
[97.720398] Dynamic subtest pipe-C-DP-1: SUCCESS (4.116s)
[97.739686] Subtest cursor-random-128x128: SUCCESS (7.999s)
[98.109810] [020/142] (921s left) kms_legacy_colorkey (basic)
[98.523799] Starting subtest: basic
[98.545416] Starting dynamic subtest: pipe-A
[98.571595] Dynamic subtest pipe-A: SUCCESS (0.020s)
[98.612176] Starting dynamic subtest: pipe-B
[98.637152] Dynamic subtest pipe-B: SUCCESS (0.001s)
[98.673588] Starting dynamic subtest: pipe-C
[98.702248] Dynamic subtest pipe-C: SUCCESS (0.000s)
[98.726520] Subtest basic: SUCCESS (0.023s)
[98.863454] [021/142] (920s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[99.288138] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[99.309975] Starting dynamic subtest: pipe-A-DP-1
[100.289237] Dynamic subtest pipe-A-DP-1: SUCCESS (1.008s)
[100.327527] Starting dynamic subtest: pipe-B-DP-1
[101.607687] Dynamic subtest pipe-B-DP-1: SUCCESS (1.315s)
[101.642543] Starting dynamic subtest: pipe-C-DP-1
[102.535746] Dynamic subtest pipe-C-DP-1: SUCCESS (0.920s)
[102.556866] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (3.255s)
[102.958457] [022/142] (916s left) kms_content_protection (atomic-dpms)
[103.379626] Starting subtest: atomic-dpms
[103.399231] Starting dynamic subtest: pipe-A-DP-1
[224.088143] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[231.211118] Closing watchdogs
[231.219606] Initializing watchdogs
[231.219770]   /dev/watchdog0
[231.274363] [023/142] (788s left) core_setmaster (master-drop-set-shared-fd)
[233.269957] Starting subtest: master-drop-set-shared-fd
[233.309455] Subtest master-drop-set-shared-fd: SUCCESS (0.045s)
[233.419541] [024/142] (786s left) kms_big_fb (linear-32bpp-rotate-180)
[233.820191] Starting subtest: linear-32bpp-rotate-180
[239.597226] Subtest linear-32bpp-rotate-180: SUCCESS (5.793s)
[240.180331] [025/142] (779s left) testdisplay
[263.684706] [026/142] (756s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[263.983182] Starting subtest: single-wait-all-for-submit-signaled
[264.004180] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s)
[264.108199] [027/142] (756s left) kms_ccs (pipe-a-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[264.613469] Starting subtest: pipe-A-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[264.652087] Subtest pipe-A-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[265.205753] [028/142] (755s left) gem_userptr_blits (dmabuf-unsync)
[265.585798] Subtest dmabuf-unsync: SKIP (0.000s)
[265.692637] [029/142] (754s left) i915_pm_dc (dc9-dpms)
[267.078026] Starting subtest: dc9-dpms
[267.151102] Subtest dc9-dpms: SKIP (0.000s)
[267.282805] [030/142] (753s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[267.706240] Starting subtest: y-tiled-32bpp-rotate-270
[272.874023] Subtest y-tiled-32bpp-rotate-270: SUCCESS (5.209s)
[273.421500] [031/142] (747s left) gem_exec_params (invalid-ring)
[273.712240] Starting subtest: invalid-ring
[273.731143] Subtest invalid-ring: SUCCESS (0.001s)
[273.875178] [032/142] (746s left) gem_exec_schedule (deep)
[274.312233] Starting subtest: deep
[274.333943] Starting dynamic subtest: rcs0
[288.264559] Dynamic subtest rcs0: SUCCESS (13.979s)
[288.318559] Starting dynamic subtest: bcs0
[303.570938] Dynamic subtest bcs0: SUCCESS (15.302s)
[303.608357] Starting dynamic subtest: vcs0
[318.087641] Dynamic subtest vcs0: SUCCESS (14.514s)
[318.129532] Starting dynamic subtest: vecs0
[332.543275] Dynamic subtest vecs0: SUCCESS (14.440s)
[332.562499] Subtest deep: SUCCESS (58.256s)
[333.563531] [033/142] (687s left) kms_atomic (crtc-invalid-params-fence)
[334.034806] Starting subtest: crtc-invalid-params-fence
[334.227414] Subtest crtc-invalid-params-fence: SUCCESS (0.192s)
[334.715241] [034/142] (686s left) kms_display_modes (extended-mode-basic)
[335.090190] Starting subtest: extended-mode-basic
[335.128653] Subtest extended-mode-basic: SKIP (0.000s)
[335.241764] [035/142] (685s left) v3d/v3d_submit_csd (multiple-job-submission)
[335.596398] Subtest multiple-job-submission: SKIP (0.000s)
[335.701778] [036/142] (685s left) gem_mmap_gtt (basic-small-copy)
[336.001382] Starting subtest: basic-small-copy
[340.482563] Subtest basic-small-copy: SUCCESS (4.513s)
[340.594441] [037/142] (680s left) i915_pm_rpm (modeset-lpsp)
[342.818542] Starting subtest: modeset-lpsp
[343.253272] Subtest modeset-lpsp: SKIP (0.413s)
[343.758899] [038/142] (677s left) kms_color (gamma)
[344.128642] Starting subtest: gamma
[344.147742] Starting dynamic subtest: pipe-A
[344.684908] Dynamic subtest pipe-A: SUCCESS (0.543s)
[344.724191] Starting dynamic subtest: pipe-B
[345.496805] Dynamic subtest pipe-B: SUCCESS (0.813s)
[345.534532] Starting dynamic subtest: pipe-C
[346.455939] Dynamic subtest pipe-C: SUCCESS (0.956s)
[346.476516] Subtest gamma: SUCCESS (2.319s)
[346.824191] [039/142] (674s left) kms_vblank (pipe-c-query-forked-busy-hang)
[347.190600] Starting subtest: pipe-C-query-forked-busy-hang
[355.131473] Subtest pipe-C-query-forked-busy-hang: SUCCESS (7.818s)
[355.380138] [040/142] (666s left) perf (invalid-remove-userspace-config)
[355.859469] Starting subtest: invalid-remove-userspace-config
[355.880797] Subtest invalid-remove-userspace-config: SUCCESS (0.039s)
[355.985091] [041/142] (665s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[356.286370] Starting subtest: basic-cpu-gtt-noreloc
[356.305373] Subtest basic-cpu-gtt-noreloc: SUCCESS (0.016s)
[356.485618] [042/142] (665s left) kms_ccs (pipe-c-ccs-on-another-bo-yf_tiled_ccs)
[357.230240] Starting subtest: pipe-C-ccs-on-another-bo-yf_tiled_ccs
[357.272906] Subtest pipe-C-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s)
[357.606800] [043/142] (664s left) gem_busy (parallel)
[358.044120] Starting subtest: parallel
[358.063616] Starting dynamic subtest: rcs0
[358.108346] Dynamic subtest rcs0: SUCCESS (0.050s)
[358.147137] Starting dynamic subtest: bcs0
[358.168108] Dynamic subtest bcs0: SUCCESS (0.036s)
[358.214275] Starting dynamic subtest: vcs0
[358.233508] Dynamic subtest vcs0: SUCCESS (0.045s)
[358.267852] Starting dynamic subtest: vecs0
[358.286698] Dynamic subtest vecs0: SUCCESS (0.036s)
[358.306763] Subtest parallel: SUCCESS (0.179s)
[358.503822] [044/142] (663s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[358.957151] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[358.994578] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[359.106945] [045/142] (663s left) syncobj_timeline (etime-single-wait-submitted)
[359.403613] Starting subtest: etime-single-wait-submitted
[359.502489] Subtest etime-single-wait-submitted: SUCCESS (0.107s)
[359.618181] [046/142] (662s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[360.042298] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[360.081971] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[360.189826] [047/142] (662s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_gen12_mc_ccs)
[360.745391] Starting subtest: pipe-B-bad-rotation-90-y_tiled_gen12_mc_ccs
[360.786073] Subtest pipe-B-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[361.313170] [048/142] (661s left) drm_fdinfo (virtual-busy-hang)
[361.607601] Starting subtest: virtual-busy-hang
[365.788122] Subtest virtual-busy-hang: SUCCESS (4.205s)
[365.909067] [049/142] (656s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[366.294305] Starting subtest: cursor-rapid-movement-32x10
[366.452752] Subtest cursor-rapid-movement-32x10: SKIP (0.107s)
[366.572806] [050/142] (656s left) gem_exec_schedule (pi-ringfull)
[367.024574] Starting subtest: pi-ringfull
[367.043040] Starting dynamic subtest: rcs0
[367.101318] Dynamic subtest rcs0: SUCCESS (0.091s)
[367.136890] Starting dynamic subtest: bcs0
[367.192364] Dynamic subtest bcs0: SUCCESS (0.090s)
[367.238776] Starting dynamic subtest: vcs0
[367.292439] Dynamic subtest vcs0: SUCCESS (0.088s)
[367.340823] Starting dynamic subtest: vecs0
[367.391149] Dynamic subtest vecs0: SUCCESS (0.089s)
[367.415707] Subtest pi-ringfull: SUCCESS (0.369s)
[367.610609] [051/142] (655s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[367.980325] Starting subtest: cursorB-vs-flipB-atomic-transitions
[368.018713] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.003s)
[368.129946] [052/142] (654s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[368.931779] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[369.257451] [053/142] (653s left) gen7_exec_parse (load-register-reg)
[369.573454] Subtest load-register-reg: SKIP (0.000s)
[369.680918] [054/142] (653s left) vc4/vc4_purgeable_bo (mark-purgeable)
[370.027722] Subtest mark-purgeable: SKIP (0.000s)
[370.138133] [055/142] (652s left) kms_addfb_basic (tile-pitch-mismatch)
[370.434501] Starting subtest: tile-pitch-mismatch
[370.456163] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[370.649454] [056/142] (652s left) gem_workarounds (suspend-resume-context)
[370.971278] Starting subtest: suspend-resume-context
[388.488692] Subtest suspend-resume-context: SUCCESS (1.311s)
[394.665203] [057/142] (650s left) kms_flip (2x-flip-vs-expired-vblank)
[395.043549] Starting subtest: 2x-flip-vs-expired-vblank
[395.083253] Subtest 2x-flip-vs-expired-vblank: SKIP (0.003s)
[395.196021] [058/142] (650s left) gem_pxp (verify-pxp-stale-buf-execution)
[398.222888] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[398.330153] [059/142] (647s left) vc4/vc4_wait_seqno (bad-seqno-0ns)
[398.663339] Subtest bad-seqno-0ns: SKIP (0.000s)
[398.760251] [060/142] (647s left) gem_flink_basic (double-flink)
[399.046725] Starting subtest: double-flink
[399.069184] Subtest double-flink: SUCCESS (0.000s)
[399.162676] [061/142] (646s left) gem_exec_schedule (submit-golden-slice)
[399.614598] Starting subtest: submit-golden-slice
[399.635777] Starting dynamic subtest: rcs0
[399.654648] Dynamic subtest rcs0: SUCCESS (0.053s)
[399.692945] Starting dynamic subtest: bcs0
[399.711751] Dynamic subtest bcs0: SUCCESS (0.048s)
[399.749152] Starting dynamic subtest: vcs0
[399.771130] Dynamic subtest vcs0: SUCCESS (0.045s)
[399.811490] Starting dynamic subtest: vecs0
[399.832170] Dynamic subtest vecs0: SUCCESS (0.042s)
[399.853246] Subtest submit-golden-slice: SUCCESS (0.201s)
[400.050373] [062/142] (645s left) kms_hdr (bpc-switch)
[400.416073] Starting subtest: bpc-switch
[402.597383] Starting dynamic subtest: pipe-A-DP-1
[403.660257] Dynamic subtest pipe-A-DP-1: SUCCESS (1.086s)
[403.679414] Subtest bpc-switch: SUCCESS (3.248s)
[404.076808] [063/142] (642s left) gem_mmap (pf-nonblock)
[404.357339] Starting subtest: pf-nonblock
[404.376118] Subtest pf-nonblock: SUCCESS (0.006s)
[404.487199] [064/142] (641s left) i915_pm_rps (reset)
[404.784174] Starting subtest: reset
[408.523252] Subtest reset: SUCCESS (3.575s)
[408.612430] [065/142] (637s left) v3d/v3d_perfmon (create-two-perfmon)
[408.962724] Subtest create-two-perfmon: SKIP (0.000s)
[409.057656] [066/142] (637s left) syncobj_wait (multi-wait-submitted)
[409.333069] Starting subtest: multi-wait-submitted
[409.410789] Subtest multi-wait-submitted: SUCCESS (0.102s)
[409.524451] [067/142] (636s left) gem_ctx_param (invalid-get-engines)
[409.820871] Starting subtest: invalid-get-engines
[409.840452] Subtest invalid-get-engines: SUCCESS (0.000s)
[409.928798] [068/142] (636s left) gem_exec_store (cachelines)
[410.285684] Starting subtest: cachelines
[410.307265] Starting dynamic subtest: rcs0
[410.328616] Dynamic subtest rcs0: SUCCESS (0.057s)
[410.369731] Starting dynamic subtest: bcs0
[410.389319] Dynamic subtest bcs0: SUCCESS (0.038s)
[410.426687] Starting dynamic subtest: vcs0
[410.445225] Dynamic subtest vcs0: SUCCESS (0.026s)
[410.482302] Starting dynamic subtest: vecs0
[410.502148] Dynamic subtest vecs0: SUCCESS (0.032s)
[410.523440] Subtest cachelines: SUCCESS (0.166s)
[410.626719] [069/142] (636s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[411.023134] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[412.255525] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.251s)
[412.611025] [070/142] (634s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[413.058631] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[413.096293] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[413.206213] [071/142] (633s left) sysfs_timeslice_duration (duration)
[413.497737] Starting subtest: duration
[413.517136] Starting dynamic subtest: vecs0
[413.635990] Dynamic subtest vecs0: SUCCESS (0.090s)
[413.678577] Starting dynamic subtest: vcs0
[413.800472] Dynamic subtest vcs0: SUCCESS (0.089s)
[413.845925] Starting dynamic subtest: bcs0
[413.968346] Dynamic subtest bcs0: SUCCESS (0.096s)
[414.010257] Starting dynamic subtest: rcs0
[414.155500] Dynamic subtest rcs0: SUCCESS (0.103s)
[414.180861] Subtest duration: SUCCESS (0.390s)
[414.289614] [072/142] (632s left) kms_chamelium_frames (hdmi-crc-single)
[414.783155] Subtest hdmi-crc-single: SKIP (0.000s)
[415.112067] [073/142] (632s left) kms_force_connector_basic (prune-stale-modes)
[415.426380] Subtest prune-stale-modes: SKIP (0.000s)
[415.541206] [074/142] (631s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[415.963779] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[416.005801] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[416.115781] [075/142] (631s left) core_getversion
[416.521535] [076/142] (630s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[416.963244] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[419.898443] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SUCCESS (3.012s)
[420.067091] [077/142] (627s left) i915_module_load (load)
[420.340005] Starting subtest: load
[420.382997] Subtest load: SKIP (0.003s)
[420.474093] [078/142] (627s left) gem_mmap_gtt (basic)
[420.771108] Starting subtest: basic
[420.790329] Subtest basic: SUCCESS (0.002s)
[420.879192] [079/142] (626s left) kms_chamelium_audio (hdmi-audio-edid)
[421.335995] Subtest hdmi-audio-edid: SKIP (0.000s)
[421.668151] [080/142] (626s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[422.461672] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[422.822940] [081/142] (625s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_mtl_rc_ccs)
[423.537929] Starting subtest: pipe-C-random-ccs-data-4_tiled_mtl_rc_ccs
[423.599868] Subtest pipe-C-random-ccs-data-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[423.938749] [082/142] (624s left) gem_pxp (reject-modify-context-protection-off-2)
[426.911319] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[427.066181] [083/142] (621s left) gem_spin_batch (resubmit-all)
[427.371608] Starting subtest: resubmit-all
[427.390709] Starting dynamic subtest: rcs0
[427.409656] Dynamic subtest rcs0: SUCCESS (0.011s)
[427.446910] Starting dynamic subtest: bcs0
[427.465541] Dynamic subtest bcs0: SUCCESS (0.010s)
[427.505482] Starting dynamic subtest: vcs0
[427.526931] Dynamic subtest vcs0: SUCCESS (0.006s)
[427.566878] Starting dynamic subtest: vecs0
[427.588061] Dynamic subtest vecs0: SUCCESS (0.006s)
[427.607007] Subtest resubmit-all: SUCCESS (0.045s)
[427.695905] [084/142] (620s left) gem_mmap_gtt (big-copy)
[427.972596] Starting subtest: big-copy
[438.761438] Subtest big-copy: SUCCESS (10.821s)
[438.934441] [085/142] (609s left) gem_exec_fence (long-history)
[439.790208] Starting subtest: long-history
[445.521212] Subtest long-history: SUCCESS (5.751s)
[446.233763] [086/142] (602s left) kms_writeback (writeback-fb-id)
[446.769112] Subtest writeback-fb-id: SKIP (0.000s)
[446.888322] [087/142] (601s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[447.338571] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[447.381303] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[447.493906] [088/142] (601s left) perf (short-reads)
[447.957449] Starting subtest: short-reads
[448.028997] Subtest short-reads: SUCCESS (0.051s)
[448.143617] [089/142] (600s left) gem_exec_fence (basic-busy-all)
[448.460373] Starting subtest: basic-busy-all
[448.479651] Subtest basic-busy-all: SUCCESS (0.042s)
[449.284571] [090/142] (599s left) gem_exec_balancer (parallel-balancer)
[453.539928] Subtest parallel-balancer: SKIP (0.000s)
[453.637332] [091/142] (595s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[453.932461] Starting subtest: invalid-multi-wait-all-unsubmitted
[453.954261] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[454.048120] [092/142] (594s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[454.408979] Starting subtest: blocking-absolute-wf_vblank-interruptible
[454.427711] Starting dynamic subtest: A-DP1
[465.136786] Dynamic subtest A-DP1: SUCCESS (10.719s)
[465.175612] Starting dynamic subtest: B-DP1
[475.578266] Dynamic subtest B-DP1: SUCCESS (10.434s)
[475.620061] Starting dynamic subtest: C-DP1
[486.180064] Dynamic subtest C-DP1: SUCCESS (10.432s)
[486.199294] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (31.599s)
[486.451916] [093/142] (562s left) kms_flip (absolute-wf_vblank)
[486.836504] Starting subtest: absolute-wf_vblank
[486.861181] Starting dynamic subtest: A-DP1
[497.662217] Dynamic subtest A-DP1: SUCCESS (10.669s)
[497.708249] Starting dynamic subtest: B-DP1
[508.116581] Dynamic subtest B-DP1: SUCCESS (10.450s)
[508.168653] Starting dynamic subtest: C-DP1
[518.533382] Dynamic subtest C-DP1: SUCCESS (10.605s)
[518.551940] Subtest absolute-wf_vblank: SUCCESS (31.739s)
[518.983810] [094/142] (530s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[519.374554] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc
[519.416227] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[520.114501] [095/142] (529s left) kms_ccs (pipe-a-ccs-on-another-bo-yf_tiled_ccs)
[520.630641] Starting subtest: pipe-A-ccs-on-another-bo-yf_tiled_ccs
[520.652112] Subtest pipe-A-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[521.257228] [096/142] (528s left) gem_exec_reloc (basic-cpu-gtt)
[521.552838] Starting subtest: basic-cpu-gtt
[521.574109] Subtest basic-cpu-gtt: SUCCESS (0.022s)
[521.789737] [097/142] (527s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[522.214751] Starting subtest: 4-tiled-16bpp-rotate-90
[522.255046] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.000s)
[522.372574] [098/142] (527s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[522.958876] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc
[523.002733] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s)
[523.496623] [099/142] (526s left) gem_ringfill (engines-basic)
[524.065543] Starting subtest: engines-basic
[524.084477] Starting dynamic subtest: rcs0
[524.103626] Dynamic subtest rcs0: SUCCESS (0.034s)
[524.145324] Starting dynamic subtest: bcs0
[524.163996] Dynamic subtest bcs0: SUCCESS (0.030s)
[524.205902] Starting dynamic subtest: vcs0
[524.227210] Dynamic subtest vcs0: SUCCESS (0.026s)
[524.268145] Starting dynamic subtest: vecs0
[524.288815] Dynamic subtest vecs0: SUCCESS (0.020s)
[524.307454] Subtest engines-basic: SUCCESS (0.121s)
[524.417808] [100/142] (525s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[524.854203] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[524.891014] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[525.010756] [101/142] (524s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_gen12_rc_ccs)
[525.812804] Subtest pipe-D-ccs-on-another-bo-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[526.142841] [102/142] (523s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[526.561008] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[526.601834] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[526.719916] [103/142] (523s left) gem_mmap_wc (write-cpu-read-wc)
[527.018726] Starting subtest: write-cpu-read-wc
[527.243861] Subtest write-cpu-read-wc: SUCCESS (0.275s)
[527.382032] [104/142] (522s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[527.748785] Starting subtest: 2x-flip-vs-cursor-atomic
[527.787713] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.005s)
[527.907175] [105/142] (522s left) syncobj_wait (invalid-reset-one-illegal-handle)
[528.201824] Starting subtest: invalid-reset-one-illegal-handle
[528.223412] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.001s)
[528.332222] [106/142] (522s left) perf (non-system-wide-paranoid)
[528.693169] Starting subtest: non-system-wide-paranoid
[528.784017] Subtest non-system-wide-paranoid: SUCCESS (0.110s)
[528.914148] [107/142] (521s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[529.296254] Starting subtest: flip-vs-cursor-crc-legacy
[529.919827] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.647s)
[530.302205] [108/142] (520s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[530.842464] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[530.879991] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[531.421319] [109/142] (519s left) gem_fence_thrash (bo-write-verify-threaded-x)
[531.704514] Starting subtest: bo-write-verify-threaded-x
[534.323911] Subtest bo-write-verify-threaded-x: SUCCESS (2.451s)
[534.420603] [110/142] (516s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[534.816020] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[534.854128] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[534.981441] [111/142] (516s left) gem_mmap_gtt (hang-user)
[535.304982] Starting subtest: hang-user
[550.592722] Subtest hang-user: SUCCESS (15.155s)
[550.711086] [112/142] (500s left) i915_hangman (engine-engine-error)
[551.011394] Starting subtest: engine-engine-error
[551.032797] Starting dynamic subtest: rcs0
[551.094299] Dynamic subtest rcs0: SUCCESS (0.090s)
[551.141431] Starting dynamic subtest: bcs0
[551.184298] Dynamic subtest bcs0: SKIP (0.003s)
[551.232683] Starting dynamic subtest: vcs0
[551.251729] Dynamic subtest vcs0: SUCCESS (0.079s)
[551.294596] Starting dynamic subtest: vecs0
[551.313668] Dynamic subtest vecs0: SUCCESS (0.087s)
[551.334087] Subtest engine-engine-error: SUCCESS (0.270s)
[551.433700] [113/142] (499s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[551.797227] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[551.816323] Starting dynamic subtest: pipe-A-valid-mode
[551.904165] Dynamic subtest pipe-A-valid-mode: SKIP (0.088s)
[551.946521] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SKIP (0.093s)
[552.234373] [114/142] (499s left) syncobj_basic (bad-fd-to-handle)
[552.529785] Starting subtest: bad-fd-to-handle
[552.548870] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[552.662219] [115/142] (498s left) syncobj_timeline (multi-wait-signaled)
[552.953740] Starting subtest: multi-wait-signaled
[552.973996] Subtest multi-wait-signaled: SUCCESS (0.003s)
[553.091731] [116/142] (498s left) gem_softpin (noreloc-interruptible)
[553.374324] Starting subtest: noreloc-interruptible
[559.054221] Subtest noreloc-interruptible: SUCCESS (5.531s)
[559.144121] [117/142] (492s left) gem_exec_alignment (forked)
[559.442327] Starting subtest: forked
[572.363281] Killing the test because the kernel is tainted.
[578.645190] Aborting: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         1814 [max: 8192]
 direct dependencies:                 18446 [max: 32768]
 indirect dependencies:              156297
 all direct dependencies:            378638
 dependency chains:                   26342 [max: 131072]
 dependency chain hlocks used:       112164 [max: 655360]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     244
 in-softirq chains:                     711
 in-process chains:                   25387
 stack-trace entries:                165912 [max: 1048576]
 number of stack traces:               8649
 number of stack hash chains:          6720
 combined max dependencies:       133715424
 hardirq-safe locks:                     99
 hardirq-unsafe locks:                 1072
 softirq-safe locks:                    211
 softirq-unsafe locks:                  982
 irq-safe locks:                        237
 irq-unsafe locks:                     1072
 hardirq-read-safe locks:                 4
 hardirq-read-unsafe locks:             327
 softirq-read-safe locks:                 8
 softirq-read-unsafe locks:             322
 irq-read-safe locks:                     8
 irq-read-unsafe locks:                 327
 uncategorized locks:                   294
 unused locks:                            1
 max locking depth:                      16
 max bfs queue depth:                   416
 max lock class index:                 1813
 chain lookup misses:                 26534
 chain lookup hits:               198942988
 cyclic checks:                       26740
 redundant checks:                        0
 redundant links:                         0
 find-mask forwards checks:            4392
 find-mask backwards checks:           2705
 hardirq on events:               116708563
 hardirq off events:              116708559
 redundant hardirq ons:                  38
 redundant hardirq offs:                 68
 softirq on events:                  598318
 softirq off events:                 598318
 redundant softirq ons:                   0
 redundant softirq offs:                  0
 debug_locks:                             0

 zapped classes:                          1
 zapped lock chains:                    123
 large chain blocks:                      1

[578.666975] Closing watchdogs
Done.