[52.778637] Initializing watchdogs [52.779172] /dev/watchdog0 [52.812347] [001/142] (960s left) gem_eio (in-flight-immediate) [53.683087] Starting subtest: in-flight-immediate [84.579835] Subtest in-flight-immediate: SUCCESS (30.897s) [86.637398] [002/142] (926s left) gem_pxp (create-valid-protected-context) [89.544923] Subtest create-valid-protected-context: SKIP (0.000s) [89.697157] [003/142] (923s left) prime_busy (before-wait) [90.085890] Starting subtest: before-wait [90.116997] Starting dynamic subtest: rcs0 [90.160205] Dynamic subtest rcs0: SUCCESS (0.043s) [90.192750] Starting dynamic subtest: bcs0 [90.220463] Dynamic subtest bcs0: SUCCESS (0.059s) [90.258459] Starting dynamic subtest: vcs0 [90.269535] Dynamic subtest vcs0: SUCCESS (0.048s) [90.308877] Starting dynamic subtest: vcs1 [90.312344] Dynamic subtest vcs1: SUCCESS (0.034s) [90.343726] Starting dynamic subtest: vecs0 [90.346942] Dynamic subtest vecs0: SUCCESS (0.025s) [90.381073] Starting dynamic subtest: ccs0 [90.384604] Dynamic subtest ccs0: SUCCESS (0.024s) [90.478650] Subtest before-wait: SUCCESS (0.392s) [90.643477] [004/142] (922s left) kms_atomic_transition (modeset-transition-fencing) [91.110706] Starting subtest: modeset-transition-fencing [91.114115] Starting dynamic subtest: 1x-outputs [120.537224] Dynamic subtest 1x-outputs: SUCCESS (29.428s) [120.928942] Subtest modeset-transition-fencing: SUCCESS (29.821s) [121.858537] [005/142] (891s left) kms_cursor_crc (cursor-rapid-movement-256x256) [122.807184] Starting subtest: cursor-rapid-movement-256x256 [122.839220] Starting dynamic subtest: pipe-A-eDP-1 [124.089972] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.252s) [124.271245] Starting dynamic subtest: pipe-D-eDP-1 [126.886269] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.649s) [126.889774] Subtest cursor-rapid-movement-256x256: SUCCESS (4.079s) [127.844325] [006/142] (885s left) prime_mmap_coherency (read) [128.235388] Starting subtest: read [133.642156] Subtest read: SUCCESS (5.406s) [133.837173] [007/142] (879s left) kms_cursor_crc (cursor-sliding-64x64) [134.578998] Starting subtest: cursor-sliding-64x64 [134.612066] Starting dynamic subtest: pipe-A-eDP-1 [139.719628] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.109s) [139.812632] Starting dynamic subtest: pipe-D-eDP-1 [145.779581] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.995s) [145.782970] Subtest cursor-sliding-64x64: SUCCESS (11.196s) [146.880956] [008/142] (866s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25) [147.360110] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25 [147.362971] Starting dynamic subtest: pipe-A-eDP-1 [147.453463] Dynamic subtest pipe-A-eDP-1: SKIP (0.084s) [147.486887] Starting dynamic subtest: pipe-B-eDP-1 [147.574343] Dynamic subtest pipe-B-eDP-1: SKIP (0.110s) [147.609252] Starting dynamic subtest: pipe-C-eDP-1 [147.635655] Dynamic subtest pipe-C-eDP-1: SKIP (0.043s) [147.673202] Starting dynamic subtest: pipe-D-eDP-1 [147.680206] Dynamic subtest pipe-D-eDP-1: SKIP (0.043s) [147.686794] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.306s) [147.790535] [009/142] (866s left) kms_addfb_basic (clobberred-modifier) [148.164544] Starting subtest: clobberred-modifier [148.170369] Subtest clobberred-modifier: SKIP (0.000s) [148.412138] [010/142] (865s left) gem_ppgtt (shrink-vs-evict-any) [148.653560] Starting subtest: shrink-vs-evict-any [156.581978] Subtest shrink-vs-evict-any: SUCCESS (7.923s) [156.751551] [011/142] (857s left) syncobj_timeline (wait-all-for-submit-snapshot) [157.133613] Starting subtest: wait-all-for-submit-snapshot [157.219398] Subtest wait-all-for-submit-snapshot: SUCCESS (0.086s) [157.390942] [012/142] (856s left) gem_mmap_gtt (isolation) [157.757518] Subtest isolation: SKIP (0.000s) [157.877842] [013/142] (856s left) kms_addfb_basic (too-wide) [158.372606] Starting subtest: too-wide [158.376272] Subtest too-wide: SUCCESS (0.000s) [158.533785] [014/142] (855s left) template (a) [158.909342] Starting subtest: A [158.912904] Subtest A: SUCCESS (0.000s) [159.036527] [015/142] (855s left) sw_sync (sync_expired_merge) [159.395712] Starting subtest: sync_expired_merge [160.471476] Subtest sync_expired_merge: SUCCESS (1.075s) [160.598526] [016/142] (853s left) gem_basic (bad-close) [160.970609] Starting subtest: bad-close [160.977856] Subtest bad-close: SUCCESS (0.000s) [161.094580] [017/142] (853s left) gem_exec_fair (basic-pace-solo) [161.499126] Subtest basic-pace-solo: SKIP (0.000s) [161.639290] [018/142] (852s left) gem_sync (basic-each) [162.055169] Starting subtest: basic-each [164.671487] Subtest basic-each: SUCCESS (2.611s) [164.948192] [019/142] (849s left) kms_flip (dpms-off-confusion) [165.350765] Starting subtest: dpms-off-confusion [165.379191] Starting dynamic subtest: A-eDP1 [174.647719] Dynamic subtest A-eDP1: SUCCESS (9.269s) [174.686915] Starting dynamic subtest: B-eDP1 [184.167900] Dynamic subtest B-eDP1: SUCCESS (9.516s) [184.208466] Starting dynamic subtest: C-eDP1 [193.257923] Dynamic subtest C-eDP1: SUCCESS (9.086s) [193.298340] Starting dynamic subtest: D-eDP1 [202.329767] Dynamic subtest D-eDP1: SUCCESS (9.068s) [202.356176] Subtest dpms-off-confusion: SUCCESS (37.009s) [203.948380] [020/142] (810s left) gem_exec_fence (syncobj-timeline-invalid-wait) [205.979075] Starting subtest: syncobj-timeline-invalid-wait [205.992820] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.010s) [206.666592] [021/142] (808s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_mc_ccs) [207.592038] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs [207.598835] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.002s) [209.039398] [022/142] (805s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs) [210.003375] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs [210.012744] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s) [211.394588] [023/142] (803s left) kms_frontbuffer_tracking (fbc-2p-rte) [211.909380] Starting subtest: fbc-2p-rte [211.916377] Subtest fbc-2p-rte: SKIP (0.000s) [212.077919] [024/142] (802s left) i915_module_load (resize-bar) [212.443528] Starting subtest: resize-bar [212.477270] Subtest resize-bar: SKIP (0.030s) [212.586546] [025/142] (802s left) prime_vgem (fence-read-hang) [213.004134] Subtest fence-read-hang: SKIP (0.000s) [213.163748] [026/142] (802s left) gem_ccs (suspend-resume) [213.561310] Starting subtest: suspend-resume [213.568056] Subtest suspend-resume: SKIP (0.000s) [213.700374] [027/142] (801s left) vc4/vc4_purgeable_bo (mark-unpurgeable-twice) [214.172279] Subtest mark-unpurgeable-twice: SKIP (0.000s) [214.302277] [028/142] (801s left) kms_cursor_legacy (short-flip-before-cursor-toggle) [214.876293] Starting subtest: short-flip-before-cursor-toggle [216.298546] Subtest short-flip-before-cursor-toggle: SUCCESS (1.420s) [217.245477] [029/142] (798s left) gem_exec_balancer (bonded-pair) [217.673336] Starting subtest: bonded-pair [217.680364] Subtest bonded-pair: SKIP (0.001s) [218.641627] [030/142] (796s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt) [219.154814] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt [234.001130] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (14.848s) [234.261747] [031/142] (781s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [234.814706] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [238.720238] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (3.904s) [239.227270] [032/142] (776s left) kms_force_connector_basic (force-connector-state) [239.613266] Starting subtest: force-connector-state [241.332529] Subtest force-connector-state: SUCCESS (1.719s) [242.080497] [033/142] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite) [242.614446] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite [242.621132] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [242.773872] [034/142] (773s left) kms_big_fb (yf-tiled-64bpp-rotate-180) [243.311410] Starting subtest: yf-tiled-64bpp-rotate-180 [243.318197] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s) [243.478259] [035/142] (772s left) i915_pm_rps (thresholds-idle) [243.877439] Starting subtest: thresholds-idle [243.881526] Starting dynamic subtest: gt0 [243.897511] Dynamic subtest gt0: SKIP (0.014s) [243.940984] Starting dynamic subtest: gt1 [243.948464] Dynamic subtest gt1: SKIP (0.003s) [243.955758] Subtest thresholds-idle: SKIP (0.027s) [244.066482] [036/142] (771s left) kms_lease (simple_lease) [244.547587] Starting subtest: simple_lease [244.551274] Starting dynamic subtest: pipe-A-eDP-1 [245.735517] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.181s) [245.780808] Starting dynamic subtest: pipe-B-eDP-1 [247.867630] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.126s) [247.914904] Starting dynamic subtest: pipe-C-eDP-1 [250.059839] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.180s) [250.097678] Starting dynamic subtest: pipe-D-eDP-1 [252.235453] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.167s) [252.239913] Subtest simple_lease: SUCCESS (7.689s) [253.203579] [037/142] (762s left) gem_caching (reads) [253.602593] Subtest reads: SKIP (0.000s) [253.714080] [038/142] (762s left) gem_exec_schedule (pi-common) [254.313422] Starting subtest: pi-common [254.316496] Starting dynamic subtest: rcs0 [254.462796] Dynamic subtest rcs0: SUCCESS (0.148s) [254.524537] Starting dynamic subtest: bcs0 [254.615775] Dynamic subtest bcs0: SUCCESS (0.149s) [254.677417] Starting dynamic subtest: vcs0 [254.766911] Dynamic subtest vcs0: SUCCESS (0.147s) [254.823393] Starting dynamic subtest: vcs1 [254.916583] Dynamic subtest vcs1: SUCCESS (0.145s) [254.981157] Starting dynamic subtest: vecs0 [255.066783] Dynamic subtest vecs0: SUCCESS (0.146s) [255.128147] Starting dynamic subtest: ccs0 [255.219829] Dynamic subtest ccs0: SUCCESS (0.148s) [255.224119] Subtest pi-common: SUCCESS (0.911s) [255.544279] [039/142] (760s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant) [256.033536] Starting subtest: coverage-vs-premult-vs-constant [257.763412] Starting dynamic subtest: pipe-A-eDP-1 [258.773336] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.008s) [258.820427] Starting dynamic subtest: pipe-D-eDP-1 [260.539477] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.752s) [260.543181] Subtest coverage-vs-premult-vs-constant: SUCCESS (4.504s) [261.483595] [040/142] (754s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt) [261.983291] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt [261.989640] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [262.137106] [041/142] (754s left) kms_plane (pixel-format) [262.609296] Starting subtest: pixel-format [262.614379] Starting dynamic subtest: pipe-A-planes [283.161220] Dynamic subtest pipe-A-planes: SUCCESS (20.542s) [283.202835] Starting dynamic subtest: pipe-B-planes [304.443676] Dynamic subtest pipe-B-planes: SUCCESS (21.271s) [304.446797] Subtest pixel-format: SUCCESS (41.834s) [305.395526] [042/142] (711s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [305.781662] Starting subtest: etime-single-wait-all-for-submit-submitted [305.883201] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.102s) [306.032719] [043/142] (710s left) dumb_buffer (create-valid-dumb) [306.406342] Starting subtest: create-valid-dumb [306.409946] Subtest create-valid-dumb: SUCCESS (0.000s) [306.544706] [044/142] (710s left) gem_wait (invalid-buf) [306.940759] Starting subtest: invalid-buf [306.944362] Subtest invalid-buf: SUCCESS (0.000s) [307.346603] [045/142] (709s left) i915_selftest (mock) [311.078699] Starting subtest: mock [311.082738] Starting dynamic subtest: sanitycheck [312.040711] Dynamic subtest sanitycheck: SUCCESS (0.961s) [312.067361] Starting dynamic subtest: shmem [312.783054] Dynamic subtest shmem: SUCCESS (0.740s) [312.815272] Starting dynamic subtest: fence [320.631997] Dynamic subtest fence: SUCCESS (7.843s) [320.658373] Starting dynamic subtest: scatterlist [322.387763] Dynamic subtest scatterlist: SUCCESS (1.752s) [322.414304] Starting dynamic subtest: syncmap [325.053855] Dynamic subtest syncmap: SUCCESS (2.663s) [325.076539] Starting dynamic subtest: uncore [325.782885] Dynamic subtest uncore: SUCCESS (0.725s) [325.809977] Starting dynamic subtest: ring [326.533623] Dynamic subtest ring: SUCCESS (0.747s) [326.561058] Starting dynamic subtest: engine [327.275435] Dynamic subtest engine: SUCCESS (0.738s) [327.302383] Starting dynamic subtest: timelines [345.761244] Dynamic subtest timelines: SUCCESS (18.481s) [345.787884] Starting dynamic subtest: requests [358.546692] Dynamic subtest requests: SUCCESS (12.781s) [358.572405] Starting dynamic subtest: objects [359.541292] Dynamic subtest objects: SUCCESS (0.990s) [359.568872] Starting dynamic subtest: phys [360.522788] Dynamic subtest phys: SUCCESS (0.978s) [360.549869] Starting dynamic subtest: dmabuf [361.529595] Dynamic subtest dmabuf: SUCCESS (1.002s) [361.557607] Starting dynamic subtest: vma [388.835111] Dynamic subtest vma: SUCCESS (27.301s) [388.884362] Starting dynamic subtest: evict [395.336706] Dynamic subtest evict: FAIL (6.460s) [395.341940] Subtest mock: FAIL (84.230s) [395.509793] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1609 [max: 8192] direct dependencies: 15266 [max: 32768] indirect dependencies: 79635 all direct dependencies: 286832 dependency chains: 20698 [max: 131072] dependency chain hlocks used: 80733 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 160 in-softirq chains: 514 in-process chains: 20024 stack-trace entries: 185870 [max: 1048576] number of stack traces: 9296 number of stack hash chains: 7060 combined max dependencies: 1660372875 hardirq-safe locks: 88 hardirq-unsafe locks: 963 softirq-safe locks: 180 softirq-unsafe locks: 876 irq-safe locks: 194 irq-unsafe locks: 963 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 291 softirq-read-safe locks: 7 softirq-read-unsafe locks: 286 irq-read-safe locks: 7 irq-read-unsafe locks: 291 uncategorized locks: 268 unused locks: 1 max locking depth: 16 max bfs queue depth: 397 max lock class index: 1783 chain lookup misses: 27765 chain lookup hits: 208179807 cyclic checks: 27321 redundant checks: 0 redundant links: 0 find-mask forwards checks: 4158 find-mask backwards checks: 3010 hardirq on events: 116557580 hardirq off events: 116557558 redundant hardirq ons: 158 redundant hardirq offs: 23 softirq on events: 686596 softirq off events: 686596 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 475 zapped lock chains: 6584 large chain blocks: 1 [395.514289] Closing watchdogs Done.