[46.248661] Initializing watchdogs [46.248745] /dev/watchdog0 [46.255326] [001/137] (960s left) kms_vblank (pipe-b-ts-continuation-suspend) [48.341599] Starting subtest: pipe-B-ts-continuation-suspend [69.049980] Subtest pipe-B-ts-continuation-suspend: SUCCESS (9.578s) [74.614280] [002/137] (948s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_ccs) [77.006621] Starting subtest: pipe-D-crc-sprite-planes-basic-y_tiled_ccs [77.011673] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.003s) [77.106334] [003/137] (946s left) kms_big_fb (x-tiled-64bpp-rotate-180) [79.098886] Starting subtest: x-tiled-64bpp-rotate-180 [81.493442] Subtest x-tiled-64bpp-rotate-180: SUCCESS (2.392s) [81.646791] [004/137] (941s left) kms_big_fb (y-tiled-32bpp-rotate-180) [83.646428] Starting subtest: y-tiled-32bpp-rotate-180 [85.442495] Subtest y-tiled-32bpp-rotate-180: SUCCESS (1.794s) [85.546690] [005/137] (937s left) gem_exec_capture (capture-invisible) [85.644551] Starting subtest: capture-invisible [85.646363] Starting dynamic subtest: lmem0 [85.650417] Dynamic subtest lmem0: SKIP (0.000s) [85.657020] Starting dynamic subtest: smem0 [85.659954] Dynamic subtest smem0: SKIP (0.000s) [85.662979] Subtest capture-invisible: SKIP (0.000s) [85.733500] [006/137] (937s left) gem_exec_fence (syncobj-channel) [86.185459] Starting subtest: syncobj-channel [87.217677] Subtest syncobj-channel: SUCCESS (1.026s) [87.588173] [007/137] (935s left) drm_fdinfo (virtual-busy) [87.692675] Subtest virtual-busy: SKIP (0.000s) [87.714738] [008/137] (935s left) i915_hangman (engine-error-state-capture) [87.844334] Starting subtest: engine-error-state-capture [87.845816] Starting dynamic subtest: rcs0 [90.070952] Dynamic subtest rcs0: SUCCESS (2.227s) [90.078166] Starting dynamic subtest: bcs0 [94.041972] Dynamic subtest bcs0: SUCCESS (3.971s) [94.049326] Starting dynamic subtest: vcs0 [98.009024] Dynamic subtest vcs0: SUCCESS (3.967s) [98.016435] Starting dynamic subtest: vcs1 [102.042060] Dynamic subtest vcs1: SUCCESS (4.033s) [102.049946] Starting dynamic subtest: vecs0 [106.004119] Dynamic subtest vecs0: SUCCESS (3.962s) [106.005776] Subtest engine-error-state-capture: SUCCESS (18.160s) [106.129846] [009/137] (917s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu) [108.123410] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu [110.520971] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.402s) [110.670891] [010/137] (912s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [110.741224] Starting subtest: multi-wait-for-submit-available-signaled [110.742627] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [110.823786] [011/137] (912s left) kms_async_flips (test-cursor) [112.812507] Starting subtest: test-cursor [112.814355] Starting dynamic subtest: pipe-A-HDMI-A-4 [114.762631] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (1.950s) [114.768942] Starting dynamic subtest: pipe-B-HDMI-A-4 [116.534775] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (1.771s) [116.541174] Starting dynamic subtest: pipe-C-HDMI-A-4 [117.923554] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (1.388s) [117.930335] Starting dynamic subtest: pipe-D-HDMI-A-4 [118.936480] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (1.011s) [118.937352] Subtest test-cursor: SUCCESS (6.126s) [119.615668] [012/137] (903s left) syncobj_timeline (device-signal-unordered) [119.690068] Starting subtest: device-signal-unordered [119.691054] Subtest device-signal-unordered: SUCCESS (0.001s) [119.750690] [013/137] (903s left) kms_psr2_sf (cursor-plane-move-continuous-sf) [119.820351] Subtest cursor-plane-move-continuous-sf: SKIP (0.000s) [119.840607] [014/137] (903s left) api_intel_bb (render) [119.909756] Starting subtest: render [119.910665] Starting dynamic subtest: render-none-512 [119.943679] Dynamic subtest render-none-512: SUCCESS (0.034s) [119.950135] Starting dynamic subtest: render-none-1024 [120.000009] Dynamic subtest render-none-1024: SUCCESS (0.056s) [120.006486] Starting dynamic subtest: render-x-512 [120.017435] Dynamic subtest render-x-512: SUCCESS (0.017s) [120.023757] Starting dynamic subtest: render-x-1024 [120.085059] Dynamic subtest render-x-1024: SUCCESS (0.067s) [120.091515] Starting dynamic subtest: render-y-512 [120.101447] Dynamic subtest render-y-512: SUCCESS (0.016s) [120.107881] Starting dynamic subtest: render-y-1024 [120.165906] Dynamic subtest render-y-1024: SUCCESS (0.064s) [120.166838] Subtest render: SUCCESS (0.257s) [120.264802] [015/137] (903s left) kms_plane_multiple (tiling-y) [122.240883] Starting subtest: tiling-y [122.241920] Starting dynamic subtest: pipe-A-HDMI-A-4 [122.907070] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.667s) [122.915115] Starting dynamic subtest: pipe-B-HDMI-A-4 [123.590649] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.681s) [123.598627] Starting dynamic subtest: pipe-C-HDMI-A-4 [124.215238] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.622s) [124.223328] Starting dynamic subtest: pipe-D-HDMI-A-4 [124.820824] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.601s) [124.822946] Subtest tiling-y: SUCCESS (2.580s) [124.930287] [016/137] (898s left) gem_render_copy (y-tiled-ccs-to-yf-tiled) [125.007020] Starting subtest: y-tiled-ccs-to-yf-tiled [125.008035] Starting dynamic subtest: smem [125.091869] Dynamic subtest smem: SUCCESS (0.084s) [125.098411] Starting dynamic subtest: lmem0 [126.027522] Dynamic subtest lmem0: SUCCESS (0.932s) [126.028422] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (1.017s) [126.214021] [017/137] (897s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt) [128.195577] Starting subtest: psr-2p-primscrn-indfb-msflip-blt [128.197573] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [128.280134] [018/137] (895s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_gen12_rc_ccs) [130.463946] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs [130.465016] Subtest pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs: SUCCESS (0.000s) [130.790924] [019/137] (892s left) gem_exec_params (no-blt) [130.863469] Starting subtest: no-blt [130.865757] Subtest no-blt: SUCCESS (0.001s) [130.957359] [020/137] (892s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs) [133.262714] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs [133.266976] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.003s) [133.462559] [021/137] (890s left) gem_exec_params (rs-invalid) [133.534375] Starting subtest: rs-invalid [133.536712] Subtest rs-invalid: SUCCESS (0.002s) [133.645362] [022/137] (890s left) kms_vblank (pipe-d-wait-forked-busy) [135.620272] Starting subtest: pipe-D-wait-forked-busy [139.190889] Subtest pipe-D-wait-forked-busy: SUCCESS (3.571s) [139.295006] [023/137] (884s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [141.277350] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt [141.279494] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [141.361832] [024/137] (882s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_ccs) [143.528044] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_ccs [143.529978] Subtest pipe-B-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.000s) [143.878178] [025/137] (879s left) prime_mmap (test_aperture_limit) [143.946768] Starting subtest: test_aperture_limit [143.947702] Starting dynamic subtest: test_aperture_limit-smem [149.399191] Dynamic subtest test_aperture_limit-smem: SUCCESS (5.449s) [149.400170] Subtest test_aperture_limit: SUCCESS (5.450s) [151.543457] [026/137] (872s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff) [153.525642] Starting subtest: psr-2p-primscrn-spr-indfb-onoff [153.527567] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [153.611018] [027/137] (870s left) kms_cursor_legacy (torture-bo) [155.608531] Starting subtest: torture-bo [155.609676] Starting dynamic subtest: pipe-A [177.410431] Dynamic subtest pipe-A: SUCCESS (21.800s) [177.417047] Starting dynamic subtest: pipe-B [199.224457] Dynamic subtest pipe-B: SUCCESS (21.813s) [199.231050] Starting dynamic subtest: pipe-C [221.134665] Dynamic subtest pipe-C: SUCCESS (21.910s) [221.141439] Starting dynamic subtest: pipe-D [242.899084] Dynamic subtest pipe-D: SUCCESS (21.764s) [242.905705] Starting dynamic subtest: all-pipes [264.523572] Dynamic subtest all-pipes: SUCCESS (21.624s) [264.524535] Subtest torture-bo: SUCCESS (108.913s) [264.597497] [028/137] (759s left) gem_mmap_wc (bad-offset) [264.755980] Subtest bad-offset: SKIP (0.000s) [264.789685] [029/137] (759s left) perf (gen12-mi-rpc) [264.927819] Starting subtest: gen12-mi-rpc [264.928740] Starting dynamic subtest: rcs0 [264.965395] Dynamic subtest rcs0: SUCCESS (0.037s) [264.966403] Subtest gen12-mi-rpc: SUCCESS (0.038s) [265.000986] [030/137] (758s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180) [267.023120] Starting subtest: linear-max-hw-stride-64bpp-rotate-180 [268.117928] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.091s) [268.234812] [031/137] (755s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [270.217885] Starting subtest: yf-tiled-16bpp-rotate-180 [270.220047] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s) [270.315689] [032/137] (753s left) api_intel_bb (object-noreloc-keep-cache-simple) [270.417269] Starting subtest: object-noreloc-keep-cache-simple [270.434157] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.017s) [270.521867] [033/137] (753s left) gem_ctx_shared (create-shared-gtt) [270.619584] Starting subtest: create-shared-gtt [272.772170] Subtest create-shared-gtt: SUCCESS (2.149s) [274.131623] [034/137] (749s left) syncobj_timeline (etime-single-wait-all-submitted) [274.200600] Starting subtest: etime-single-wait-all-submitted [274.309345] Subtest etime-single-wait-all-submitted: SUCCESS (0.104s) [274.413093] [035/137] (749s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt) [276.422900] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt [276.424931] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s) [276.496582] [036/137] (747s left) kms_flip (2x-busy-flip) [277.549627] Starting subtest: 2x-busy-flip [277.575287] Subtest 2x-busy-flip: SKIP (0.025s) [277.648143] [037/137] (746s left) gem_ringfill (basic-all) [278.099601] Starting subtest: basic-all [279.440145] Subtest basic-all: SUCCESS (1.338s) [279.556662] [038/137] (744s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling) [281.561690] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling [281.562619] Starting dynamic subtest: pipe-A-valid-mode [282.150664] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.581s) [282.151588] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SUCCESS (0.583s) [282.271352] [039/137] (741s left) kms_atomic (crtc-invalid-params-fence) [284.467075] Starting subtest: crtc-invalid-params-fence [284.588153] Subtest crtc-invalid-params-fence: SUCCESS (0.122s) [284.753790] [040/137] (739s left) kms_hdr (static-toggle-suspend) [286.731269] Starting subtest: static-toggle-suspend [286.733518] Subtest static-toggle-suspend: SKIP (0.000s) [286.787587] [041/137] (737s left) kms_lease (empty_lease) [288.783041] Starting subtest: empty_lease [288.784081] Starting dynamic subtest: pipe-A-HDMI-A-4 [288.785063] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.001s) [288.793929] Starting dynamic subtest: pipe-B-HDMI-A-4 [288.794825] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.001s) [288.801642] Starting dynamic subtest: pipe-C-HDMI-A-4 [288.802573] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.001s) [288.809076] Starting dynamic subtest: pipe-D-HDMI-A-4 [288.810068] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.001s) [288.811085] Subtest empty_lease: SUCCESS (0.011s) [288.853562] [042/137] (735s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt) [290.855743] Starting subtest: psr-rgb101010-draw-blt [290.857808] Subtest psr-rgb101010-draw-blt: SKIP (0.000s) [290.919765] [043/137] (733s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [290.998177] Starting subtest: single-wait-all-for-submit-available-submitted [290.999307] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s) [291.065158] [044/137] (733s left) kms_addfb_basic (bad-pitch-65536) [291.160670] Starting subtest: bad-pitch-65536 [291.161692] Subtest bad-pitch-65536: SUCCESS (0.000s) [293.152899] [045/137] (731s left) kms_chamelium_color (ctm-max) [295.156058] Subtest ctm-max: SKIP (0.000s) [295.177641] [046/137] (729s left) kms_big_fb (4-tiled-addfb) [297.166404] Starting subtest: 4-tiled-addfb [297.168472] Subtest 4-tiled-addfb: SKIP (0.000s) [297.253016] [047/137] (726s left) gem_exec_schedule (submit-late-slice) [297.373225] Starting subtest: submit-late-slice [297.374269] Starting dynamic subtest: rcs0 [297.435261] Dynamic subtest rcs0: SUCCESS (0.062s) [297.442515] Starting dynamic subtest: bcs0 [297.468965] Dynamic subtest bcs0: SUCCESS (0.033s) [297.475868] Starting dynamic subtest: vcs0 [297.501975] Dynamic subtest vcs0: SUCCESS (0.032s) [297.509025] Starting dynamic subtest: vcs1 [297.536571] Dynamic subtest vcs1: SUCCESS (0.034s) [297.543539] Starting dynamic subtest: vecs0 [297.583936] Dynamic subtest vecs0: SUCCESS (0.047s) [297.585303] Subtest submit-late-slice: SUCCESS (0.211s) [298.024964] [048/137] (726s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt) [300.036600] Starting subtest: fbc-rgb565-draw-mmap-gtt [300.038557] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s) [300.118758] [049/137] (724s left) gem_exec_reloc (basic-wc-read) [300.192734] Subtest basic-wc-read: SKIP (0.000s) [300.227366] [050/137] (724s left) kms_cursor_legacy (short-flip-after-cursor-toggle) [302.204132] Starting subtest: short-flip-after-cursor-toggle [303.197488] Subtest short-flip-after-cursor-toggle: SUCCESS (0.987s) [303.324945] [051/137] (720s left) gem_exec_reloc (basic-cpu-gtt-noreloc) [303.397515] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s) [303.435295] [052/137] (720s left) fbdev (read) [303.507912] Starting subtest: read [304.954310] Subtest read: SUCCESS (1.443s) [305.007485] [053/137] (719s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt) [306.985091] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt [306.987019] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [307.058078] [054/137] (717s left) kms_addfb_basic (bad-pitch-32) [307.140677] Starting subtest: bad-pitch-32 [307.141752] Subtest bad-pitch-32: SUCCESS (0.000s) [309.141331] [055/137] (715s left) gem_exec_balancer (bonded-sync) [309.248955] Subtest bonded-sync: SKIP (0.000s) [309.611990] [056/137] (714s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_mc_ccs) [311.764638] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_mc_ccs [311.767556] Subtest pipe-B-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s) [312.107393] [057/137] (712s left) kms_vblank (pipe-c-wait-forked-hang) [314.086147] Starting subtest: pipe-C-wait-forked-hang [328.992116] Subtest pipe-C-wait-forked-hang: SUCCESS (14.905s) [329.089476] [058/137] (695s left) kms_prime (basic-modeset-hybrid) [331.687491] Subtest basic-modeset-hybrid: SKIP (0.000s) [331.810096] [059/137] (692s left) sw_sync (sync_random_merge) [331.877032] Starting subtest: sync_random_merge [331.928182] Subtest sync_random_merge: SUCCESS (0.051s) [331.976704] [060/137] (692s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [333.966081] Starting subtest: basic-busy-flip-before-cursor-atomic [333.968025] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [334.022247] [061/137] (690s left) gem_partial_pwrite_pread (reads-uncached) [334.100015] Subtest reads-uncached: SKIP (0.000s) [334.148749] [062/137] (690s left) vgem_basic (sysfs) [334.222050] Starting subtest: sysfs [334.223018] Subtest sysfs: SUCCESS (0.000s) [334.273172] [063/137] (690s left) v3d/v3d_submit_csd (job-perfmon) [334.372752] Subtest job-perfmon: SKIP (0.000s) [334.421757] [064/137] (690s left) i915_selftest (mock) [335.923172] Starting subtest: mock [335.924322] Starting dynamic subtest: sanitycheck [336.136257] Dynamic subtest sanitycheck: SUCCESS (0.214s) [336.145145] Starting dynamic subtest: shmem [336.356049] Dynamic subtest shmem: SUCCESS (0.219s) [336.363278] Starting dynamic subtest: fence [343.478895] Dynamic subtest fence: SUCCESS (7.122s) [343.487868] Starting dynamic subtest: scatterlist [344.706380] Dynamic subtest scatterlist: SUCCESS (1.226s) [344.714756] Starting dynamic subtest: syncmap [347.060275] Dynamic subtest syncmap: SUCCESS (2.350s) [347.068148] Starting dynamic subtest: uncore [347.271292] Dynamic subtest uncore: SUCCESS (0.213s) [347.280567] Starting dynamic subtest: ring [347.490075] Dynamic subtest ring: SUCCESS (0.218s) [347.497962] Starting dynamic subtest: engine [347.714264] Dynamic subtest engine: SUCCESS (0.223s) [347.722933] Starting dynamic subtest: timelines [352.224039] Dynamic subtest timelines: SUCCESS (4.506s) [352.231238] Starting dynamic subtest: requests [354.398878] Dynamic subtest requests: SUCCESS (2.174s) [354.405148] Starting dynamic subtest: objects [354.701573] Dynamic subtest objects: SUCCESS (0.302s) [354.714042] Starting dynamic subtest: phys [355.023329] Dynamic subtest phys: SUCCESS (0.320s) [355.039674] Starting dynamic subtest: dmabuf [355.334137] Dynamic subtest dmabuf: SUCCESS (0.310s) [355.343023] Starting dynamic subtest: vma [366.640089] Dynamic subtest vma: SUCCESS (11.305s) [366.646911] Starting dynamic subtest: evict [367.805775] Dynamic subtest evict: FAIL (1.150s) [367.807851] Subtest mock: FAIL (31.870s) [367.992723] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1698 [max: 8192] direct dependencies: 16372 [max: 32768] indirect dependencies: 102024 all direct dependencies: 309562 dependency chains: 22368 [max: 131072] dependency chain hlocks used: 92043 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 172 in-softirq chains: 658 in-process chains: 21538 stack-trace entries: 186770 [max: 1048576] number of stack traces: 9626 number of stack hash chains: 7256 combined max dependencies: 2455596773 hardirq-safe locks: 88 hardirq-unsafe locks: 1011 softirq-safe locks: 180 softirq-unsafe locks: 920 irq-safe locks: 202 irq-unsafe locks: 1011 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 295 softirq-read-safe locks: 8 softirq-read-unsafe locks: 290 irq-read-safe locks: 8 irq-read-unsafe locks: 295 uncategorized locks: 301 unused locks: 1 max locking depth: 15 max bfs queue depth: 432 max lock class index: 1869 chain lookup misses: 28803 chain lookup hits: 289996503 cyclic checks: 28523 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4718 find-mask backwards checks: 3085 hardirq on events: 152781406 hardirq off events: 152781400 redundant hardirq ons: 56 redundant hardirq offs: 18 softirq on events: 386080 softirq off events: 386080 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 460 zapped lock chains: 6320 large chain blocks: 1 [367.993748] Closing watchdogs Done.