[test_drm_mm_init] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]

From: Fengguang Wu
Date: Mon Nov 06 2017 - 23:46:11 EST



Hi Chris,

Here's a warning in v4.14-rc8 -- it's not necessarily a new bug.

[ 134.914567] console [ttyS0] enabled
[ 134.943214] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 134.974836] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 135.000528] drm_mm: Testing DRM range manger (struct drm_mm), with random_seed=0xe5165cd4 max_iterations=8192 max_prime=128
[ 135.001981] drm_mm: igt_sanitycheck - ok!
[ 216.830054] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
[ 216.834261] Modules linked in:
[ 216.834261] CPU: 0 PID: 1 Comm: swapper Not tainted 4.14.0-rc8 #65
[ 216.834261] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 216.834261] task: ffff88000037c040 task.stack: ffff880000380000
[ 216.834261] RIP: 0010:__sanitizer_cov_trace_pc+0x8/0x56
[ 216.834261] RSP: 0000:ffff880000387a80 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
[ 216.834261] RAX: ffffc90000915c20 RBX: ffffc90000915c00 RCX: ffff88000037c040
[ 216.834261] RDX: 0000000000000000 RSI: dffffc0000000000 RDI: ffffc90000915cc0
[ 216.834261] RBP: 0000000000000a9b R08: 0000000000000007 R09: fffffbfff1fe06b9
[ 216.834261] R10: fffffbfff1fe06b9 R11: 0000000000000000 R12: 0000000000000005
[ 216.834261] R13: 0000000000000002 R14: ffff880000387c68 R15: ffffc90000915ca0
[ 216.834261] FS: 0000000000000000(0000) GS:ffffffff8f24f000(0000) knlGS:0000000000000000
[ 216.834261] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 216.834261] CR2: 0000000000000000 CR3: 000000001ae20000 CR4: 00000000000006b0
[ 216.834261] Call Trace:
[ 216.834261] assert_continuous+0x9e/0x2f3
[ 216.834261] __igt_insert+0x59d/0x907
[ 216.834261] ? igt_align+0x2db/0x2db
[ 216.834261] ? lock_release+0x22f/0x3d7
[ 216.834261] ? ftrace_likely_update+0x99/0xbe
[ 216.834261] ? pvclock_clocksource_read+0x19d/0x224
[ 216.834261] ? ftrace_likely_update+0x99/0xbe
[ 216.834261] ? lock_release+0x22f/0x3d7
[ 216.834261] ? ftrace_likely_update+0x99/0xbe
[ 216.834261] igt_insert+0xd9/0x144
[ 216.834261] test_drm_mm_init+0x221/0x3bc
[ 216.834261] ? drm_kms_helper_init+0x70/0x70
[ 216.834261] do_one_initcall+0x14d/0x30f
[ 216.834261] ? initcall_blacklisted+0x1a7/0x1a7
[ 216.834261] ? lock_downgrade+0x279/0x279
[ 216.834261] kernel_init_freeable+0x24a/0x3f6
[ 216.834261] ? rest_init+0x10d/0x10d
[ 216.834261] kernel_init+0x11/0x244
[ 216.834261] ? rest_init+0x10d/0x10d
[ 216.834261] ret_from_fork+0x25/0x30
[ 216.834261] Code: 75 08 4c 89 ea 4c 89 f7 45 31 ed e8 d6 63 19 00 e8 0e 00 00 00 44 89 e8 5b 5d 41 5c 41 5d 41 5e 41 5f c3 48 8b 0c 25 40 70 25 8f <48> 85 c9 74 48 8b 05 c9 bc c9 01 a9 00 01 1f 00 75 3b 8b 81 f8 [ 216.834261] Kernel panic - not syncing: softlockup: hung tasks
[ 216.834261] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.14.0-rc8 #65
[ 216.834261] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 216.834261] Call Trace:
[ 216.834261] <IRQ>
[ 216.834261] panic+0x1f4/0x4ab
[ 216.834261] ? __warn+0x1e1/0x1e1
[ 216.834261] ? watchdog+0x1a/0x1a
[ 216.834261] watchdog_timer_fn+0x3c0/0x3ef
[ 216.834261] __hrtimer_run_queues+0x351/0x59e
[ 216.834261] ? __hrtimer_get_remaining+0x8d/0x8d
[ 216.834261] ? read_seqcount_retry+0x2e/0x33
[ 216.834261] ? ktime_get_update_offsets_now+0x178/0x18a
[ 216.834261] hrtimer_interrupt+0xbc/0x2be
[ 216.834261] ? hrtimer_get_next_event+0x94/0x94
[ 216.834261] smp_apic_timer_interrupt+0x199/0x2c1
[ 216.834261] apic_timer_interrupt+0x89/0x90
[ 216.834261] </IRQ>
[ 216.834261] RIP: 0010:__sanitizer_cov_trace_pc+0x8/0x56
[ 216.834261] RSP: 0000:ffff880000387a80 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
[ 216.834261] RAX: ffffc90000915c20 RBX: ffffc90000915c00 RCX: ffff88000037c040
[ 216.834261] RDX: 0000000000000000 RSI: dffffc0000000000 RDI: ffffc90000915cc0
[ 216.834261] RBP: 0000000000000a9b R08: 0000000000000007 R09: fffffbfff1fe06b9
[ 216.834261] R10: fffffbfff1fe06b9 R11: 0000000000000000 R12: 0000000000000005
[ 216.834261] R13: 0000000000000002 R14: ffff880000387c68 R15: ffffc90000915ca0
[ 216.834261] assert_continuous+0x9e/0x2f3
[ 216.834261] __igt_insert+0x59d/0x907
[ 216.834261] ? igt_align+0x2db/0x2db
[ 216.834261] ? lock_release+0x22f/0x3d7
[ 216.834261] ? ftrace_likely_update+0x99/0xbe
[ 216.834261] ? pvclock_clocksource_read+0x19d/0x224
[ 216.834261] ? ftrace_likely_update+0x99/0xbe
[ 216.834261] ? lock_release+0x22f/0x3d7
[ 216.834261] ? ftrace_likely_update+0x99/0xbe
[ 216.834261] igt_insert+0xd9/0x144
[ 216.834261] test_drm_mm_init+0x221/0x3bc
[ 216.834261] ? drm_kms_helper_init+0x70/0x70
[ 216.834261] do_one_initcall+0x14d/0x30f
[ 216.834261] ? initcall_blacklisted+0x1a7/0x1a7
[ 216.834261] ? lock_downgrade+0x279/0x279
[ 216.834261] kernel_init_freeable+0x24a/0x3f6
[ 216.834261] ? rest_init+0x10d/0x10d
[ 216.834261] kernel_init+0x11/0x244
[ 216.834261] ? rest_init+0x10d/0x10d
[ 216.834261] ret_from_fork+0x25/0x30
[ 216.834261] Kernel Offset: 0xc400000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff)

Thanks,
Fengguang