[65.383507] Initializing watchdogs [65.383690] /dev/watchdog0 [65.401346] [001/142] (960s left) gem_ctx_persistence (legacy-engines-cleanup) [65.598269] Starting subtest: legacy-engines-cleanup [65.602390] Starting dynamic subtest: render [65.621380] Dynamic subtest render: SUCCESS (0.035s) [65.644174] Starting dynamic subtest: bsd [65.649359] Dynamic subtest bsd: SUCCESS (0.024s) [65.664637] Starting dynamic subtest: blt [65.670924] Dynamic subtest blt: SUCCESS (0.021s) [65.695581] Starting dynamic subtest: vebox [65.699818] Dynamic subtest vebox: SUCCESS (0.018s) [65.703581] Subtest legacy-engines-cleanup: SUCCESS (0.103s) [65.783394] [002/142] (960s left) kms_vblank (pipe-b-query-busy) [66.036198] Starting subtest: pipe-B-query-busy [71.839269] Subtest pipe-B-query-busy: SUCCESS (5.788s) [72.064684] [003/142] (953s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_rc_ccs) [72.934585] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs [72.953702] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s) [73.184612] [004/142] (952s left) v3d/v3d_perfmon (get-values-invalid-perfmon) [73.629757] Subtest get-values-invalid-perfmon: SKIP (0.000s) [73.720769] [005/142] (952s left) kms_psr2_sf (primary-plane-update-sf-dmg-area-big-fb) [73.936192] Subtest primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s) [73.976655] [006/142] (952s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [74.218862] Starting subtest: basic-flip-before-cursor-legacy [75.743781] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.533s) [75.979571] [007/142] (950s left) gem_create (create-ext-cpu-access-sanity-check) [76.266322] Starting subtest: create-ext-cpu-access-sanity-check [76.273546] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s) [76.369299] [008/142] (949s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc) [76.647805] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc [76.654650] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [76.712377] [009/142] (949s left) i915_pm_rpm (gem-mmap-type) [78.737675] Starting subtest: gem-mmap-type [78.745481] Starting dynamic subtest: gtt-smem0 [81.006434] Dynamic subtest gtt-smem0: SUCCESS (2.276s) [81.024686] Starting dynamic subtest: wb-smem0 [82.136848] Dynamic subtest wb-smem0: SUCCESS (1.126s) [82.202066] Starting dynamic subtest: wc-smem0 [83.478149] Dynamic subtest wc-smem0: SUCCESS (1.345s) [83.503193] Starting dynamic subtest: uc-smem0 [84.579580] Dynamic subtest uc-smem0: SUCCESS (1.114s) [84.586141] Subtest gem-mmap-type: SUCCESS (5.862s) [84.854690] [010/142] (941s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt) [85.126462] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt [87.170185] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (2.064s) [87.401135] [011/142] (938s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu) [87.678747] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu [90.134904] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.471s) [90.287640] [012/142] (936s left) v3d/v3d_wait_bo (unused-bo-0ns) [90.508358] Subtest unused-bo-0ns: SKIP (0.000s) [90.570055] [013/142] (935s left) syncobj_timeline (invalid-transfer-non-existent-point) [90.738605] Starting subtest: invalid-transfer-non-existent-point [90.742322] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s) [90.819972] [014/142] (935s left) api_intel_allocator (reloc-allocator) [90.989015] Starting subtest: reloc-allocator [90.992622] Starting dynamic subtest: basic [90.996205] Dynamic subtest basic: SUCCESS (0.006s) [91.010732] Starting dynamic subtest: parallel-one [91.014710] Dynamic subtest parallel-one: SUCCESS (0.010s) [91.031310] Starting dynamic subtest: print [91.034892] Dynamic subtest print: SUCCESS (0.000s) [91.051685] Starting dynamic subtest: reuse [91.055331] Dynamic subtest reuse: SUCCESS (0.000s) [91.070022] Starting dynamic subtest: fork-reopen-allocator [91.099662] Dynamic subtest fork-reopen-allocator: SUCCESS (0.053s) [91.110035] Subtest reloc-allocator: SUCCESS (0.071s) [91.139351] [015/142] (935s left) fbdev (info) [91.296620] Starting subtest: info [91.300353] Subtest info: SUCCESS (0.000s) [91.371548] [016/142] (935s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_rc_ccs) [92.135701] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs [92.146767] Subtest pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s) [92.412814] [017/142] (934s left) gem_userptr_blits (map-fixed-invalidate) [92.613329] Starting subtest: map-fixed-invalidate [92.617106] Starting dynamic subtest: gtt [92.884185] Dynamic subtest gtt: SUCCESS (0.273s) [92.902386] Starting dynamic subtest: wb [93.106369] Dynamic subtest wb: SUCCESS (0.207s) [93.124557] Starting dynamic subtest: wc [93.298648] Dynamic subtest wc: SUCCESS (0.206s) [93.315375] Starting dynamic subtest: uc [93.505137] Dynamic subtest uc: SUCCESS (0.206s) [93.508702] Subtest map-fixed-invalidate: SUCCESS (0.894s) [93.618878] [018/142] (933s left) kms_psr (cursor_render) [93.817328] Subtest cursor_render: SKIP (0.000s) [93.843412] [019/142] (932s left) gem_ctx_engines (invalid-engines) [94.014927] Starting subtest: invalid-engines [94.030305] Subtest invalid-engines: SUCCESS (0.015s) [94.244274] [020/142] (932s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [94.501499] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [94.508639] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [94.588426] [021/142] (932s left) kms_big_fb (y-tiled-64bpp-rotate-90) [94.848618] Starting subtest: y-tiled-64bpp-rotate-90 [95.147562] Subtest y-tiled-64bpp-rotate-90: SKIP (0.306s) [95.334277] [022/142] (931s left) gem_exec_schedule (preempt-self) [95.541781] Starting subtest: preempt-self [95.546286] Starting dynamic subtest: rcs0 [95.562960] Dynamic subtest rcs0: SUCCESS (0.032s) [95.599155] Starting dynamic subtest: bcs0 [95.607607] Dynamic subtest bcs0: SUCCESS (0.041s) [95.640522] Starting dynamic subtest: vcs0 [95.644814] Dynamic subtest vcs0: SUCCESS (0.038s) [95.663461] Starting dynamic subtest: vecs0 [95.684390] Dynamic subtest vecs0: SUCCESS (0.040s) [95.702697] Subtest preempt-self: SUCCESS (0.153s) [96.001373] [023/142] (930s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_mtl_rc_ccs_cc) [96.880741] Subtest pipe-D-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s) [97.108611] [024/142] (929s left) perf (stress-open-close) [97.391515] Starting subtest: stress-open-close [97.395163] Starting dynamic subtest: 0-rcs0 [99.593599] Dynamic subtest 0-rcs0: SUCCESS (2.214s) [99.610836] Subtest stress-open-close: SUCCESS (2.214s) [99.646990] [025/142] (927s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling) [99.928484] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling [99.943948] Starting dynamic subtest: pipe-A-valid-mode [100.339843] Dynamic subtest pipe-A-valid-mode: SKIP (0.420s) [100.347227] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.423s) [100.587722] [026/142] (926s left) gen7_exec_parse (load-register-reg) [100.751512] Subtest load-register-reg: SKIP (0.000s) [100.806694] [027/142] (926s left) kms_universal_plane (universal-plane-pipe-a-functional) [101.049606] Starting subtest: universal-plane-pipe-A-functional [106.555289] Subtest universal-plane-pipe-A-functional: SUCCESS (5.517s) [106.790617] [028/142] (920s left) i915_pm_rpm (gem-execbuf) [108.817503] Starting subtest: gem-execbuf [108.833722] Starting dynamic subtest: smem0 [110.381657] Dynamic subtest smem0: SUCCESS (1.561s) [110.385230] Subtest gem-execbuf: SUCCESS (1.562s) [110.787619] [029/142] (916s left) kms_pipe_crc_basic (read-crc) [111.070430] Starting subtest: read-crc [111.073988] Starting dynamic subtest: pipe-A-HDMI-A-1 [111.990793] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.904s) [112.011203] Starting dynamic subtest: pipe-B-HDMI-A-2 [112.809227] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.818s) [112.828502] Starting dynamic subtest: pipe-C-HDMI-A-1 [113.927654] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.111s) [113.931915] Subtest read-crc: SUCCESS (2.854s) [114.183611] [030/142] (912s left) kms_vblank (pipe-a-wait-forked-busy-hang) [114.443100] Starting subtest: pipe-A-wait-forked-busy-hang [128.479236] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (14.018s) [128.696537] [031/142] (898s left) kms_vblank (pipe-b-ts-continuation-dpms-suspend) [129.026601] Starting subtest: pipe-B-ts-continuation-dpms-suspend [165.900033] Subtest pipe-B-ts-continuation-dpms-suspend: SUCCESS (5.455s) [171.091188] [032/142] (892s left) kms_ccs (pipe-a-bad-aux-stride-yf_tiled_ccs) [171.830994] Starting subtest: pipe-A-bad-aux-stride-yf_tiled_ccs [171.838321] Subtest pipe-A-bad-aux-stride-yf_tiled_ccs: SUCCESS (0.002s) [172.184127] [033/142] (891s left) kms_rotation_crc (primary-4-tiled-reflect-x-0) [172.463125] Starting subtest: primary-4-tiled-reflect-x-0 [172.478069] Subtest primary-4-tiled-reflect-x-0: SKIP (0.003s) [172.552079] [034/142] (891s left) kms_force_connector_basic (force-connector-state) [172.926628] Starting subtest: force-connector-state [174.429823] Subtest force-connector-state: SUCCESS (1.501s) [175.106819] [035/142] (888s left) kms_big_fb (x-tiled-8bpp-rotate-270) [175.385772] Starting subtest: x-tiled-8bpp-rotate-270 [175.702531] Subtest x-tiled-8bpp-rotate-270: SKIP (0.296s) [175.860502] [036/142] (887s left) gem_exec_fence (nb-await) [176.159503] Starting subtest: nb-await [176.163338] Starting dynamic subtest: rcs0 [176.229589] Dynamic subtest rcs0: SUCCESS (0.069s) [176.254891] Starting dynamic subtest: bcs0 [176.323534] Dynamic subtest bcs0: SUCCESS (0.081s) [176.342537] Starting dynamic subtest: vcs0 [176.396562] Dynamic subtest vcs0: SUCCESS (0.074s) [176.417239] Starting dynamic subtest: vecs0 [176.463132] Dynamic subtest vecs0: SUCCESS (0.073s) [176.466923] Subtest nb-await: SUCCESS (0.303s) [177.174366] [037/142] (886s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt) [177.460886] Starting subtest: psr-1p-primscrn-indfb-plflip-blt [177.468133] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s) [177.525877] [038/142] (886s left) kms_plane_multiple (tiling-none) [177.793944] Starting subtest: tiling-none [177.797619] Starting dynamic subtest: pipe-A-HDMI-A-1 [179.666749] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.854s) [179.691880] Starting dynamic subtest: pipe-A-HDMI-A-2 [181.346701] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.687s) [181.376830] Starting dynamic subtest: pipe-B-HDMI-A-1 [183.014903] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.634s) [183.040626] Starting dynamic subtest: pipe-B-HDMI-A-2 [184.644254] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.638s) [184.669801] Starting dynamic subtest: pipe-C-HDMI-A-1 [186.250829] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.595s) [186.281904] Starting dynamic subtest: pipe-C-HDMI-A-2 [187.834114] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.560s) [187.839025] Subtest tiling-none: SUCCESS (10.036s) [188.147572] [039/142] (875s left) kms_lease (lease_unleased_connector) [188.398449] Starting subtest: lease_unleased_connector [188.409373] Starting dynamic subtest: pipe-A-HDMI-A-1 [188.492340] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.081s) [188.512347] Starting dynamic subtest: pipe-A-HDMI-A-2 [188.576990] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.076s) [188.592910] Starting dynamic subtest: pipe-B-HDMI-A-1 [188.656868] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.075s) [188.702605] Starting dynamic subtest: pipe-B-HDMI-A-2 [188.741894] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.072s) [188.759174] Starting dynamic subtest: pipe-C-HDMI-A-1 [188.819228] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.070s) [188.835991] Starting dynamic subtest: pipe-C-HDMI-A-2 [188.895177] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.075s) [188.898960] Subtest lease_unleased_connector: SUCCESS (0.482s) [188.972717] [040/142] (874s left) kms_big_fb (yf-tiled-8bpp-rotate-90) [189.233081] Starting subtest: yf-tiled-8bpp-rotate-90 [189.240485] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s) [189.320737] [041/142] (874s left) kms_setmode (basic-clone-single-crtc) [189.509446] Starting subtest: basic-clone-single-crtc [190.005706] Subtest basic-clone-single-crtc: SKIP (0.499s) [190.267024] [042/142] (873s left) api_intel_bb (intel-bb-blit-y) [190.473200] Starting subtest: intel-bb-blit-y [193.455075] Subtest intel-bb-blit-y: SUCCESS (2.968s) [193.542489] [043/142] (870s left) kms_chamelium_color (ctm-0-75) [193.830842] Subtest ctm-0-75: SKIP (0.000s) [193.905155] [044/142] (870s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt) [194.220062] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt [194.227247] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [194.298292] [045/142] (869s left) i915_selftest (mock) [196.027226] Starting subtest: mock [196.031491] Starting dynamic subtest: sanitycheck [196.346358] Dynamic subtest sanitycheck: SUCCESS (0.319s) [196.365927] Starting dynamic subtest: shmem [196.641895] Dynamic subtest shmem: SUCCESS (0.293s) [196.661109] Starting dynamic subtest: fence [203.846425] Dynamic subtest fence: SUCCESS (7.201s) [203.880974] Starting dynamic subtest: scatterlist [205.119038] Dynamic subtest scatterlist: SUCCESS (1.295s) [205.136946] Starting dynamic subtest: syncmap [207.560841] Dynamic subtest syncmap: SUCCESS (2.418s) [207.582670] Starting dynamic subtest: uncore [207.862694] Dynamic subtest uncore: SUCCESS (0.300s) [207.884933] Starting dynamic subtest: ring [208.142394] Dynamic subtest ring: SUCCESS (0.279s) [208.165014] Starting dynamic subtest: engine [208.442355] Dynamic subtest engine: SUCCESS (0.300s) [208.464925] Starting dynamic subtest: timelines [218.158723] Dynamic subtest timelines: SUCCESS (9.734s) [218.172992] Starting dynamic subtest: requests [220.387859] Dynamic subtest requests: SUCCESS (2.225s) [220.401489] Starting dynamic subtest: objects [220.780116] Dynamic subtest objects: SUCCESS (0.391s) [220.794863] Starting dynamic subtest: phys [221.214423] Dynamic subtest phys: SUCCESS (0.433s) [221.229153] Starting dynamic subtest: dmabuf [221.615936] Dynamic subtest dmabuf: SUCCESS (0.398s) [221.657303] Starting dynamic subtest: vma [243.058176] Dynamic subtest vma: SUCCESS (21.442s) [243.073306] Starting dynamic subtest: evict [245.461119] Dynamic subtest evict: FAIL (2.314s) [245.468609] Subtest mock: FAIL (49.367s) [245.487445] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1707 [max: 8192] direct dependencies: 16658 [max: 32768] indirect dependencies: 107834 all direct dependencies: 309398 dependency chains: 22430 [max: 131072] dependency chain hlocks used: 91407 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 143 in-softirq chains: 689 in-process chains: 21598 stack-trace entries: 186526 [max: 1048576] number of stack traces: 9653 number of stack hash chains: 7277 combined max dependencies: 2146076640 hardirq-safe locks: 78 hardirq-unsafe locks: 1020 softirq-safe locks: 190 softirq-unsafe locks: 933 irq-safe locks: 209 irq-unsafe locks: 1020 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: 292 unused locks: 1 max locking depth: 17 max bfs queue depth: 434 max lock class index: 1863 chain lookup misses: 28609 chain lookup hits: 118178313 cyclic checks: 28351 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4814 find-mask backwards checks: 3092 hardirq on events: 73094410 hardirq off events: 73094408 redundant hardirq ons: 26 redundant hardirq offs: 49 softirq on events: 237759 softirq off events: 237759 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 443 zapped lock chains: 6110 large chain blocks: 1 [245.491902] Closing watchdogs Done.