[55.533814] Initializing watchdogs [55.533860] /dev/watchdog0 [55.542803] [001/130] (960s left) kms_flip (2x-wf_vblank-ts-check) [55.656283] Starting subtest: 2x-wf_vblank-ts-check [55.666831] Subtest 2x-wf_vblank-ts-check: SKIP (0.010s) [55.723773] [002/130] (960s left) kms_addfb_basic (bo-too-small-due-to-tiling) [55.812054] Starting subtest: bo-too-small-due-to-tiling [55.816687] Subtest bo-too-small-due-to-tiling: SKIP (0.000s) [55.886259] [003/130] (960s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size) [56.006217] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size [67.206510] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.189s) [67.976953] [004/130] (948s left) gem_ccs (ctrl-surf-copy-new-ctx) [68.046117] Starting subtest: ctrl-surf-copy-new-ctx [68.050900] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s) [68.133224] [005/130] (947s left) gem_exec_whisper (basic-normal-all) [68.198553] Starting subtest: basic-normal-all [70.444644] Subtest basic-normal-all: SUCCESS (2.245s) [70.605813] [006/130] (945s left) gen9_exec_parse (batch-without-end) [70.671306] Subtest batch-without-end: SKIP (0.000s) [70.742915] [007/130] (945s left) perf_pmu (gt-awake) [70.784505] Starting subtest: gt-awake [79.915810] Subtest gt-awake: SUCCESS (9.128s) [80.012960] [008/130] (936s left) i915_hangman (detector) [80.090086] Starting subtest: detector [80.092441] Starting dynamic subtest: rcs0 [90.890748] Dynamic subtest rcs0: SUCCESS (10.799s) [90.900049] Starting dynamic subtest: bcs0 [102.920990] Dynamic subtest bcs0: SUCCESS (12.028s) [102.930253] Starting dynamic subtest: vcs0 [114.825868] Dynamic subtest vcs0: SUCCESS (11.903s) [114.834371] Starting dynamic subtest: vcs1 [124.872061] Dynamic subtest vcs1: SUCCESS (10.044s) [124.881500] Starting dynamic subtest: vecs0 [136.840866] Dynamic subtest vecs0: SUCCESS (11.966s) [136.850656] Starting dynamic subtest: ccs0 [148.871422] Dynamic subtest ccs0: SUCCESS (12.029s) [148.874480] Subtest detector: SUCCESS (68.775s) [148.931278] [009/130] (867s left) kms_vrr (flip-basic-fastset) [149.033944] Starting subtest: flip-basic-fastset [149.040678] Subtest flip-basic-fastset: SKIP (0.000s) [149.085099] [010/130] (867s left) prime_mmap (test_aperture_limit) [149.147383] Starting subtest: test_aperture_limit [149.149737] Starting dynamic subtest: test_aperture_limit-smem [149.154922] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.008s) [149.156929] Subtest test_aperture_limit: SUCCESS (0.009s) [149.219937] [011/130] (867s left) kms_pm_rpm (universal-planes-dpms) [151.138714] Starting subtest: universal-planes-dpms [151.141771] Starting dynamic subtest: plane-32 [159.831559] Dynamic subtest plane-32: SUCCESS (8.692s) [159.841547] Starting dynamic subtest: plane-41 [168.144075] Dynamic subtest plane-41: SUCCESS (8.310s) [168.154490] Starting dynamic subtest: plane-50 [176.744538] Dynamic subtest plane-50: SUCCESS (8.599s) [176.753864] Starting dynamic subtest: plane-59 [185.544036] Dynamic subtest plane-59: SUCCESS (8.796s) [185.554593] Starting dynamic subtest: plane-68 [193.830553] Dynamic subtest plane-68: SUCCESS (8.286s) [193.840130] Starting dynamic subtest: plane-77 [202.400170] Dynamic subtest plane-77: SUCCESS (8.568s) [202.402667] Subtest universal-planes-dpms: SUCCESS (51.261s) [202.725678] [012/130] (813s left) gem_exec_params (cliprects_ptr-dirt) [202.793828] Starting subtest: cliprects_ptr-dirt [202.796119] Subtest cliprects_ptr-dirt: SUCCESS (0.000s) [202.878311] [013/130] (813s left) gem_workarounds (reset) [202.944091] Starting subtest: reset [203.069220] Subtest reset: SUCCESS (0.124s) [203.182229] [014/130] (813s left) sw_sync (sync_merge_invalid) [203.219430] Starting subtest: sync_merge_invalid [203.248984] Subtest sync_merge_invalid: SUCCESS (0.027s) [203.303878] [015/130] (813s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render) [203.410587] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render [206.018971] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (2.613s) [206.134245] [016/130] (810s left) kms_cdclk (mode-transition) [206.223495] Starting subtest: mode-transition [206.225546] Starting dynamic subtest: pipe-A-eDP-1 [206.521518] Dynamic subtest pipe-A-eDP-1: SKIP (0.293s) [206.532067] Starting dynamic subtest: pipe-B-eDP-1 [206.536911] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [206.546551] Starting dynamic subtest: pipe-C-eDP-1 [206.551317] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [206.558037] Starting dynamic subtest: pipe-D-eDP-1 [206.562495] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [206.566944] Subtest mode-transition: SKIP (0.299s) [207.279099] [017/130] (809s left) kms_dirtyfb (drrs-dirtyfb-ioctl) [207.378134] Starting subtest: drrs-dirtyfb-ioctl [207.382791] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s) [207.472179] [018/130] (808s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render) [207.568724] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render [207.572844] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [207.654155] [019/130] (808s left) kms_psr (fbc-psr2-primary-blt) [207.749130] Starting subtest: fbc-psr2-primary-blt [207.751476] Starting dynamic subtest: eDP-1 [207.755694] Dynamic subtest eDP-1: SKIP (0.000s) [207.759892] Subtest fbc-psr2-primary-blt: SKIP (0.001s) [207.801570] [020/130] (808s left) kms_psr (psr-cursor-render) [207.900067] Starting subtest: psr-cursor-render [207.902207] Starting dynamic subtest: eDP-1 [209.589892] Dynamic subtest eDP-1: SUCCESS (1.688s) [209.592745] Subtest psr-cursor-render: SUCCESS (1.689s) [210.435645] [021/130] (805s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [210.489106] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [210.490989] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [210.562119] [022/130] (805s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt) [210.665500] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt [210.669717] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [210.738839] [023/130] (805s left) gem_exec_create (basic) [210.797311] Starting subtest: basic [210.799694] Starting dynamic subtest: smem [212.903551] Dynamic subtest smem: SUCCESS (2.105s) [212.906070] Subtest basic: SUCCESS (2.106s) [213.065718] [024/130] (803s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move) [213.173080] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move [213.177050] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [213.272757] [025/130] (803s left) gem_ctx_persistence (heartbeat-close) [213.335963] Starting subtest: heartbeat-close [213.340510] Subtest heartbeat-close: SKIP (0.000s) [213.435530] [026/130] (803s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [213.543349] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip [214.110991] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.570s) [214.261697] [027/130] (802s left) gen7_exec_parse (chained-batch) [214.323605] Subtest chained-batch: SKIP (0.000s) [214.358193] [028/130] (802s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [214.458601] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [214.462884] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [214.539611] [029/130] (802s left) syncobj_timeline (wait-zero-handles) [214.602626] Starting subtest: wait-zero-handles [214.604679] Subtest wait-zero-handles: SUCCESS (0.000s) [214.691821] [030/130] (801s left) gem_exec_schedule (smoketest) [214.870217] Starting subtest: smoketest [214.872378] Starting dynamic subtest: rcs0 [220.434077] Dynamic subtest rcs0: SUCCESS (5.508s) [220.443840] Starting dynamic subtest: bcs0 [225.966220] Dynamic subtest bcs0: SUCCESS (5.534s) [225.976172] Starting dynamic subtest: vcs0 [231.486758] Dynamic subtest vcs0: SUCCESS (5.522s) [231.501873] Starting dynamic subtest: vcs1 [237.008910] Dynamic subtest vcs1: SUCCESS (5.518s) [237.022179] Starting dynamic subtest: vecs0 [242.520182] Dynamic subtest vecs0: SUCCESS (5.516s) [242.528704] Starting dynamic subtest: ccs0 [248.029837] Dynamic subtest ccs0: SUCCESS (5.511s) [248.032099] Subtest smoketest: SUCCESS (33.112s) [248.233315] [031/130] (768s left) kms_chamelium_edid (dp-mode-timings) [248.623662] Subtest dp-mode-timings: SKIP (0.000s) [249.435900] [032/130] (767s left) kms_tiled_display (basic-test-pattern) [249.559250] Subtest basic-test-pattern: SKIP (0.000s) [249.582474] [033/130] (767s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic) [249.698469] Starting subtest: flip-vs-cursor-busy-crc-atomic [250.503092] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.804s) [251.284967] [034/130] (765s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render) [251.396541] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render [251.400651] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [251.500420] [035/130] (765s left) kms_flip (nonblocking-read) [251.612914] Starting subtest: nonblocking-read [251.615180] Subtest nonblocking-read: SUCCESS (0.000s) [251.712964] [036/130] (764s left) kms_psr (fbc-psr-no-drrs) [251.821107] Starting subtest: fbc-psr-no-drrs [251.823425] Starting dynamic subtest: eDP-1 [251.827879] Dynamic subtest eDP-1: SKIP (0.000s) [251.832306] Subtest fbc-psr-no-drrs: SKIP (0.001s) [251.878655] [037/130] (764s left) gem_ctx_persistence (idempotent) [251.940786] Starting subtest: idempotent [251.943224] Subtest idempotent: SUCCESS (0.000s) [252.047445] [038/130] (764s left) kms_psr (psr2-cursor-blt) [252.138210] Starting subtest: psr2-cursor-blt [252.140495] Starting dynamic subtest: eDP-1 [254.408473] Dynamic subtest eDP-1: FAIL (1.679s) [254.410701] Subtest psr2-cursor-blt: FAIL (1.679s) [254.426418] [039/130] (762s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa [254.482256] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled [254.589991] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.108s) [254.649827] [040/130] (762s left) drm_read (empty-block) [254.794743] Starting subtest: empty-block [255.793397] Subtest empty-block: SUCCESS (1.001s) [255.943944] [041/130] (760s left) i915_pm_rpm (gem-idle) [258.097076] Starting subtest: gem-idle [264.298302] Subtest gem-idle: SUCCESS (6.200s) [264.587932] [042/130] (752s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs) [265.908094] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs [265.910272] Starting dynamic subtest: pipe-A-eDP-1 [266.150750] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.210s) [266.159266] Starting dynamic subtest: pipe-B-eDP-1 [267.363583] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.212s) [267.373279] Starting dynamic subtest: pipe-C-eDP-1 [268.562029] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.197s) [268.571498] Starting dynamic subtest: pipe-D-eDP-1 [269.779407] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.216s) [269.781277] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.839s) [270.881675] [043/130] (745s left) kms_flip (2x-plain-flip-interruptible) [270.973887] Starting subtest: 2x-plain-flip-interruptible [270.986555] Subtest 2x-plain-flip-interruptible: SKIP (0.011s) [271.039579] [044/130] (745s left) kms_cursor_crc (cursor-sliding-256x85) [271.265809] Starting subtest: cursor-sliding-256x85 [271.305871] Subtest cursor-sliding-256x85: SKIP (0.035s) [271.478895] [045/130] (745s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5) [271.573627] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5 [271.575871] Starting dynamic subtest: pipe-A [271.584485] Dynamic subtest pipe-A: SKIP (0.005s) [271.593400] Starting dynamic subtest: pipe-B [271.602746] Dynamic subtest pipe-B: SKIP (0.015s) [271.610482] Starting dynamic subtest: pipe-C [271.619703] Dynamic subtest pipe-C: SKIP (0.018s) [271.627346] Starting dynamic subtest: pipe-D [271.635805] Dynamic subtest pipe-D: SKIP (0.016s) [271.639644] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.055s) [271.707228] [046/130] (745s left) kms_vblank (ts-continuation-idle-hang) [271.790047] Starting subtest: ts-continuation-idle-hang [271.792344] Starting dynamic subtest: pipe-A-eDP-1 [298.716955] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.922s) [298.729765] Starting dynamic subtest: pipe-D-eDP-1 [323.685347] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.966s) [323.687645] Subtest ts-continuation-idle-hang: SUCCESS (51.892s) [324.523803] [047/130] (692s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render) [324.619479] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render [329.614973] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.997s) [329.739921] [048/130] (687s left) kms_async_flips (test-time-stamp) [329.847376] Starting subtest: test-time-stamp [330.208317] Starting dynamic subtest: pipe-A-eDP-1 [331.001270] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.791s) [331.364684] Starting dynamic subtest: pipe-B-eDP-1 [332.121774] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.762s) [332.483979] Starting dynamic subtest: pipe-C-eDP-1 [333.240921] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.763s) [333.604087] Starting dynamic subtest: pipe-D-eDP-1 [334.369756] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.772s) [334.372206] Subtest test-time-stamp: SUCCESS (4.520s) [335.495119] [049/130] (681s left) gem_spin_batch (legacy-resubmit) [335.563124] Starting subtest: legacy-resubmit [335.565412] Starting dynamic subtest: default [335.569838] Dynamic subtest default: SUCCESS (0.006s) [335.577314] Starting dynamic subtest: render [335.579583] Dynamic subtest render: SUCCESS (0.001s) [335.586714] Starting dynamic subtest: bsd [335.588965] Dynamic subtest bsd: SUCCESS (0.002s) [335.596115] Starting dynamic subtest: bsd1 [335.598306] Dynamic subtest bsd1: SUCCESS (0.002s) [335.605405] Starting dynamic subtest: bsd2 [335.607693] Dynamic subtest bsd2: SUCCESS (0.001s) [335.614651] Starting dynamic subtest: blt [335.616903] Dynamic subtest blt: SUCCESS (0.002s) [335.623874] Starting dynamic subtest: vebox [335.626114] Dynamic subtest vebox: SUCCESS (0.002s) [335.628299] Subtest legacy-resubmit: SUCCESS (0.021s) [335.801672] [050/130] (681s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt) [335.914464] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt [341.115229] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (5.203s) [341.229438] [051/130] (675s left) gem_linear_blits (interruptible) [341.589529] Starting subtest: interruptible [345.411037] Subtest interruptible: SUCCESS (3.821s) [345.554801] [052/130] (671s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render) [345.709592] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render [348.770253] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (3.064s) [348.893720] [053/130] (668s left) kms_pm_backlight (basic-brightness) [348.998090] Starting subtest: basic-brightness [349.061863] Starting dynamic subtest: eDP-1 [349.565767] Dynamic subtest eDP-1: SUCCESS (0.501s) [349.568601] Subtest basic-brightness: SUCCESS (0.567s) [350.385397] [054/130] (666s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [355.352157] Starting subtest: fbc-psr2-cursor-plane-update-sf [355.354543] Starting dynamic subtest: pipe-A-eDP-1 [356.157458] Dynamic subtest pipe-A-eDP-1: SKIP (0.803s) [356.166364] Starting dynamic subtest: pipe-B-eDP-1 [357.284954] Dynamic subtest pipe-B-eDP-1: SKIP (1.124s) [357.289355] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.930s) [358.440205] [055/130] (658s left) gem_eio (in-flight-immediate) [358.665687] Starting subtest: in-flight-immediate [366.720545] Subtest in-flight-immediate: SUCCESS (8.055s) [366.818852] [056/130] (650s left) syncobj_timeline (invalid-signal-illegal-point) [366.876788] Starting subtest: invalid-signal-illegal-point [366.878787] Subtest invalid-signal-illegal-point: SUCCESS (0.000s) [366.962137] [057/130] (650s left) i915_query (query-topology-coherent-slice-mask) [367.029835] Starting subtest: query-topology-coherent-slice-mask [367.033938] Subtest query-topology-coherent-slice-mask: SKIP (0.000s) [367.124538] [058/130] (649s left) perf (buffer-fill) [367.244077] Starting subtest: buffer-fill [367.245912] Starting dynamic subtest: 0-rcs0 [369.336913] Dynamic subtest 0-rcs0: SUCCESS (2.093s) [369.344811] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2309 [max: 8192] direct dependencies: 26324 [max: 524288] indirect dependencies: 221272 all direct dependencies: 544548 dependency chains: 39007 [max: 524288] dependency chain hlocks used: 163443 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 375 in-softirq chains: 842 in-process chains: 37790 stack-trace entries: 271104 [max: 524288] number of stack traces: 12496 number of stack hash chains: 8722 combined max dependencies: 3388603096 hardirq-safe locks: 121 hardirq-unsafe locks: 1322 softirq-safe locks: 250 softirq-unsafe locks: 1230 irq-safe locks: 271 irq-unsafe locks: 1322 hardirq-read-safe locks: 6 hardirq-read-unsafe locks: 478 softirq-read-safe locks: 9 softirq-read-unsafe locks: 474 irq-read-safe locks: 10 irq-read-unsafe locks: 478 uncategorized locks: 369 unused locks: 1 max locking depth: 16 max bfs queue depth: 396 max lock class index: 2308 debug_locks: 0 zapped classes: 2 zapped lock chains: 160 large chain blocks: 1 [369.348970] Starting dynamic subtest: 1-vcs0 [373.486873] Dynamic subtest 1-vcs0: SUCCESS (4.147s) [373.489303] Subtest buffer-fill: SUCCESS (6.244s) [373.498252] Closing watchdogs Done.