linux-kernel - [migration_cpu_stop] WARNING: CPU: 0 PID: 11 at kernel/sched/core.c:1187 set_task

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>] [<thread-prev] [thread-next>] [day] [month] [year] [list]

Date:   Tue, 21 Nov 2017 20:04:20 +0800
From:   Fengguang Wu <fengguang.wu@...el.com>
To:     Jiri Olsa <jolsa@...hat.com>
Cc:     Ingo Molnar <mingo@...hat.com>,
        Peter Zijlstra <peterz@...radead.org>,
        Linus Torvalds <torvalds@...ux-foundation.org>,
        Thomas Gleixner <tglx@...utronix.de>,
        "Paul E. McKenney" <paulmck@...ux.vnet.ibm.com>,
        Luca Abeni <luca.abeni@...tannapisa.it>,
        Tejun Heo <tj@...nel.org>,
        Matt Fleming <matt@...eblueprint.co.uk>,
        Nicolas Pitre <nicolas.pitre@...aro.org>,
        linux-kernel@...r.kernel.org, lkp@...org
Subject: [migration_cpu_stop] WARNING: CPU: 0 PID: 11 at
 kernel/sched/core.c:1187 set_task_cpu+0x257/0x6b6

Hello,

FYI this happens in mainline kernel 4.14.0-00902-g43ff2f4.
It happens since 4.13.

It occurs in 7 out of 36 boots.

[   58.171538] no ifx modem active;
[   58.211860] ACPI: Preparing to enter system sleep state S5
[   58.216823] reboot: Power down
[   58.244526] acpi_power_off called
[   61.460467] ------------[ cut here ]------------
[   61.465481] WARNING: CPU: 0 PID: 11 at kernel/sched/core.c:1187 set_task_cpu+0x257/0x6b6:
						set_task_cpu at kernel/sched/core.c:1187
[   61.476692] Modules linked in:
[   61.479745] CPU: 0 PID: 11 Comm: migration/0 Not tainted 4.14.0-00902-g43ff2f4 #1
[   61.487252] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   61.495323] task: ffff880016c00000 task.stack: ffff880016c08000
[   61.501310] RIP: 0010:set_task_cpu+0x257/0x6b6:
						set_task_cpu at kernel/sched/core.c:1187
[   61.505662] RSP: 0018:ffff880016c0fcd0 EFLAGS: 00010096
[   61.510814] RAX: 0000000000000007 RBX: ffff88000f14b000 RCX: 0000000000000000
[   61.519499] RDX: 1ffffffff4e1cc00 RSI: ffff88000f14b730 RDI: ffffffffa70e6794
[   61.526823] RBP: ffff880016c0fd00 R08: ffff880016c0fd98 R09: 0000000000000001
[   61.533687] R10: ffff88000ac77970 R11: 0000000000000001 R12: 0000000000000001
[   61.542113] R13: ffff88000f14b064 R14: ffff880016c0fd98 R15: 0000000000000001
[   61.548884] FS:  0000000000000000(0000) GS:ffff880017400000(0000) knlGS:0000000000000000
[   61.556733] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   61.562275] CR2: 00007f3eca6152c0 CR3: 0000000007a15003 CR4: 00000000000206b0
[   61.569074] Call Trace:
[   61.571577]  move_queued_task+0x123/0x3ab:
						move_queued_task at kernel/sched/sched.h:943 (discriminator 9)
[   61.575591]  migration_cpu_stop+0x216/0x2f2:
						migration_cpu_stop at kernel/sched/core.c:1013
[   61.579676]  ? sched_ttwu_pending+0x1fd/0x1fd:
						migration_cpu_stop at kernel/sched/core.c:986
[   61.583957]  ? lock_acquire+0xed/0x14b:
						lock_acquire at arch/x86/include/asm/current.h:15
[   61.587675]  ? sched_ttwu_pending+0x1fd/0x1fd:
						migration_cpu_stop at kernel/sched/core.c:986
[   61.593768]  cpu_stopper_thread+0x1c9/0x25e:
						cpu_stopper_thread at kernel/stop_machine.c:481
[   61.598538]  ? cpu_stop_create+0x6a/0x6a:
						cpu_stopper_thread at kernel/stop_machine.c:458
[   61.602459]  smpboot_thread_fn+0x615/0x64b:
						smpboot_thread_fn at kernel/smpboot.c:164
[   61.606662]  ? sort_range+0x17/0x17:
						smpboot_thread_fn at kernel/smpboot.c:107
[   61.610132]  ? schedule+0x16e/0x190:
						schedule at arch/x86/include/asm/preempt.h:81 (discriminator 1)
[   61.613570]  kthread+0x365/0x374:
						kthread at kernel/kthread.c:231
[   61.616910]  ? sort_range+0x17/0x17:
						smpboot_thread_fn at kernel/smpboot.c:107
[   61.620412]  ? kthread_stop+0xc8/0xc8:
						kthread at kernel/kthread.c:190
[   61.625690]  ret_from_fork+0x1f/0x30:
						ret_from_fork at arch/x86/entry/entry_64.S:443
[   61.629381] Code: 83 e0 07 83 c0 03 38 d0 7c 09 84 d2 74 05 e8 9f 44 1f 00 44 3b 3d 4a d3 fd 01 72 02 0f ff 45 89 fc 4c 0f a3 25 d7 d1 fd 01 72 02 <0f> ff 4c 89 ea 48 b8 00 00 00 00 00 fc ff df 48 c1 ea 03 8a 14
[   61.648413] ---[ end trace 764cf6ead543b298 ]---
[   61.653178] sched: Unexpected reschedule of offline CPU#1!

Attached the full dmesg, kconfig and reproduce scripts.

Thanks,
Fengguang

View attachment "dmesg-vm-lkp-wsx03-quantal-x86_64-18:20171114123346:x86_64-randconfig-n0-11131431:4.14.0-00902-g43ff2f4:1" of type "text/plain" (80040 bytes)

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

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

View attachment "reproduce-vm-lkp-wsx03-quantal-x86_64-18:20171114123346:x86_64-randconfig-n0-11131431:4.14.0-00902-g43ff2f4:1" of type "text/plain" (1849 bytes)