lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list] 
Message-ID: <20171130134451.vnuby6rxev252aif@wfg-t540p.sh.intel.com>
Date:   Thu, 30 Nov 2017 21:44:51 +0800
From:   Fengguang Wu <fengguang.wu@...el.com>
To:     linux-kernel@...r.kernel.org
Cc:     Matthew Wilcox <mawilcox@...rosoft.com>,
        Heikki Krogerus <heikki.krogerus@...ux.intel.com>,
        Dan Carpenter <dan.carpenter@...cle.com>, lkp@...org
Subject: [string_selftest_init] watchdog: BUG: soft lockup - CPU#0 stuck for
 23s! [swapper:1]

Hello,

FYI this happens in mainline kernel 4.15.0-rc1.
It at least dates back to v4.14-rc1 .

It occurs in 2 out of 3 boots.

[  107.032959] Asymmetric key parser 'x509' registered
[  107.058214] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[  107.078095] io scheduler noop registered
[  107.107701] io scheduler cfq registered (default)
[  107.120795] io scheduler mq-deadline registered
[  132.280220] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper:1]
[  132.280220] Modules linked in:
[  132.280220] CPU: 0 PID: 1 Comm: swapper Not tainted 4.15.0-rc1 #110
[  132.280220] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[  132.280220] task: ffff880018e98040 task.stack: ffff880018ea0000
[  132.280220] RIP: 0010:__asan_load8+0x64/0x7e
[  132.280220] RSP: 0000:ffff880018ea7dd0 EFLAGS: 00000207 ORIG_RAX: ffffffffffffff11
[  132.280220] RAX: 1ffff10001c8dd00 RBX: 000000000000007d RCX: ffffffff84f2df6d
[  132.280220] RDX: b1b2b3b4b5b6b7b8 RSI: dffffc0000000000 RDI: ffff88000e46eba0
[  132.280220] RBP: ffff88000e46e848 R08: 0000000000000007 R09: ffff88000e46e848
[  132.280220] R10: 0000000000000008 R11: 0000000000000001 R12: 000000000000007d
[  132.280220] R13: ffff88000e46eba0 R14: 000000000000006b R15: 0000000000000056
[  132.280220] FS:  0000000000000000(0000) GS:ffffffff84846000(0000) knlGS:0000000000000000
[  132.280220] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  132.280220] CR2: 0000000000000000 CR3: 0000000007a17000 CR4: 00000000000006f0
[  132.280220] Call Trace:
[  132.280220]  string_selftest_init+0x369/0x4ab:
						memset64_selftest at lib/test_string.c:92
						 (inlined by) string_selftest_init at lib/test_string.c:129
[  132.280220]  ? prandom_init+0xcf/0xcf:
						string_selftest_init at lib/test_string.c:115
[  132.280220]  ? do_early_param+0xde/0xde:
						repair_env_string at init/main.c:244
[  132.280220]  do_one_initcall+0xfd/0x202:
						do_one_initcall at init/main.c:826
[  132.280220]  ? initcall_blacklisted+0x178/0x178:
						do_one_initcall at init/main.c:815
[  132.280220]  ? ___might_sleep+0xa8/0x1f3:
						___might_sleep at kernel/sched/core.c:6027
[  132.280220]  ? __schedule_bug+0x9e/0x9e:
						___might_sleep at kernel/sched/core.c:6018
[  132.280220]  ? __memset+0x12/0x30:
						__memset at arch/x86/lib/memset_64.S:38
[  132.280220]  ? __might_sleep+0x2f/0xbf:
						__might_sleep at kernel/sched/core.c:6006
[  132.280220]  kernel_init_freeable+0x1a5/0x273:
						do_initcall_level at init/main.c:891
						 (inlined by) do_initcalls at init/main.c:900
						 (inlined by) do_basic_setup at init/main.c:918
						 (inlined by) kernel_init_freeable at init/main.c:1066
[  132.280220]  ? rest_init+0xa9/0xa9:
						kernel_init at init/main.c:990
[  132.280220]  kernel_init+0xc/0x146:
						kernel_init at init/main.c:995
[  132.280220]  ? rest_init+0xa9/0xa9:
						kernel_init at init/main.c:990
[  132.280220]  ret_from_fork+0x1f/0x30:
						ret_from_fork at arch/x86/entry/entry_64.S:447
[  132.280220] Code: c1 e9 03 41 80 3c 31 00 75 15 48 c1 e8 03 31 d2 8a 04 30 84 c0 74 08 31 d2 44 38 c0 0f 9e c2 83 e2 01 eb 10 48 c1 e8 03 8a 04 30 <84> c0 74 15 3c 07 0f 9e c2 84 d2 74 0c 31 d2 be 08 00 00 00 e9
[  132.280220] Kernel panic - not syncing: softlockup: hung tasks
[  132.280220] CPU: 0 PID: 1 Comm: swapper Tainted: G             L   4.15.0-rc1 #110
[  132.280220] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[  132.280220] Call Trace:
[  132.280220]  <IRQ>
[  132.280220]  panic+0x165/0x324:
						panic at kernel/panic.c:194
[  132.280220]  ? refcount_error_report+0x12e/0x12e:
						panic at kernel/panic.c:135
[  132.280220]  ? vprintk_func+0x93/0x9b:
						vprintk_func at kernel/printk/printk_safe.c:380
[  132.280220]  ? watchdog_timer_fn+0x1d2/0x212:
						watchdog_timer_fn at kernel/watchdog.c:443
[  132.280220]  watchdog_timer_fn+0x1e3/0x212:
						watchdog_timer_fn at kernel/watchdog.c:444
[  132.280220]  hrtimer_run_queues+0xe9/0x1cc:
						__preempt_count_add at arch/x86/include/asm/preempt.h:76
						 (inlined by) __run_hrtimer at kernel/time/hrtimer.c:1213
						 (inlined by) __hrtimer_run_queues at kernel/time/hrtimer.c:1275
						 (inlined by) hrtimer_run_queues at kernel/time/hrtimer.c:1414
[  132.280220]  run_local_timers+0x5c/0xc7:
						run_local_timers at kernel/time/timer.c:1698
[  132.280220]  ? add_timer_on+0x1a9/0x1a9:
						run_local_timers at kernel/time/timer.c:1695
[  132.280220]  ? __irqentry_text_end+0x6/0x6:
						__do_softirq at kernel/softirq.c:243
[  132.280220]  ? ktime_get+0x4f/0x5a:
						ktime_get at kernel/time/timekeeping.c:768
[  132.280220]  update_process_times+0x20/0x52:
						update_process_times at kernel/time/timer.c:1630
[  132.280220]  tick_handle_periodic+0x1c/0x69:
						tick_handle_periodic at kernel/time/tick-common.c:104
[  132.280220]  smp_apic_timer_interrupt+0x52/0x60:
						exiting_irq at arch/x86/include/asm/apic.h:540
						 (inlined by) smp_apic_timer_interrupt at arch/x86/kernel/apic/apic.c:1052
[  132.280220]  apic_timer_interrupt+0xa7/0xb0:
						apic_timer_interrupt at arch/x86/entry/entry_64.S:795
[  132.280220]  </IRQ>
[  132.280220] RIP: 0010:__asan_load8+0x64/0x7e
[  132.280220] RSP: 0000:ffff880018ea7dd0 EFLAGS: 00000207 ORIG_RAX: ffffffffffffff11
[  132.280220] RAX: 1ffff10001c8dd00 RBX: 000000000000007d RCX: ffffffff84f2df6d
[  132.280220] RDX: b1b2b3b4b5b6b7b8 RSI: dffffc0000000000 RDI: ffff88000e46eba0
[  132.280220] RBP: ffff88000e46e848 R08: 0000000000000007 R09: ffff88000e46e848
[  132.280220] R10: 0000000000000008 R11: 0000000000000001 R12: 000000000000007d
[  132.280220] R13: ffff88000e46eba0 R14: 000000000000006b R15: 0000000000000056
[  132.280220]  ? string_selftest_init+0x369/0x4ab:
						memset64_selftest at lib/test_string.c:92
						 (inlined by) string_selftest_init at lib/test_string.c:129
[  132.280220]  string_selftest_init+0x369/0x4ab:
						memset64_selftest at lib/test_string.c:92
						 (inlined by) string_selftest_init at lib/test_string.c:129
[  132.280220]  ? prandom_init+0xcf/0xcf:
						string_selftest_init at lib/test_string.c:115
[  132.280220]  ? do_early_param+0xde/0xde:
						repair_env_string at init/main.c:244
[  132.280220]  do_one_initcall+0xfd/0x202:
						do_one_initcall at init/main.c:826
[  132.280220]  ? initcall_blacklisted+0x178/0x178:
						do_one_initcall at init/main.c:815
[  132.280220]  ? ___might_sleep+0xa8/0x1f3:
						___might_sleep at kernel/sched/core.c:6027
[  132.280220]  ? __schedule_bug+0x9e/0x9e:
						___might_sleep at kernel/sched/core.c:6018
[  132.280220]  ? __memset+0x12/0x30:
						__memset at arch/x86/lib/memset_64.S:38
[  132.280220]  ? __might_sleep+0x2f/0xbf:
						__might_sleep at kernel/sched/core.c:6006
[  132.280220]  kernel_init_freeable+0x1a5/0x273:
						do_initcall_level at init/main.c:891
						 (inlined by) do_initcalls at init/main.c:900
						 (inlined by) do_basic_setup at init/main.c:918
						 (inlined by) kernel_init_freeable at init/main.c:1066
[  132.280220]  ? rest_init+0xa9/0xa9:
						kernel_init at init/main.c:990
[  132.280220]  kernel_init+0xc/0x146:
						kernel_init at init/main.c:995
[  132.280220]  ? rest_init+0xa9/0xa9:
						kernel_init at init/main.c:990
[  132.280220]  ret_from_fork+0x1f/0x30:
						ret_from_fork at arch/x86/entry/entry_64.S:447
[  132.280220] Kernel Offset: 0x2600000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff)

Attached the full dmesg, kconfig and reproduce scripts.

Thanks,
Fengguang

View attachment "dmesg-vm-lkp-wsx03-yocto-x86_64-12:20171128065524:x86_64-randconfig-a0-11280544:4.15.0-rc1:110" of type "text/plain" (36233 bytes)

View attachment ".config" of type "text/plain" (97744 bytes)

View attachment "job-script" of type "text/plain" (3902 bytes)

View attachment "reproduce-vm-lkp-wsx03-yocto-x86_64-12:20171128065524:x86_64-randconfig-a0-11280544:4.15.0-rc1:110" of type "text/plain" (2087 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ