[39.619060] Initializing watchdogs [39.619100] /dev/watchdog0 [39.623503] [001/139] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt) [39.694350] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt [39.696473] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [39.774387] [002/139] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move) [39.837646] Starting subtest: psr-1p-primscrn-cur-indfb-move [45.199531] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (5.362s) [45.272282] [003/139] (954s left) kms_fbcon_fbt (fbc) [45.320984] Starting subtest: fbc [50.793941] Subtest fbc: SUCCESS (5.473s) [50.855407] [004/139] (949s left) kms_ccs (pipe-a-bad-pixel-format-y-tiled-gen12-rc-ccs-cc) [50.928786] Starting subtest: pipe-A-bad-pixel-format-y-tiled-gen12-rc-ccs-cc [50.931110] Subtest pipe-A-bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [52.087420] [005/139] (948s left) perf (mi-rpc) [52.210176] Starting subtest: mi-rpc [52.212492] Subtest mi-rpc: SKIP (0.000s) [52.234813] [006/139] (947s left) v3d/v3d_perfmon (get-values-valid-perfmon) [52.299734] Subtest get-values-valid-perfmon: SKIP (0.000s) [52.340239] [007/139] (947s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-rc-ccs) [52.699275] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs [52.701403] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s) [53.574280] [008/139] (946s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0) [53.651499] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0 [53.653614] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [53.730704] [009/139] (946s left) kms_getfb (getfb2-handle-protection) [53.773245] Starting subtest: getfb2-handle-protection [53.791169] Subtest getfb2-handle-protection: SUCCESS (0.018s) [53.876232] [010/139] (946s left) kms_cursor_crc (cursor-offscreen-128x42) [53.999758] Starting subtest: cursor-offscreen-128x42 [54.002134] Starting dynamic subtest: pipe-A-eDP-1 [56.092896] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.089s) [56.121276] Starting dynamic subtest: pipe-D-eDP-1 [58.822690] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.709s) [58.824283] Subtest cursor-offscreen-128x42: SUCCESS (4.822s) [59.711298] [011/139] (940s left) gem_ctx_exec (basic) [59.749576] Starting subtest: basic [59.750717] Subtest basic: SUCCESS (0.002s) [59.840182] [012/139] (940s left) gem_exec_schedule (u-fairslice-all) [59.907862] Starting subtest: u-fairslice-all [61.966481] Subtest u-fairslice-all: SUCCESS (2.058s) [63.525499] [013/139] (936s left) syncobj_basic (bad-destroy) [63.572867] Starting subtest: bad-destroy [63.574242] Subtest bad-destroy: SUCCESS (0.000s) [63.622290] [014/139] (936s left) v3d/v3d_wait_bo (map-bo-0ns) [63.714351] Subtest map-bo-0ns: SKIP (0.000s) [63.758765] [015/139] (936s left) gem_close (many-handles-one-vma) [63.828067] Starting subtest: many-handles-one-vma [63.872123] Subtest many-handles-one-vma: SUCCESS (0.044s) [63.941914] [016/139] (936s left) kms_psr2_sf (overlay-plane-move-continuous-sf) [69.119443] Starting subtest: overlay-plane-move-continuous-sf [69.120820] Starting dynamic subtest: pipe-A-eDP-1 [75.878244] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.762s) [75.888736] Starting dynamic subtest: pipe-B-eDP-1 [82.674231] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.794s) [82.675329] Subtest overlay-plane-move-continuous-sf: SUCCESS (13.560s) [83.579553] [017/139] (916s left) syncobj_timeline (multi-wait-all-signaled) [83.619266] Starting subtest: multi-wait-all-signaled [83.620657] Subtest multi-wait-all-signaled: SUCCESS (0.001s) [83.676628] [018/139] (916s left) kms_properties (plane-properties-legacy) [83.734943] Starting subtest: plane-properties-legacy [88.329970] Subtest plane-properties-legacy: SUCCESS (4.595s) [89.223360] [019/139] (911s left) kms_frontbuffer_tracking (fbcpsr-slowdraw) [89.304715] Starting subtest: fbcpsr-slowdraw [112.319240] Subtest fbcpsr-slowdraw: SUCCESS (23.016s) [112.439511] [020/139] (888s left) kms_draw_crc (draw-method-mmap-gtt) [112.516087] Starting subtest: draw-method-mmap-gtt [112.518462] Subtest draw-method-mmap-gtt: SKIP (0.000s) [112.590284] [021/139] (887s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted) [115.169083] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s) [115.225271] [022/139] (885s left) kms_vblank (ts-continuation-modeset) [115.301745] Starting subtest: ts-continuation-modeset [115.302921] Starting dynamic subtest: pipe-A-eDP-1 [116.851644] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.550s) [116.864107] Starting dynamic subtest: pipe-D-eDP-1 [119.221023] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.362s) [119.222180] Subtest ts-continuation-modeset: SUCCESS (3.919s) [120.121590] [023/139] (880s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-rc-ccs) [120.543143] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-rc-ccs [120.547253] Subtest pipe-C-random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.000s) [121.415349] [024/139] (879s left) perf (blocking) [121.508640] Starting subtest: blocking [121.510418] Starting dynamic subtest: 0-rcs0 [131.535967] Dynamic subtest 0-rcs0: SUCCESS (10.025s) [131.544848] Starting dynamic subtest: 1-vcs1 [141.558280] Dynamic subtest 1-vcs1: SUCCESS (10.022s) [141.559727] Subtest blocking: SUCCESS (20.048s) [141.589167] [025/139] (859s left) kms_properties (connector-properties-legacy) [141.659153] Starting subtest: connector-properties-legacy [150.162332] Subtest connector-properties-legacy: SUCCESS (8.503s) [151.034570] [026/139] (849s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [151.118182] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [151.119956] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [151.197656] [027/139] (849s left) dumb_buffer (map-uaf) [151.251062] Starting subtest: map-uaf [151.254199] Subtest map-uaf: SUCCESS (0.007s) [151.337555] [028/139] (849s left) kms_ccs (pipe-d-random-ccs-data-y-tiled-gen12-mc-ccs) [151.773770] Starting subtest: pipe-D-random-ccs-data-y-tiled-gen12-mc-ccs [151.777319] Subtest pipe-D-random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.000s) [152.648820] [029/139] (848s left) kms_ccs (pipe-d-crc-primary-rotation-180-yf-tiled-ccs) [153.043552] Starting subtest: pipe-D-crc-primary-rotation-180-yf-tiled-ccs [153.046336] Subtest pipe-D-crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.000s) [153.927106] [030/139] (846s left) kms_pm_rpm (legacy-planes) [155.634749] Starting subtest: legacy-planes [155.636438] Starting dynamic subtest: plane-40 [183.561055] Dynamic subtest plane-40: SUCCESS (27.926s) [183.571182] Starting dynamic subtest: plane-49 [207.792647] Dynamic subtest plane-49: SUCCESS (24.230s) [207.802901] Starting dynamic subtest: plane-58 [235.924558] Dynamic subtest plane-58: SUCCESS (28.129s) [235.934421] Starting dynamic subtest: plane-67 [264.015419] Dynamic subtest plane-67: SUCCESS (28.091s) [264.017043] Subtest legacy-planes: SUCCESS (108.381s) [264.811953] [031/139] (735s left) v3d/v3d_submit_csd (multi-and-single-sync) [264.870585] Subtest multi-and-single-sync: SKIP (0.000s) [264.912688] [032/139] (735s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt) [264.985874] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt [264.987580] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [265.064647] [033/139] (735s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt) [265.127315] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt [265.129650] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [265.201115] [034/139] (735s left) gem_exec_balancer (nohangcheck) [265.447889] Starting subtest: nohangcheck [266.029601] Subtest nohangcheck: SUCCESS (0.583s) [266.125385] [035/139] (734s left) kms_chamelium_hpd (dp-hpd-after-suspend) [266.492661] Subtest dp-hpd-after-suspend: SKIP (0.000s) [267.360330] [036/139] (733s left) sw_sync (sync_busy_fork_unixsocket) [267.407474] Starting subtest: sync_busy_fork_unixsocket [267.417753] Subtest sync_busy_fork_unixsocket: SUCCESS (0.014s) [267.455851] [037/139] (733s left) gem_lmem_swapping (verify-random) [267.491650] Subtest verify-random: SKIP (0.000s) [267.514687] [038/139] (733s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt) [267.559652] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt [267.561800] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [267.643571] [039/139] (733s left) gem_reg_read (timestamp-monotonic) [267.682326] Starting subtest: timestamp-monotonic [272.411896] Subtest timestamp-monotonic: SUCCESS (4.729s) [272.459155] [040/139] (728s left) gem_unref_active_buffers [272.649467] [041/139] (728s left) gem_exec_whisper (basic-contexts-forked-all) [272.696233] Starting subtest: basic-contexts-forked-all [306.056780] Subtest basic-contexts-forked-all: SUCCESS (33.357s) [306.230273] [042/139] (694s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc) [306.286784] Starting subtest: psr-rgb565-draw-mmap-wc [311.168633] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (4.883s) [311.290380] [043/139] (689s left) gem_exec_async (forked-writes) [311.359343] Starting subtest: forked-writes [311.361182] Starting dynamic subtest: rcs0 [311.389082] Dynamic subtest rcs0: SUCCESS (0.029s) [311.402926] Starting dynamic subtest: bcs0 [311.430498] Dynamic subtest bcs0: SUCCESS (0.040s) [311.441502] Starting dynamic subtest: vcs0 [311.455890] Dynamic subtest vcs0: SUCCESS (0.025s) [311.467409] Starting dynamic subtest: vcs1 [311.479773] Dynamic subtest vcs1: SUCCESS (0.023s) [311.487948] Starting dynamic subtest: vecs0 [311.500764] Dynamic subtest vecs0: SUCCESS (0.021s) [311.507417] Starting dynamic subtest: ccs0 [311.532406] Dynamic subtest ccs0: SUCCESS (0.031s) [311.533532] Subtest forked-writes: SUCCESS (0.174s) [311.736241] [044/139] (689s left) kms_ccs (pipe-c-bad-rotation-90-yf-tiled-ccs) [312.151753] Starting subtest: pipe-C-bad-rotation-90-yf-tiled-ccs [312.154165] Subtest pipe-C-bad-rotation-90-yf-tiled-ccs: SKIP (0.000s) [313.030262] [045/139] (687s left) kms_invalid_mode (bad-hsync-end) [313.081970] Starting subtest: bad-hsync-end [313.083280] Starting dynamic subtest: pipe-A-eDP-1 [313.363506] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s) [313.376465] Starting dynamic subtest: pipe-B-eDP-1 [313.377617] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [313.386722] Starting dynamic subtest: pipe-C-eDP-1 [313.387867] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [313.394501] Starting dynamic subtest: pipe-D-eDP-1 [313.395535] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [313.396548] Subtest bad-hsync-end: SUCCESS (0.293s) [314.249077] [046/139] (686s left) gem_exec_params (rel-constants-invalid-rel-gen5) [314.304484] Starting subtest: rel-constants-invalid-rel-gen5 [314.305539] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s) [314.382949] [047/139] (686s left) drm_fdinfo (all-busy-check-all) [314.431827] Starting subtest: all-busy-check-all [314.434057] Subtest all-busy-check-all: SKIP (0.002s) [314.473829] [048/139] (686s left) kms_flip (2x-flip-vs-suspend-interruptible) [314.566452] Starting subtest: 2x-flip-vs-suspend-interruptible [314.585606] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.021s) [314.648471] [049/139] (686s left) api_intel_bb (purge-bb) [314.694861] Starting subtest: purge-bb [314.695999] Subtest purge-bb: SUCCESS (0.001s) [314.789885] [050/139] (686s left) gem_mmap_wc (write-wc-read-gtt) [314.829643] Subtest write-wc-read-gtt: SKIP (0.000s) [314.860972] [051/139] (686s left) vc4/vc4_wait_bo (used-bo) [314.940242] Subtest used-bo: SKIP (0.000s) [314.985724] [052/139] (686s left) gem_ctx_exec (basic-close-race) [315.026955] Starting subtest: basic-close-race [320.496496] Subtest basic-close-race: SUCCESS (5.469s) [322.419147] [053/139] (678s left) kms_chamelium_edid (vga-edid-read) [322.773220] Subtest vga-edid-read: SKIP (0.000s) [323.614472] [054/139] (677s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs) [323.980799] Starting subtest: pipe-A-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs [323.983728] Subtest pipe-A-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s) [324.873746] [055/139] (676s left) i915_pciid [324.971373] [056/139] (676s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-ccs) [325.365599] Starting subtest: pipe-D-crc-primary-basic-y-tiled-ccs [325.368251] Subtest pipe-D-crc-primary-basic-y-tiled-ccs: SKIP (0.000s) [326.219505] [057/139] (675s left) gem_close_race (multigpu-basic-threads) [326.288884] Starting subtest: multigpu-basic-threads [326.291304] Subtest multigpu-basic-threads: SKIP (0.000s) [326.499102] [058/139] (674s left) kms_ccs (pipe-b-crc-sprite-planes-basic-yf-tiled-ccs) [326.897234] Starting subtest: pipe-B-crc-sprite-planes-basic-yf-tiled-ccs [326.899925] Subtest pipe-B-crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.001s) [327.723940] [059/139] (673s left) i915_query (query-garbage) [327.761324] Starting subtest: query-garbage [327.762464] Subtest query-garbage: SUCCESS (0.000s) [327.818373] [060/139] (673s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary) [327.875317] Starting subtest: fbcpsr-indfb-scaledprimary [333.718646] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.845s) [333.828811] [061/139] (667s left) kms_flip (2x-flip-vs-blocking-wf-vblank) [333.884997] Starting subtest: 2x-flip-vs-blocking-wf-vblank [333.917986] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.033s) [333.986278] [062/139] (667s left) kms_ccs (pipe-a-random-ccs-data-4-tiled-dg2-rc-ccs-cc) [334.355320] Starting subtest: pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs-cc [334.358873] Subtest pipe-A-random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s) [335.225145] [063/139] (666s left) i915_pm_rps (reset) [335.277429] Starting subtest: reset [335.307923] Subtest reset: FAIL (0.019s) [335.329588] [064/139] (666s left) syncobj_timeline (wait-all-complex) [335.372698] Starting subtest: wait-all-complex [335.590298] Subtest wait-all-complex: SUCCESS (0.213s) [335.650557] [065/139] (665s left) kms_cursor_crc (cursor-random-512x170) [335.822956] Starting subtest: cursor-random-512x170 [335.825322] Subtest cursor-random-512x170: SKIP (0.001s) [335.933186] [066/139] (665s left) syncobj_timeline (etime-multi-wait-all-submitted) [335.986306] Starting subtest: etime-multi-wait-all-submitted [336.094342] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s) [336.157962] [067/139] (665s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [336.243922] Starting subtest: extended-pageflip-modeset-hang-oldfb [336.245161] Starting dynamic subtest: pipe-A [344.152021] Dynamic subtest pipe-A: SUCCESS (7.909s) [344.168326] Starting dynamic subtest: pipe-D [352.892827] Dynamic subtest pipe-D: SUCCESS (8.731s) [352.894199] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.648s) [353.803726] [068/139] (647s left) kms_big_fb (4-tiled-8bpp-rotate-180) [353.868124] Starting subtest: 4-tiled-8bpp-rotate-180 [355.018862] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.144s) [355.123771] [069/139] (646s left) syncobj_timeline (host-signal-ordered) [355.176260] Starting subtest: host-signal-ordered [355.177408] Subtest host-signal-ordered: SUCCESS (0.000s) [355.236801] [070/139] (646s left) gem_ctx_persistence (smoketest) [355.282927] Starting subtest: smoketest [386.482574] Subtest smoketest: SUCCESS (31.198s) [386.600879] [071/139] (614s left) perf (gen12-oa-tlb-invalidate) [386.698569] Starting subtest: gen12-oa-tlb-invalidate [386.699980] Starting dynamic subtest: 0-rcs0 [396.741457] Dynamic subtest 0-rcs0: SUCCESS (10.041s) [396.748996] Starting dynamic subtest: 1-vcs0 [406.788982] Dynamic subtest 1-vcs0: SUCCESS (10.046s) [406.790348] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.088s) [406.822312] [072/139] (594s left) kms_async_flips (crc) [406.917789] Starting subtest: crc [407.287284] Starting dynamic subtest: pipe-A-eDP-1 [410.528710] Dynamic subtest pipe-A-eDP-1: FAIL (3.171s) [410.844847] Starting dynamic subtest: pipe-B-eDP-1 [414.093356] Dynamic subtest pipe-B-eDP-1: FAIL (2.997s) [414.413813] Starting dynamic subtest: pipe-C-eDP-1 [417.651954] Dynamic subtest pipe-C-eDP-1: FAIL (3.175s) [417.974454] Starting dynamic subtest: pipe-D-eDP-1 [421.233779] Dynamic subtest pipe-D-eDP-1: FAIL (3.196s) [421.234886] Subtest crc: FAIL (14.244s) [422.416762] [073/139] (579s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats) [422.489240] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats [422.490458] Starting dynamic subtest: pipe-A-eDP-1 [422.877076] Dynamic subtest pipe-A-eDP-1: SKIP (0.386s) [422.886935] Starting dynamic subtest: pipe-B-eDP-1 [424.379597] Dynamic subtest pipe-B-eDP-1: SKIP (1.503s) [424.386214] Starting dynamic subtest: pipe-C-eDP-1 [425.724197] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.345s) [425.735613] Starting dynamic subtest: pipe-D-eDP-1 [427.078494] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.352s) [427.079484] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (4.595s) [428.361557] [074/139] (573s left) api_intel_bb (lot-of-buffers) [428.415522] Starting subtest: lot-of-buffers [428.945265] Subtest lot-of-buffers: SUCCESS (0.529s) [429.061192] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1787 [max: 8192] direct dependencies: 17556 [max: 32768] indirect dependencies: 121297 all direct dependencies: 385636 dependency chains: 25800 [max: 131072] dependency chain hlocks used: 107569 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 295 in-softirq chains: 601 in-process chains: 24904 stack-trace entries: 170831 [max: 1048576] number of stack traces: 8559 number of stack hash chains: 6691 combined max dependencies: 142904464 hardirq-safe locks: 111 hardirq-unsafe locks: 1070 softirq-safe locks: 211 softirq-unsafe locks: 980 irq-safe locks: 233 irq-unsafe locks: 1070 hardirq-read-safe locks: 4 hardirq-read-unsafe locks: 318 softirq-read-safe locks: 7 softirq-read-unsafe locks: 313 irq-read-safe locks: 8 irq-read-unsafe locks: 318 uncategorized locks: 281 unused locks: 1 max locking depth: 16 max bfs queue depth: 389 max lock class index: 1786 chain lookup misses: 26360 chain lookup hits: 342996825 cyclic checks: 26130 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4435 find-mask backwards checks: 2683 hardirq on events: 186437426 hardirq off events: 186437404 redundant hardirq ons: 160 redundant hardirq offs: 38 softirq on events: 1167202 softirq off events: 1167202 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 1 zapped lock chains: 98 large chain blocks: 1 [429.062588] Closing watchdogs Done.