[39.524637] Initializing watchdogs [39.524706] /dev/watchdog0 [39.536825] [001/143] (960s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render) [39.673346] Starting subtest: fbc-rgb565-draw-render [42.755562] Subtest fbc-rgb565-draw-render: SUCCESS (3.088s) [42.895314] [002/143] (957s left) i915_pm_rpm (gem-execbuf) [44.590867] Starting subtest: gem-execbuf [44.593783] Starting dynamic subtest: smem0 [46.591359] Dynamic subtest smem0: SUCCESS (2.002s) [46.594091] Subtest gem-execbuf: SUCCESS (2.002s) [46.678494] [003/143] (953s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc) [47.083732] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc [47.089998] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.001s) [47.970597] [004/143] (952s left) kms_ccs (pipe-b-ccs-on-another-bo-4-tiled-mtl-rc-ccs) [48.397722] Starting subtest: pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs [48.400304] Subtest pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.000s) [49.227225] [005/143] (950s left) kms_frontbuffer_tracking (psr-suspend) [49.295981] Starting subtest: psr-suspend [86.754857] Subtest psr-suspend: SUCCESS (5.948s) [86.862839] [006/143] (944s left) kms_properties (get_properties-sanity-non-atomic) [86.926608] Starting subtest: get_properties-sanity-non-atomic [86.929025] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s) [86.985982] [007/143] (944s left) kms_lease (possible-crtcs-filtering) [87.039321] Starting subtest: possible-crtcs-filtering [87.041745] Subtest possible-crtcs-filtering: SUCCESS (0.005s) [87.103528] [008/143] (944s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted) [87.160969] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted [87.163619] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s) [87.229918] [009/143] (944s left) kms_pm_lpsp (kms-lpsp) [88.331897] Starting subtest: kms-lpsp [88.334117] Starting dynamic subtest: pipe-A-eDP-1 [88.509870] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.176s) [88.825575] Subtest kms-lpsp: SUCCESS (0.496s) [89.729807] [010/143] (942s left) gem_pxp (reject-modify-context-protection-off-3) [92.316449] Subtest reject-modify-context-protection-off-3: SKIP (0.000s) [92.384696] [011/143] (939s left) gem_exec_balancer (full-pulse) [92.482864] Starting subtest: full-pulse [96.572599] Subtest full-pulse: SUCCESS (4.089s) [96.814463] [012/143] (935s left) kms_psr (fbc-psr-sprite-mmap-gtt) [96.886053] Starting subtest: fbc-psr-sprite-mmap-gtt [96.888636] Starting dynamic subtest: eDP-1 [96.893620] Dynamic subtest eDP-1: SKIP (0.000s) [96.898297] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.000s) [96.986431] [013/143] (934s left) gem_exec_schedule (preempt-contexts) [97.069900] Starting subtest: preempt-contexts [97.072345] Starting dynamic subtest: rcs0 [97.132809] Dynamic subtest rcs0: SUCCESS (0.064s) [97.150105] Starting dynamic subtest: bcs0 [97.176154] Dynamic subtest bcs0: SUCCESS (0.042s) [97.194502] Starting dynamic subtest: vcs0 [97.215055] Dynamic subtest vcs0: SUCCESS (0.039s) [97.230795] Starting dynamic subtest: vcs1 [97.251204] Dynamic subtest vcs1: SUCCESS (0.036s) [97.267545] Starting dynamic subtest: vecs0 [97.286662] Dynamic subtest vecs0: SUCCESS (0.037s) [97.301921] Starting dynamic subtest: ccs0 [97.323665] Dynamic subtest ccs0: SUCCESS (0.034s) [97.326236] Subtest preempt-contexts: SUCCESS (0.253s) [99.036569] [014/143] (932s left) kms_cursor_crc (cursor-random-512x170) [99.296354] Starting subtest: cursor-random-512x170 [99.300970] Subtest cursor-random-512x170: SKIP (0.001s) [99.415842] [015/143] (932s left) syncobj_timeline (etime-single-wait-submitted) [99.466865] Starting subtest: etime-single-wait-submitted [99.571626] Subtest etime-single-wait-submitted: SUCCESS (0.106s) [99.644683] [016/143] (932s left) perf (missing-sample-flags) [99.770267] Starting subtest: missing-sample-flags [99.772648] Subtest missing-sample-flags: SUCCESS (0.000s) [99.790041] [017/143] (932s left) kms_addfb_basic (addfb25-y-tiled-legacy) [99.845917] Starting subtest: addfb25-y-tiled-legacy [99.847973] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [99.917137] [018/143] (932s left) syncobj_wait (wait-for-submit-snapshot) [99.982926] Starting subtest: wait-for-submit-snapshot [100.067001] Subtest wait-for-submit-snapshot: SUCCESS (0.083s) [100.132662] [019/143] (931s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt) [100.186242] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt [103.122171] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (2.940s) [103.245190] [020/143] (928s left) gem_mmap_wc (write-gtt-read-wc) [103.297899] Subtest write-gtt-read-wc: SKIP (0.000s) [103.332778] [021/143] (928s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt) [103.421907] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt [103.425855] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [103.514389] [022/143] (928s left) prime_busy (hang) [103.583453] Starting subtest: hang [103.586167] Starting dynamic subtest: rcs0 [117.500335] Dynamic subtest rcs0: SUCCESS (13.915s) [117.506602] Starting dynamic subtest: bcs0 [124.654503] Dynamic subtest bcs0: SUCCESS (7.153s) [124.660639] Starting dynamic subtest: vcs0 [131.617673] Dynamic subtest vcs0: SUCCESS (6.963s) [131.633235] Starting dynamic subtest: vcs1 [138.654752] Dynamic subtest vcs1: SUCCESS (7.039s) [138.671038] Starting dynamic subtest: vecs0 [145.645867] Dynamic subtest vecs0: SUCCESS (6.988s) [145.652212] Starting dynamic subtest: ccs0 [159.475153] Dynamic subtest ccs0: SUCCESS (13.831s) [159.478012] Subtest hang: SUCCESS (55.890s) [159.578411] [023/143] (872s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy) [159.664774] Starting subtest: flip-vs-cursor-crc-legacy [160.395960] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.730s) [161.282363] [024/143] (870s left) v3d/v3d_job_submission (threaded-job-submission) [161.362603] Subtest threaded-job-submission: SKIP (0.000s) [161.406042] [025/143] (870s left) gem_madvise (dontneed-after-mmap) [161.440907] Starting subtest: dontneed-after-mmap [161.445433] Subtest dontneed-after-mmap: SUCCESS (0.002s) [161.509681] [026/143] (870s left) syncobj_timeline (wait-all-complex) [161.563001] Starting subtest: wait-all-complex [161.779998] Subtest wait-all-complex: SUCCESS (0.218s) [161.851224] [027/143] (870s left) gem_partial_pwrite_pread (write) [161.916789] Subtest write: SKIP (0.000s) [161.948832] [028/143] (870s left) perf_pmu (rc6) [161.987950] Starting subtest: rc6 [161.990429] Starting dynamic subtest: gt0 [168.329221] Dynamic subtest gt0: SUCCESS (6.342s) [168.346214] Starting dynamic subtest: runtime-pm-gt0 [176.037325] Dynamic subtest runtime-pm-gt0: SUCCESS (7.708s) [176.054440] Starting dynamic subtest: runtime-pm-long-gt0 [188.434704] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.397s) [188.453074] Starting dynamic subtest: other-idle-gt0 [194.779121] Dynamic subtest other-idle-gt0: SUCCESS (6.345s) [194.795574] Starting dynamic subtest: gt1 [201.121246] Dynamic subtest gt1: SUCCESS (6.342s) [201.139321] Starting dynamic subtest: runtime-pm-gt1 [208.519597] Dynamic subtest runtime-pm-gt1: SUCCESS (7.397s) [208.537088] Starting dynamic subtest: runtime-pm-long-gt1 [220.919437] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.398s) [220.936572] Starting dynamic subtest: other-idle-gt1 [227.262478] Dynamic subtest other-idle-gt1: SUCCESS (6.343s) [227.265469] Subtest rc6: SUCCESS (65.274s) [228.288398] [029/143] (804s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render) [228.364060] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render [228.368795] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [228.420873] [030/143] (803s left) gem_fenced_exec_thrash (no-spare-fences-busy) [228.488207] Subtest no-spare-fences-busy: SKIP (0.000s) [228.526210] [031/143] (803s left) kms_ccs (pipe-c-ccs-on-another-bo-y-tiled-gen12-mc-ccs) [228.934606] Starting subtest: pipe-C-ccs-on-another-bo-y-tiled-gen12-mc-ccs [228.941028] Subtest pipe-C-ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.000s) [229.803615] [032/143] (802s left) gem_mmap_offset (basic-uaf) [229.867257] Starting subtest: basic-uaf [229.869678] Subtest basic-uaf: SUCCESS (0.001s) [229.922225] [033/143] (802s left) gem_userptr_blits (map-fixed-invalidate-busy) [229.995329] Starting subtest: map-fixed-invalidate-busy [230.000085] Subtest map-fixed-invalidate-busy: SKIP (0.000s) [230.073871] [034/143] (802s left) gem_create (create-clear) [230.137367] Starting subtest: create-clear [230.139813] Starting dynamic subtest: smem0 [263.544710] Dynamic subtest smem0: SUCCESS (33.405s) [263.547647] Subtest create-clear: SUCCESS (33.405s) [263.714985] [035/143] (768s left) gem_spin_batch (resubmit-all) [263.806690] Starting subtest: resubmit-all [263.809583] Starting dynamic subtest: rcs0 [263.831047] Dynamic subtest rcs0: SUCCESS (0.025s) [263.849070] Starting dynamic subtest: bcs0 [263.851703] Dynamic subtest bcs0: SUCCESS (0.002s) [263.865408] Starting dynamic subtest: vcs0 [263.867996] Dynamic subtest vcs0: SUCCESS (0.002s) [263.885887] Starting dynamic subtest: vcs1 [263.888451] Dynamic subtest vcs1: SUCCESS (0.002s) [263.902213] Starting dynamic subtest: vecs0 [263.904881] Dynamic subtest vecs0: SUCCESS (0.001s) [263.915119] Starting dynamic subtest: ccs0 [263.917530] Dynamic subtest ccs0: SUCCESS (0.001s) [263.919924] Subtest resubmit-all: SUCCESS (0.034s) [264.979394] [036/143] (767s left) kms_chamelium_hpd (vga-hpd-without-ddc) [265.349772] Subtest vga-hpd-without-ddc: SKIP (0.000s) [266.211698] [037/143] (766s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-factor-0-25) [266.292450] Starting subtest: planes-downscale-factor-0-25-upscale-factor-0-25 [266.294863] Starting dynamic subtest: pipe-A-eDP-1 [266.299437] Dynamic subtest pipe-A-eDP-1: SKIP (0.008s) [266.307475] Starting dynamic subtest: pipe-B-eDP-1 [266.317557] Dynamic subtest pipe-B-eDP-1: SKIP (0.015s) [266.324718] Starting dynamic subtest: pipe-C-eDP-1 [266.332048] Dynamic subtest pipe-C-eDP-1: SKIP (0.015s) [266.345626] Starting dynamic subtest: pipe-D-eDP-1 [266.350034] Dynamic subtest pipe-D-eDP-1: SKIP (0.016s) [266.353830] Subtest planes-downscale-factor-0-25-upscale-factor-0-25: SKIP (0.058s) [266.393500] [038/143] (766s left) kms_cursor_edge_walk (128x128-top-edge) [266.443519] Starting subtest: 128x128-top-edge [266.446761] Starting dynamic subtest: pipe-A-eDP-1 [269.971866] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.531s) [269.983614] Starting dynamic subtest: pipe-D-eDP-1 [273.207920] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.234s) [273.210459] Subtest 128x128-top-edge: SUCCESS (6.769s) [274.092819] [039/143] (758s left) kms_dirtyfb (psr-dirtyfb-ioctl) [274.162766] Starting subtest: psr-dirtyfb-ioctl [274.165159] Starting dynamic subtest: A-eDP-1 [275.887876] Dynamic subtest A-eDP-1: SUCCESS (1.725s) [275.890631] Subtest psr-dirtyfb-ioctl: SUCCESS (1.726s) [276.780670] [040/143] (755s left) gem_softpin (invalid) [276.833490] Starting subtest: invalid [276.839606] Subtest invalid: SUCCESS (0.006s) [276.910498] [041/143] (755s left) syncobj_timeline (multi-wait-available-signaled) [276.968964] Starting subtest: multi-wait-available-signaled [276.971982] Subtest multi-wait-available-signaled: SUCCESS (0.000s) [276.990895] [042/143] (755s left) kms_chamelium_frames (hdmi-crc-fast) [277.344279] Subtest hdmi-crc-fast: SKIP (0.000s) [278.241414] [043/143] (754s left) kms_rotation_crc (primary-rotation-180) [278.289644] Starting subtest: primary-rotation-180 [286.545304] Subtest primary-rotation-180: SUCCESS (8.258s) [287.789325] [044/143] (744s left) gem_userptr_blits (stress-purge) [287.885706] Starting subtest: stress-purge [293.277660] Subtest stress-purge: SUCCESS (5.392s) [293.359998] [045/143] (739s left) perf_pmu (multi-client) [293.458064] Starting subtest: multi-client [293.460394] Starting dynamic subtest: rcs0 [294.518665] Dynamic subtest rcs0: SUCCESS (1.059s) [294.537175] Starting dynamic subtest: bcs0 [295.578460] Dynamic subtest bcs0: SUCCESS (1.057s) [295.596762] Starting dynamic subtest: vcs0 [296.635065] Dynamic subtest vcs0: SUCCESS (1.056s) [296.653564] Starting dynamic subtest: vcs1 [297.983551] Dynamic subtest vcs1: SUCCESS (1.348s) [298.002128] Starting dynamic subtest: vecs0 [299.973535] Dynamic subtest vecs0: SUCCESS (1.989s) [299.991328] Starting dynamic subtest: ccs0 [301.954953] Dynamic subtest ccs0: SUCCESS (1.981s) [301.957854] Subtest multi-client: SUCCESS (8.496s) [302.043999] [046/143] (730s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180) [302.154867] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180 [303.298985] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.145s) [303.447867] [047/143] (729s left) kms_big_fb (linear-32bpp-rotate-270) [303.544909] Starting subtest: linear-32bpp-rotate-270 [303.562821] Subtest linear-32bpp-rotate-270: SKIP (0.018s) [303.637801] [048/143] (729s left) gem_exec_schedule (semaphore-noskip) [303.732412] Starting subtest: semaphore-noskip [304.255464] Subtest semaphore-noskip: SUCCESS (0.521s) [304.561514] [049/143] (728s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [304.667530] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [305.415040] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.749s) [305.599666] [050/143] (727s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [305.660435] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [305.662978] Starting dynamic subtest: pipe-A-default-mode [305.964625] Dynamic subtest pipe-A-default-mode: SKIP (0.300s) [305.970218] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.302s) [306.861922] [051/143] (726s left) i915_suspend (basic-s2idle-without-i915) [306.915932] Starting subtest: basic-s2idle-without-i915 [325.273680] Subtest basic-s2idle-without-i915: SUCCESS (3.400s) [325.326574] [052/143] (722s left) gem_exec_params (rsvd2-dirt) [325.368401] Starting subtest: rsvd2-dirt [325.372996] Subtest rsvd2-dirt: SKIP (0.000s) [325.442712] [053/143] (722s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt) [325.535555] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt [325.539560] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [325.587857] [054/143] (722s left) gen9_exec_parse (secure-batches) [325.634913] Subtest secure-batches: SKIP (0.000s) [325.662529] [055/143] (722s left) gem_exec_flush (basic-uc-pro-default) [325.723960] Starting subtest: basic-uc-pro-default [331.188245] Subtest basic-uc-pro-default: SUCCESS (5.466s) [331.353805] [056/143] (716s left) gem_mmap (bad-object) [331.424501] Subtest bad-object: SKIP (0.000s) [331.464011] [057/143] (716s left) gem_ctx_isolation (preservation-s3) [331.533397] Starting subtest: preservation-S3 [331.535965] Starting dynamic subtest: rcs0 [347.325887] Dynamic subtest rcs0: SUCCESS (1.316s) [347.346306] Starting dynamic subtest: bcs0 [363.285646] Dynamic subtest bcs0: SUCCESS (1.280s) [363.297299] Starting dynamic subtest: vcs0 [379.293552] Dynamic subtest vcs0: SUCCESS (2.859s) [379.300413] Starting dynamic subtest: vcs1 [397.285295] Dynamic subtest vcs1: SUCCESS (4.969s) [397.302499] Starting dynamic subtest: vecs0 [415.294436] Dynamic subtest vecs0: SUCCESS (5.006s) [415.310448] Starting dynamic subtest: ccs0 [433.410186] Dynamic subtest ccs0: SUCCESS (3.320s) [433.412923] Subtest preservation-S3: SUCCESS (18.754s) [433.607699] [058/143] (697s left) kms_ccs (pipe-a-crc-sprite-planes-basic-yf-tiled-ccs) [434.882367] Starting subtest: pipe-A-crc-sprite-planes-basic-yf-tiled-ccs [434.887335] Subtest pipe-A-crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.002s) [435.215389] [059/143] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff) [435.269569] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff [435.274278] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [435.312839] [060/143] (696s left) gem_exec_alignment (pi-isolated) [435.367765] Starting subtest: pi-isolated [447.403405] Killing the test because the kernel is tainted. [450.991383] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1832 [max: 8192] direct dependencies: 19166 [max: 32768] indirect dependencies: 152651 all direct dependencies: 391642 dependency chains: 27956 [max: 131072] dependency chain hlocks used: 119639 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 251 in-softirq chains: 623 in-process chains: 27082 stack-trace entries: 203818 [max: 1048576] number of stack traces: 10024 number of stack hash chains: 7516 combined max dependencies: 4258747584 hardirq-safe locks: 108 hardirq-unsafe locks: 1102 softirq-safe locks: 211 softirq-unsafe locks: 1015 irq-safe locks: 230 irq-unsafe locks: 1102 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 307 softirq-read-safe locks: 7 softirq-read-unsafe locks: 302 irq-read-safe locks: 7 irq-read-unsafe locks: 307 uncategorized locks: 308 unused locks: 1 max locking depth: 16 max bfs queue depth: 429 max lock class index: 1845 chain lookup misses: 34069 chain lookup hits: 577811009 cyclic checks: 34335 redundant checks: 0 redundant links: 0 find-mask forwards checks: 5570 find-mask backwards checks: 2996 hardirq on events: 490532532 hardirq off events: 490532510 redundant hardirq ons: 132 redundant hardirq offs: 26 softirq on events: 516058 softirq off events: 516058 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 190 zapped lock chains: 5630 large chain blocks: 1 [450.994775] Closing watchdogs Done.