[49.194731] Initializing watchdogs [49.194971] /dev/watchdog0 [49.228375] [001/142] (960s left) gem_eio (in-flight-immediate) [49.607839] Starting subtest: in-flight-immediate [50.678695] Subtest in-flight-immediate: SUCCESS (1.090s) [50.936560] [002/142] (958s left) gem_pxp (create-valid-protected-context) [53.937416] Subtest create-valid-protected-context: SKIP (0.000s) [54.077456] [003/142] (955s left) prime_busy (before-wait) [54.377123] Starting subtest: before-wait [54.396125] Starting dynamic subtest: rcs0 [54.414833] Dynamic subtest rcs0: SUCCESS (0.021s) [54.449725] Starting dynamic subtest: bcs0 [54.471344] Dynamic subtest bcs0: SUCCESS (0.017s) [54.504719] Starting dynamic subtest: vcs0 [54.525647] Dynamic subtest vcs0: SUCCESS (0.023s) [54.557768] Starting dynamic subtest: vecs0 [54.577343] Dynamic subtest vecs0: SUCCESS (0.019s) [54.596149] Subtest before-wait: SUCCESS (0.211s) [54.683114] [004/142] (955s left) kms_atomic_transition (modeset-transition-fencing) [55.047085] Starting subtest: modeset-transition-fencing [55.068268] Starting dynamic subtest: 1x-outputs [60.211087] Dynamic subtest 1x-outputs: SUCCESS (5.169s) [60.324433] Subtest modeset-transition-fencing: SUCCESS (5.278s) [60.702643] [005/142] (949s left) kms_cursor_crc (cursor-rapid-movement-256x256) [61.116066] Starting subtest: cursor-rapid-movement-256x256 [61.137768] Starting dynamic subtest: pipe-A-DP-1 [61.630368] Dynamic subtest pipe-A-DP-1: SUCCESS (0.509s) [61.716656] Starting dynamic subtest: pipe-C-DP-1 [62.558437] Dynamic subtest pipe-C-DP-1: SUCCESS (0.862s) [62.579786] Subtest cursor-rapid-movement-256x256: SUCCESS (1.446s) [62.940739] [006/142] (947s left) prime_mmap_coherency (read) [63.239314] Starting subtest: read [68.648988] Subtest read: SUCCESS (5.412s) [68.799315] [007/142] (941s left) kms_cursor_crc (cursor-sliding-64x64) [69.174921] Starting subtest: cursor-sliding-64x64 [69.195854] Starting dynamic subtest: pipe-A-DP-1 [72.843627] Dynamic subtest pipe-A-DP-1: SUCCESS (3.662s) [72.950041] Starting dynamic subtest: pipe-C-DP-1 [76.935359] Dynamic subtest pipe-C-DP-1: SUCCESS (4.011s) [76.962080] Subtest cursor-sliding-64x64: SUCCESS (7.749s) [77.320181] [008/142] (933s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25) [77.703040] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25 [77.722046] Starting dynamic subtest: pipe-A-DP-1 [77.762765] Dynamic subtest pipe-A-DP-1: SKIP (0.032s) [77.807844] Starting dynamic subtest: pipe-B-DP-1 [77.850401] Dynamic subtest pipe-B-DP-1: SKIP (0.082s) [77.884956] Starting dynamic subtest: pipe-C-DP-1 [77.922362] Dynamic subtest pipe-C-DP-1: SKIP (0.081s) [77.962225] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.204s) [78.086669] [009/142] (932s left) kms_addfb_basic (clobberred-modifier) [78.382088] Starting subtest: clobberred-modifier [78.402878] Subtest clobberred-modifier: SUCCESS (0.001s) [78.582149] [010/142] (932s left) gem_ppgtt (shrink-vs-evict-any) [78.880433] Starting subtest: shrink-vs-evict-any [84.147913] Subtest shrink-vs-evict-any: SUCCESS (5.085s) [84.232630] [011/142] (926s left) syncobj_timeline (wait-all-for-submit-snapshot) [84.522819] Starting subtest: wait-all-for-submit-snapshot [84.592430] Subtest wait-all-for-submit-snapshot: SUCCESS (0.083s) [84.702777] [012/142] (926s left) gem_mmap_gtt (isolation) [84.998566] Starting subtest: isolation [85.059517] Subtest isolation: SUCCESS (0.004s) [85.140850] [013/142] (925s left) kms_addfb_basic (too-wide) [85.515364] Starting subtest: too-wide [85.536245] Subtest too-wide: SUCCESS (0.000s) [85.644372] [014/142] (925s left) template (a) [85.956869] Starting subtest: A [85.975633] Subtest A: SUCCESS (0.000s) [86.063139] [015/142] (924s left) sw_sync (sync_expired_merge) [86.338737] Starting subtest: sync_expired_merge [87.402787] Subtest sync_expired_merge: SUCCESS (1.074s) [87.498646] [016/142] (923s left) gem_basic (bad-close) [87.787630] Starting subtest: bad-close [87.829109] Subtest bad-close: SUCCESS (0.000s) [87.910760] [017/142] (923s left) gem_exec_fair (basic-pace-solo) [88.387415] Starting subtest: basic-pace-solo [88.406507] Starting dynamic subtest: rcs0 [93.008708] Dynamic subtest rcs0: SUCCESS (4.804s) [93.027669] Subtest basic-pace-solo: SUCCESS (4.805s) [93.231424] [018/142] (918s left) gem_sync (basic-each) [93.701674] Starting subtest: basic-each [95.996557] Subtest basic-each: SUCCESS (2.472s) [96.215394] [019/142] (915s left) kms_flip (dpms-off-confusion) [96.593406] Starting subtest: dpms-off-confusion [96.615352] Starting dynamic subtest: A-DP1 [107.152729] Dynamic subtest A-DP1: SUCCESS (10.557s) [107.185655] Starting dynamic subtest: B-DP1 [117.787878] Dynamic subtest B-DP1: SUCCESS (10.467s) [117.825974] Starting dynamic subtest: C-DP1 [128.217204] Dynamic subtest C-DP1: SUCCESS (10.447s) [128.237950] Subtest dpms-off-confusion: SUCCESS (31.479s) [128.461631] [020/142] (882s left) gem_exec_fence (syncobj-timeline-invalid-wait) [129.366846] Starting subtest: syncobj-timeline-invalid-wait [129.385457] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s) [129.606241] [021/142] (881s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_mc_ccs) [130.077767] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs [130.121741] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.001s) [130.690659] [022/142] (880s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs) [131.178681] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs [131.240620] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s) [131.775448] [023/142] (879s left) kms_frontbuffer_tracking (fbc-2p-rte) [132.216192] Starting subtest: fbc-2p-rte [132.258518] Subtest fbc-2p-rte: SKIP (0.000s) [132.359577] [024/142] (879s left) i915_module_load (resize-bar) [132.637132] Starting subtest: resize-bar [132.698821] Subtest resize-bar: SKIP (0.012s) [132.805843] [025/142] (879s left) prime_vgem (fence-read-hang) [133.140710] Subtest fence-read-hang: SKIP (0.000s) [133.242824] [026/142] (878s left) gem_ccs (suspend-resume) [133.559852] Subtest suspend-resume: SKIP (0.000s) [133.659571] [027/142] (878s left) vc4/vc4_purgeable_bo (mark-unpurgeable-twice) [134.032051] Subtest mark-unpurgeable-twice: SKIP (0.000s) [134.156533] [028/142] (878s left) kms_cursor_legacy (short-flip-before-cursor-toggle) [134.553550] Starting subtest: short-flip-before-cursor-toggle [135.632499] Subtest short-flip-before-cursor-toggle: SUCCESS (1.067s) [135.991853] [029/142] (876s left) gem_exec_balancer (bonded-pair) [136.290783] Starting subtest: bonded-pair [140.237067] Subtest bonded-pair: SUCCESS (3.774s) [144.168547] [030/142] (868s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt) [144.605578] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt [144.642654] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [144.750526] [031/142] (867s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [145.149477] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [145.186746] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [145.300122] [032/142] (867s left) kms_force_connector_basic (force-connector-state) [145.614711] Subtest force-connector-state: SKIP (0.000s) [145.698141] [033/142] (867s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite) [146.094690] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite [146.137739] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [146.243370] [034/142] (866s left) kms_big_fb (yf-tiled-64bpp-rotate-180) [146.664432] Starting subtest: yf-tiled-64bpp-rotate-180 [146.703866] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s) [146.806841] [035/142] (866s left) i915_pm_rps (thresholds-idle) [147.101485] Starting subtest: thresholds-idle [147.120161] Starting dynamic subtest: gt0 [147.163479] Dynamic subtest gt0: SKIP (0.004s) [147.204638] Subtest thresholds-idle: SKIP (0.005s) [147.305715] [036/142] (865s left) kms_lease (simple_lease) [147.694375] Starting subtest: simple_lease [147.715239] Starting dynamic subtest: pipe-A-DP-1 [148.170341] Dynamic subtest pipe-A-DP-1: SUCCESS (0.485s) [148.205305] Starting dynamic subtest: pipe-B-DP-1 [148.776172] Dynamic subtest pipe-B-DP-1: SUCCESS (0.591s) [148.813577] Starting dynamic subtest: pipe-C-DP-1 [149.368669] Dynamic subtest pipe-C-DP-1: SUCCESS (0.607s) [149.388956] Subtest simple_lease: SUCCESS (1.691s) [149.731208] [037/142] (863s left) gem_caching (reads) [150.032439] Starting subtest: reads [156.926920] Subtest reads: SUCCESS (6.898s) [157.050930] [038/142] (856s left) gem_exec_schedule (pi-common) [157.508346] Starting subtest: pi-common [157.530050] Starting dynamic subtest: rcs0 [157.593205] Dynamic subtest rcs0: SUCCESS (0.092s) [157.626931] Starting dynamic subtest: bcs0 [157.666138] Dynamic subtest bcs0: SUCCESS (0.090s) [157.701120] Starting dynamic subtest: vcs0 [157.758801] Dynamic subtest vcs0: SUCCESS (0.092s) [157.795538] Starting dynamic subtest: vecs0 [157.846678] Dynamic subtest vecs0: SUCCESS (0.089s) [157.865255] Subtest pi-common: SUCCESS (0.367s) [158.081306] [039/142] (855s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant) [158.443119] Starting subtest: coverage-vs-premult-vs-constant [158.750029] Starting dynamic subtest: pipe-A-DP-1 [159.192086] Dynamic subtest pipe-A-DP-1: SUCCESS (0.430s) [159.228539] Starting dynamic subtest: pipe-C-DP-1 [159.888548] Dynamic subtest pipe-C-DP-1: SUCCESS (0.679s) [159.909444] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.423s) [160.294165] [040/142] (853s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt) [160.709054] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt [160.748905] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [160.860622] [041/142] (852s left) kms_plane (pixel-format) [161.220455] Starting subtest: pixel-format [161.239428] Starting dynamic subtest: pipe-A-planes [167.975080] Dynamic subtest pipe-A-planes: SUCCESS (6.758s) [168.027832] Starting dynamic subtest: pipe-B-planes [174.884535] Dynamic subtest pipe-B-planes: SUCCESS (6.885s) [174.905224] Subtest pixel-format: SUCCESS (13.651s) [175.254534] [042/142] (838s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted) [175.542035] Starting subtest: etime-single-wait-all-for-submit-submitted [175.618619] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.104s) [175.750740] [043/142] (837s left) dumb_buffer (create-valid-dumb) [176.048790] Starting subtest: create-valid-dumb [176.067823] Subtest create-valid-dumb: SUCCESS (0.000s) [176.157893] [044/142] (837s left) gem_wait (invalid-buf) [176.449185] Starting subtest: invalid-buf [176.477114] Subtest invalid-buf: SUCCESS (0.000s) [176.730728] [045/142] (837s left) i915_selftest (mock) [178.582111] Starting subtest: mock [178.600953] Starting dynamic subtest: sanitycheck [179.140474] Dynamic subtest sanitycheck: SUCCESS (0.560s) [179.170849] Starting dynamic subtest: shmem [179.602394] Dynamic subtest shmem: SUCCESS (0.461s) [179.640217] Starting dynamic subtest: fence [187.389582] Dynamic subtest fence: SUCCESS (7.784s) [187.420739] Starting dynamic subtest: scatterlist [188.852123] Dynamic subtest scatterlist: SUCCESS (1.459s) [188.882990] Starting dynamic subtest: syncmap [191.432981] Dynamic subtest syncmap: SUCCESS (2.581s) [191.469489] Starting dynamic subtest: uncore [191.894731] Dynamic subtest uncore: SUCCESS (0.463s) [191.926408] Starting dynamic subtest: ring [192.365338] Dynamic subtest ring: SUCCESS (0.468s) [192.394280] Starting dynamic subtest: engine [192.826224] Dynamic subtest engine: SUCCESS (0.456s) [192.857731] Starting dynamic subtest: timelines [203.338132] Dynamic subtest timelines: SUCCESS (10.512s) [203.369994] Starting dynamic subtest: requests [206.369478] Dynamic subtest requests: SUCCESS (3.026s) [206.400345] Starting dynamic subtest: objects [206.967252] Dynamic subtest objects: SUCCESS (0.595s) [206.998955] Starting dynamic subtest: phys [207.560514] Dynamic subtest phys: SUCCESS (0.596s) [207.595273] Starting dynamic subtest: dmabuf [208.156155] Dynamic subtest dmabuf: SUCCESS (0.594s) [208.185748] Starting dynamic subtest: vma [239.982041] Dynamic subtest vma: SUCCESS (31.821s) [240.012827] Starting dynamic subtest: evict [243.799409] Dynamic subtest evict: FAIL (3.426s) [243.856518] Subtest mock: FAIL (64.829s) [243.928091] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1580 [max: 8192] direct dependencies: 14342 [max: 32768] indirect dependencies: 76939 all direct dependencies: 244922 dependency chains: 19345 [max: 131072] dependency chain hlocks used: 74645 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 123 in-softirq chains: 522 in-process chains: 18700 stack-trace entries: 172426 [max: 1048576] number of stack traces: 8593 number of stack hash chains: 6693 combined max dependencies: 1212797252 hardirq-safe locks: 68 hardirq-unsafe locks: 945 softirq-safe locks: 160 softirq-unsafe locks: 865 irq-safe locks: 176 irq-unsafe locks: 945 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 284 softirq-read-safe locks: 7 softirq-read-unsafe locks: 279 irq-read-safe locks: 7 irq-read-unsafe locks: 284 uncategorized locks: 276 unused locks: 1 max locking depth: 17 max bfs queue depth: 365 max lock class index: 1736 chain lookup misses: 25010 chain lookup hits: 103896046 cyclic checks: 24693 redundant checks: 0 redundant links: 0 find-mask forwards checks: 3610 find-mask backwards checks: 2941 hardirq on events: 62446971 hardirq off events: 62446967 redundant hardirq ons: 38 redundant hardirq offs: 55 softirq on events: 382417 softirq off events: 382417 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 452 zapped lock chains: 5602 large chain blocks: 1 [243.953681] Closing watchdogs Done.