[39.796381] Initializing watchdogs [39.796419] /dev/watchdog0 [39.802278] [001/138] (960s left) prime_mmap (test_aperture_limit) [39.857708] Starting subtest: test_aperture_limit [39.860137] Starting dynamic subtest: test_aperture_limit-smem [39.865110] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.006s) [39.867195] Subtest test_aperture_limit: SUCCESS (0.006s) [39.941075] [002/138] (960s left) gem_render_copy (y-tiled-to-vebox-yf-tiled) [40.012514] Starting subtest: y-tiled-to-vebox-yf-tiled [40.016685] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s) [40.186257] [003/138] (960s left) template (a) [40.254126] Starting subtest: A [40.256640] Subtest A: SUCCESS (0.000s) [40.313886] [004/138] (960s left) vc4/vc4_wait_bo (bad-bo) [40.414075] Subtest bad-bo: SKIP (0.000s) [40.454022] [005/138] (959s left) kms_psr (psr2_no_drrs) [40.533524] Starting subtest: psr2_no_drrs [42.207733] Subtest psr2_no_drrs: SUCCESS (1.671s) [43.139260] [006/138] (957s left) gem_exec_reloc (basic-wc-gtt-noreloc) [43.199647] Subtest basic-wc-gtt-noreloc: SKIP (0.000s) [43.260625] [007/138] (957s left) kms_flip (2x-flip-vs-rmfb) [43.338128] Starting subtest: 2x-flip-vs-rmfb [43.360641] Subtest 2x-flip-vs-rmfb: SKIP (0.025s) [43.427854] [008/138] (957s left) kms_big_fb (x-tiled-8bpp-rotate-90) [43.497791] Starting subtest: x-tiled-8bpp-rotate-90 [43.514091] Subtest x-tiled-8bpp-rotate-90: SKIP (0.018s) [43.600485] [009/138] (956s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt) [43.675689] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt [43.679736] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [43.751468] [010/138] (956s left) kms_pm_rpm (modeset-non-lpsp) [45.559063] Starting subtest: modeset-non-lpsp [49.182677] Subtest modeset-non-lpsp: SKIP (3.621s) [50.021082] [011/138] (950s left) kms_vblank (ts-continuation-dpms-rpm) [50.095181] Starting subtest: ts-continuation-dpms-rpm [50.097585] Starting dynamic subtest: pipe-A-eDP-1 [52.699668] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.603s) [52.711604] Starting dynamic subtest: pipe-D-eDP-1 [55.052880] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.347s) [55.055782] Subtest ts-continuation-dpms-rpm: SUCCESS (4.958s) [55.936194] [012/138] (944s left) v3d/v3d_submit_cl (bad-multisync-extension) [56.001145] Subtest bad-multisync-extension: SKIP (0.000s) [56.041633] [013/138] (944s left) i915_pm_rpm (debugfs-forcewake-user) [57.640810] Starting subtest: debugfs-forcewake-user [71.595598] Subtest debugfs-forcewake-user: SUCCESS (13.955s) [71.964728] [014/138] (928s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [72.040316] Starting subtest: compare-crc-sanitycheck-nv12 [72.042317] Starting dynamic subtest: pipe-A-eDP-1 [72.730557] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.689s) [72.741211] Starting dynamic subtest: pipe-B-eDP-1 [74.124074] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.393s) [74.137104] Starting dynamic subtest: pipe-C-eDP-1 [75.459560] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.332s) [75.470481] Starting dynamic subtest: pipe-D-eDP-1 [76.785585] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.324s) [76.788602] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.746s) [77.652827] [015/138] (922s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-mc-ccs) [78.046032] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs [78.054639] Subtest pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s) [78.901856] [016/138] (921s left) prime_mmap_coherency (ioctl-errors) [78.969364] Starting subtest: ioctl-errors [81.663569] Subtest ioctl-errors: SUCCESS (2.695s) [81.797676] [017/138] (918s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff) [81.960236] Starting subtest: psr-1p-primscrn-spr-indfb-onoff [87.143741] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (5.189s) [87.255720] [018/138] (913s left) gem_mmap_wc (bad-size) [87.315020] Subtest bad-size: SKIP (0.000s) [87.333124] [019/138] (913s left) gem_exec_schedule (timeslicing) [87.396604] Starting subtest: timeslicing [87.398604] Starting dynamic subtest: rcs0 [87.410898] Dynamic subtest rcs0: SUCCESS (0.019s) [87.422065] Starting dynamic subtest: bcs0 [87.430490] Dynamic subtest bcs0: SUCCESS (0.019s) [87.440806] Starting dynamic subtest: vcs0 [87.449404] Dynamic subtest vcs0: SUCCESS (0.019s) [87.459023] Starting dynamic subtest: vcs1 [87.468388] Dynamic subtest vcs1: SUCCESS (0.019s) [87.480174] Starting dynamic subtest: vecs0 [87.487480] Dynamic subtest vecs0: SUCCESS (0.019s) [87.499180] Starting dynamic subtest: ccs0 [87.507325] Dynamic subtest ccs0: SUCCESS (0.019s) [87.510684] Subtest timeslicing: SUCCESS (0.116s) [89.505242] [020/138] (911s left) kms_ccs (pipe-b-random-ccs-data-y-tiled-ccs) [89.899834] Starting subtest: pipe-B-random-ccs-data-y-tiled-ccs [89.908977] Subtest pipe-B-random-ccs-data-y-tiled-ccs: SKIP (0.000s) [90.807668] [021/138] (909s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary) [90.891089] Starting subtest: fbc-indfb-scaledprimary [94.935117] Subtest fbc-indfb-scaledprimary: SUCCESS (4.049s) [95.063625] [022/138] (905s left) gen9_exec_parse (bb-start-param) [95.113937] Subtest bb-start-param: SKIP (0.000s) [95.147393] [023/138] (905s left) gem_mmap_gtt (cpuset-basic-small-copy) [95.214503] Subtest cpuset-basic-small-copy: SKIP (0.000s) [95.237959] [024/138] (905s left) kms_psr2_sf (plane-move-sf-dmg-area) [100.434959] Starting subtest: plane-move-sf-dmg-area [100.437554] Starting dynamic subtest: pipe-A-eDP-1 [105.953177] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.516s) [105.965780] Starting dynamic subtest: pipe-B-eDP-1 [111.524801] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.570s) [111.527931] Subtest plane-move-sf-dmg-area: SUCCESS (11.091s) [112.434048] [025/138] (888s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi [112.472171] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted [112.575917] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s) [112.639406] [026/138] (888s left) gem_exec_store (pages) [112.715082] Starting subtest: pages [112.717567] Starting dynamic subtest: rcs0 [112.790353] Dynamic subtest rcs0: SUCCESS (0.075s) [112.802636] Starting dynamic subtest: bcs0 [112.855884] Dynamic subtest bcs0: SUCCESS (0.065s) [112.867669] Starting dynamic subtest: vcs0 [112.909758] Dynamic subtest vcs0: SUCCESS (0.053s) [112.921313] Starting dynamic subtest: vcs1 [112.957963] Dynamic subtest vcs1: SUCCESS (0.048s) [112.969049] Starting dynamic subtest: vecs0 [112.997557] Dynamic subtest vecs0: SUCCESS (0.039s) [113.005598] Starting dynamic subtest: ccs0 [113.074058] Dynamic subtest ccs0: SUCCESS (0.076s) [113.076775] Subtest pages: SUCCESS (0.359s) [113.248028] [027/138] (887s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt) [113.346533] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt [113.351094] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s) [113.418990] [028/138] (887s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt) [113.505634] Starting subtest: psr-1p-primscrn-indfb-plflip-blt [118.806840] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (5.305s) [118.905496] [029/138] (882s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible) [118.954791] Starting subtest: flip-vs-absolute-wf_vblank-interruptible [118.957228] Starting dynamic subtest: A-eDP1 [128.039581] Dynamic subtest A-eDP1: SUCCESS (9.084s) [128.052155] Starting dynamic subtest: B-eDP1 [136.997705] Dynamic subtest B-eDP1: SUCCESS (8.957s) [137.009638] Starting dynamic subtest: C-eDP1 [146.010077] Dynamic subtest C-eDP1: SUCCESS (9.011s) [146.022482] Starting dynamic subtest: D-eDP1 [154.971969] Dynamic subtest D-eDP1: SUCCESS (8.959s) [154.978701] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (36.025s) [156.226865] [030/138] (844s left) kms_ccs (pipe-a-random-ccs-data-y-tiled-gen12-mc-ccs) [156.603992] Starting subtest: pipe-A-random-ccs-data-y-tiled-gen12-mc-ccs [156.611277] Subtest pipe-A-random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.000s) [157.497407] [031/138] (843s left) gem_flink_basic (flink-lifetime) [157.552104] Starting subtest: flink-lifetime [157.554542] Subtest flink-lifetime: SUCCESS (0.001s) [157.624616] [032/138] (843s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt) [157.711624] Starting subtest: fbcpsr-farfromfence-mmap-gtt [157.716417] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s) [157.798203] [033/138] (843s left) syncobj_wait (invalid-multi-wait-all-unsubmitted) [157.849610] Starting subtest: invalid-multi-wait-all-unsubmitted [157.851618] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s) [157.907689] [034/138] (843s left) kms_chamelium_hpd (dp-hpd-fast) [158.256404] Subtest dp-hpd-fast: SKIP (0.000s) [159.135226] [035/138] (841s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt) [159.194721] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt [159.199354] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [159.278852] [036/138] (841s left) perf (invalid-oa-metric-set-id) [159.409529] Starting subtest: invalid-oa-metric-set-id [159.418349] Subtest invalid-oa-metric-set-id: SUCCESS (0.007s) [159.441884] [037/138] (841s left) gem_eio (in-flight-internal-immediate) [159.630926] Starting subtest: in-flight-internal-immediate [161.521074] Subtest in-flight-internal-immediate: SUCCESS (1.891s) [163.488374] [038/138] (837s left) kms_cursor_crc (cursor-onscreen-64x21) [163.598430] Starting subtest: cursor-onscreen-64x21 [163.600839] Starting dynamic subtest: pipe-A-eDP-1 [165.732607] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.131s) [165.759581] Starting dynamic subtest: pipe-D-eDP-1 [168.618072] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.864s) [168.621075] Subtest cursor-onscreen-64x21: SUCCESS (5.022s) [169.514052] [039/138] (831s left) i915_pm_rpm (gem-pread) [171.627247] Starting subtest: gem-pread [176.943908] Subtest gem-pread: SUCCESS (5.319s) [177.029620] [040/138] (824s left) kms_feature_discovery (display) [177.098188] Starting subtest: display [177.100619] Subtest display: SUCCESS (0.000s) [177.159303] [041/138] (824s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move) [177.226481] Starting subtest: fbc-2p-scndscrn-spr-indfb-move [177.230374] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [177.315435] [042/138] (823s left) gem_exec_schedule (preempt-contexts) [177.389932] Starting subtest: preempt-contexts [177.392415] Starting dynamic subtest: rcs0 [177.415450] Dynamic subtest rcs0: SUCCESS (0.037s) [177.421182] Starting dynamic subtest: bcs0 [177.442316] Dynamic subtest bcs0: SUCCESS (0.027s) [177.448345] Starting dynamic subtest: vcs0 [177.479555] Dynamic subtest vcs0: SUCCESS (0.038s) [177.486059] Starting dynamic subtest: vcs1 [177.507223] Dynamic subtest vcs1: SUCCESS (0.026s) [177.513308] Starting dynamic subtest: vecs0 [177.527059] Dynamic subtest vecs0: SUCCESS (0.020s) [177.533541] Starting dynamic subtest: ccs0 [177.547821] Dynamic subtest ccs0: SUCCESS (0.020s) [177.550195] Subtest preempt-contexts: SUCCESS (0.171s) [178.573230] [043/138] (822s left) kms_rotation_crc (primary-x-tiled-reflect-x-0) [178.658076] Starting subtest: primary-x-tiled-reflect-x-0 [186.168236] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.514s) [187.451615] [044/138] (813s left) gem_render_tiled_blits (basic) [187.496938] Subtest basic: SKIP (0.000s) [187.519894] [045/138] (813s left) gem_ctx_persistence (legacy-engines-hang) [187.569515] Starting subtest: legacy-engines-hang [187.571609] Starting dynamic subtest: render [187.656296] Dynamic subtest render: SUCCESS (0.087s) [187.670169] Starting dynamic subtest: bsd1 [188.460479] Dynamic subtest bsd1: FAIL (0.750s) [188.473565] Starting dynamic subtest: bsd2 [190.599599] Dynamic subtest bsd2: SUCCESS (2.191s) [190.613214] Starting dynamic subtest: blt [190.668733] Dynamic subtest blt: SUCCESS (0.067s) [190.681991] Starting dynamic subtest: vebox [190.721809] Dynamic subtest vebox: SUCCESS (0.052s) [190.724621] Subtest legacy-engines-hang: FAIL (3.154s) [190.797684] [046/138] (810s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc) [191.225138] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc [192.927884] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (1.702s) [194.170198] [047/138] (807s left) kms_chamelium_color (ctm-max) [194.226770] Subtest ctm-max: SKIP (0.000s) [194.255946] [048/138] (807s left) kms_writeback (writeback-invalid-parameters) [194.332911] Subtest writeback-invalid-parameters: SKIP (0.000s) [194.350042] [049/138] (807s left) gem_vm_create (invalid-create) [194.420045] Starting subtest: invalid-create [194.422359] Subtest invalid-create: SUCCESS (0.001s) [194.507499] [050/138] (806s left) gem_mmap_gtt (basic-write-read) [194.557039] Subtest basic-write-read: SKIP (0.000s) [194.581190] [051/138] (806s left) gem_cs_tlb (engines) [194.645494] Starting subtest: engines [194.647846] Starting dynamic subtest: rcs0 [196.795922] Dynamic subtest rcs0: SUCCESS (2.150s) [196.808502] Starting dynamic subtest: bcs0 [198.946549] Dynamic subtest bcs0: SUCCESS (2.150s) [198.959905] Starting dynamic subtest: vcs0 [201.098481] Dynamic subtest vcs0: SUCCESS (2.151s) [201.113079] Starting dynamic subtest: vcs1 [203.249370] Dynamic subtest vcs1: SUCCESS (2.150s) [203.262684] Starting dynamic subtest: vecs0 [205.400553] Dynamic subtest vecs0: SUCCESS (2.150s) [205.413146] Starting dynamic subtest: ccs0 [207.552376] Dynamic subtest ccs0: SUCCESS (2.150s) [207.554473] Subtest engines: SUCCESS (12.906s) [207.650977] [052/138] (793s left) gem_eio (wait-1us) [207.880354] Starting subtest: wait-1us [208.254043] Subtest wait-1us: SUCCESS (0.376s) [209.505432] [053/138] (792s left) kms_big_fb (yf-tiled-32bpp-rotate-270) [209.586065] Starting subtest: yf-tiled-32bpp-rotate-270 [209.589955] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s) [209.675260] [054/138] (791s left) api_intel_bb (lot-of-buffers) [209.720327] Starting subtest: lot-of-buffers [210.614022] Subtest lot-of-buffers: SUCCESS (0.897s) [210.741352] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1773 [max: 8192] direct dependencies: 17764 [max: 32768] indirect dependencies: 135512 all direct dependencies: 373140 dependency chains: 25691 [max: 131072] dependency chain hlocks used: 107319 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 261 in-softirq chains: 541 in-process chains: 24889 stack-trace entries: 168607 [max: 1048576] number of stack traces: 8523 number of stack hash chains: 6661 combined max dependencies: 3534479560 hardirq-safe locks: 109 hardirq-unsafe locks: 1064 softirq-safe locks: 201 softirq-unsafe locks: 977 irq-safe locks: 225 irq-unsafe locks: 1064 hardirq-read-safe locks: 4 hardirq-read-unsafe locks: 310 softirq-read-safe locks: 7 softirq-read-unsafe locks: 305 irq-read-safe locks: 8 irq-read-unsafe locks: 310 uncategorized locks: 283 unused locks: 1 max locking depth: 16 max bfs queue depth: 385 max lock class index: 1772 chain lookup misses: 26272 chain lookup hits: 139711601 cyclic checks: 26204 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4151 find-mask backwards checks: 2658 hardirq on events: 84503971 hardirq off events: 84503949 redundant hardirq ons: 28 redundant hardirq offs: 21 softirq on events: 499257 softirq off events: 499257 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 1 zapped lock chains: 98 large chain blocks: 1 [210.743885] Closing watchdogs Done.