[x86/dumpstack] cf0082cde6: BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/vmap_stack
commit cf0082cde65d1941a996d026f2b2cdbfaca17bfa
Author: Andy Lutomirski <luto(a)kernel.org>
AuthorDate: Wed Jun 29 16:08:57 2016 -0700
Commit: Andy Lutomirski <luto(a)kernel.org>
CommitDate: Thu Sep 15 11:44:54 2016 -0700
x86/dumpstack: Pin the target stack when dumping it
Specifically, pin the stack in save_stack_trace_tsk() and
show_trace_log_lvl().
This will prevent a crash if the target task dies before or while
dumping its stack once we start freeing task stacks early.
Cc: Josh Poimboeuf <jpoimboe(a)redhat.com>
Signed-off-by: Andy Lutomirski <luto(a)kernel.org>
+-------------------------------------------------+------------+------------+------------+
| | cb9b16bbc1 | cf0082cde6 | 158edabbb2 |
+-------------------------------------------------+------------+------------+------------+
| boot_successes | 95 | 0 | 0 |
| boot_failures | 5 | 34 | 78 |
| calltrace:SyS_open | 5 | | |
| BUG:kernel_reboot-without-warning_in_test_stage | 5 | | |
| BUG:unable_to_handle_kernel | 0 | 34 | 78 |
| Oops | 0 | 34 | 78 |
| RIP:show_stack_log_lvl | 0 | 34 | 78 |
| RIP:show_regs | 0 | 34 | 78 |
| BUG:stack_guard_page_was_hit_at#(stack_is#..#) | 0 | 34 | 77 |
| RIP:vsnprintf | 0 | 33 | 77 |
+-------------------------------------------------+------------+------------+------------+
[ 26.926613] #2: MTPAV on parallel port at 0x378
[ 26.936206] genirq: Flags mismatch irq 4. 00000000 (serial) vs. 00000080 (goldfish_pdev_bus)
[ 26.947288] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 26.956952] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 26.967546] IP: [<ffffffff810181f0>] show_stack_log_lvl+0x0/0x20
[ 26.976033] PGD 0
[ 26.979012] Oops: 0000 [#1] PREEMPT
[ 26.997939] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 27.017936] task: ffff88001e460040 task.stack: ffffc90000008000
[ 27.035855] RIP: 0010:[<ffffffff810181f0>] [<ffffffff810181f0>] show_stack_log_lvl+0x0/0x20
[ 27.047423] RSP: 0000:ffffc9000000ba60 EFLAGS: 00010046
[ 27.054391] RAX: 0000000000000043 RBX: ffff880019f89100 RCX: ffffc9000000ba68
[ 27.064205] RDX: ffffc9000000ba68 RSI: 0000000000000000 RDI: 0000000000000000
[ 27.073899] RBP: ffffc9000000ba68 R08: ffffffff81f0a68c R09: 0000000000000000
[ 27.083462] R10: 0000000000000292 R11: 0000000000000001 R12: ffff88001998de80
[ 27.092960] R13: ffff88001e405a00 R14: 00000000fffffff0 R15: ffff88001e405aa0
[ 27.102516] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 27.113342] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 27.121121] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 27.131386] Stack:
[ 27.137518] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 27.153941] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.161491] PGD 0
[ 27.164511] Oops: 0000 [#2] PREEMPT
[ 27.169434] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 27.179186] task: ffff88001e460040 task.stack: ffffc90000008000
[ 27.187221] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.198111] RSP: 0000:ffffc9000000b7e8 EFLAGS: 00010016
[ 27.205277] RAX: 0000000000000006 RBX: ffffc9000000b9b8 RCX: 0000000000000000
[ 27.214995] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000096
[ 27.224462] RBP: ffffc9000000b818 R08: 0000000000000001 R09: 0000000000000000
[ 27.234117] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 27.243841] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 27.253425] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 27.264248] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 27.271971] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 27.281817] Stack:
[ 27.284700] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 27.295594] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.303205] PGD 0
[ 27.306254] Oops: 0000 [#3] PREEMPT
[ 27.311173] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 27.322197] task: ffff88001e460040 task.stack: ffffc90000008000
[ 27.332404] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.352886] RSP: 0000:ffffc9000000b568 EFLAGS: 00010016
[ 27.360105] RAX: 0000000000000006 RBX: ffffc9000000b738 RCX: 0000000000000000
[ 27.389211] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000092
[ 27.400291] RBP: ffffc9000000b598 R08: 0000000000000001 R09: 0000000000000000
[ 27.422618] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 27.432337] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 27.462025] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 27.476128] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 27.484678] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 27.494363] Stack:
[ 27.497344] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 27.508914] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.516468] PGD 0
[ 27.519942] Oops: 0000 [#4] PREEMPT
[ 27.525377] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 27.536076] task: ffff88001e460040 task.stack: ffffc90000008000
[ 27.544082] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.556103] RSP: 0000:ffffc9000000b2e8 EFLAGS: 00010016
[ 27.563293] RAX: 0000000000000006 RBX: ffffc9000000b4b8 RCX: 0000000000000000
[ 27.573920] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000096
[ 27.583903] RBP: ffffc9000000b318 R08: 0000000000000001 R09: 0000000000000000
[ 27.594761] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 27.616956] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 27.641195] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 27.663593] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 27.682740] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 27.702871] Stack:
[ 27.712270] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 27.732782] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.750086] PGD 0
[ 27.752999] Oops: 0000 [#5] PREEMPT
[ 27.767682] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 27.787162] task: ffff88001e460040 task.stack: ffffc90000008000
[ 27.795061] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.806436] RSP: 0000:ffffc9000000b068 EFLAGS: 00010016
[ 27.814502] RAX: 0000000000000006 RBX: ffffc9000000b238 RCX: 0000000000000000
[ 27.824105] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000092
[ 27.834400] RBP: ffffc9000000b098 R08: 0000000000000001 R09: 0000000000000000
[ 27.844088] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 27.854825] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 27.864412] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 27.876245] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 27.884151] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 27.894296] Stack:
[ 27.897467] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 27.908354] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.916326] PGD 0
[ 27.919435] Oops: 0000 [#6] PREEMPT
[ 27.925698] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 27.935547] task: ffff88001e460040 task.stack: ffffc90000008000
[ 27.949678] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 27.970482] RSP: 0000:ffffc9000000ade8 EFLAGS: 00010016
[ 27.987104] RAX: 0000000000000006 RBX: ffffc9000000afb8 RCX: 0000000000000000
[ 28.002527] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000096
[ 28.024363] RBP: ffffc9000000ae18 R08: 0000000000000001 R09: 0000000000000000
[ 28.045012] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 28.063680] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 28.091514] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 28.113196] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.131497] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 28.151988] Stack:
[ 28.155001] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 28.176812] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.184621] PGD 0
[ 28.187598] Oops: 0000 [#7] PREEMPT
[ 28.192877] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 28.202960] task: ffff88001e460040 task.stack: ffffc90000008000
[ 28.211062] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.222142] RSP: 0000:ffffc9000000ab68 EFLAGS: 00010016
[ 28.229243] RAX: 0000000000000006 RBX: ffffc9000000ad38 RCX: 0000000000000000
[ 28.239411] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000092
[ 28.249107] RBP: ffffc9000000ab98 R08: 0000000000000001 R09: 0000000000000000
[ 28.258465] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 28.268617] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 28.278044] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 28.289549] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.297136] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 28.307407] Stack:
[ 28.310453] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 28.321112] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.328338] PGD 0
[ 28.331352] Oops: 0000 [#8] PREEMPT
[ 28.336024] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 28.345605] task: ffff88001e460040 task.stack: ffffc90000008000
[ 28.353450] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.363923] RSP: 0000:ffffc9000000a8e8 EFLAGS: 00010016
[ 28.370981] RAX: 0000000000000006 RBX: ffffc9000000aab8 RCX: 0000000000000000
[ 28.380283] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000096
[ 28.389716] RBP: ffffc9000000a918 R08: 0000000000000001 R09: 0000000000000000
[ 28.399052] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 28.408380] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 28.426274] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 28.436996] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.444547] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 28.453915] Stack:
[ 28.456698] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 28.467299] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.474623] PGD 0
[ 28.477570] Oops: 0000 [#9] PREEMPT
[ 28.482237] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 28.491824] task: ffff88001e460040 task.stack: ffffc90000008000
[ 28.499576] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.510173] RSP: 0000:ffffc9000000a668 EFLAGS: 00010016
[ 28.518727] RAX: 0000000000000006 RBX: ffffc9000000a838 RCX: 0000000000000000
[ 28.530260] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000092
[ 28.539620] RBP: ffffc9000000a698 R08: 0000000000000001 R09: 0000000000000000
[ 28.556157] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 28.565479] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 28.574853] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 28.591574] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.599187] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 28.608904] Stack:
[ 28.611669] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 28.622323] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.629775] PGD 0
[ 28.633079] Oops: 0000 [#10] PREEMPT
[ 28.638329] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 28.648383] task: ffff88001e460040 task.stack: ffffc90000008000
[ 28.655992] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.666432] RSP: 0000:ffffc9000000a3e8 EFLAGS: 00010016
[ 28.673529] RAX: 0000000000000006 RBX: ffffc9000000a5b8 RCX: 0000000000000000
[ 28.683022] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000096
[ 28.703503] RBP: ffffc9000000a418 R08: 0000000000000001 R09: 0000000000000000
[ 28.720485] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 28.730469] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 28.739930] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 28.750458] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.758040] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 28.767566] Stack:
[ 28.770360] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 28.780985] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.788371] PGD 0
[ 28.791319] Oops: 0000 [#11] PREEMPT
[ 28.801595] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 28.811216] task: ffff88001e460040 task.stack: ffffc90000008000
[ 28.829201] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.849883] RSP: 0000:ffffc9000000a168 EFLAGS: 00010016
[ 28.857209] RAX: 0000000000000006 RBX: ffffc9000000a338 RCX: 0000000000000000
[ 28.866570] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000092
[ 28.875938] RBP: ffffc9000000a198 R08: 0000000000000001 R09: 0000000000000000
[ 28.885591] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
[ 28.894957] R13: 000000000000002b R14: 0000000000000000 R15: 0000000000000040
[ 28.904293] FS: 0000000000000000(0000) GS:ffffffff8222e000(0000) knlGS:0000000000000000
[ 28.915243] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 28.923571] CR2: 0000000000000010 CR3: 000000000220c000 CR4: 00000000000006f0
[ 28.933551] Stack:
[ 28.936468] BUG: unable to handle kernel NULL pointer dereference at 0000000000000010
[ 28.947417] IP: [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 28.954747] PGD 0
[ 28.957694] Oops: 0000 [#12] PREEMPT
[ 28.962551] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc6-00273-gcf0082c #1
[ 28.980707] task: ffff88001e460040 task.stack: ffffc90000008000
[ 28.988551] RIP: 0010:[<ffffffff8101827c>] [<ffffffff8101827c>] show_regs+0x6c/0x180
[ 29.003544] RSP: 0000:ffffc90000009ee8 EFLAGS: 00010016
[ 29.010613] RAX: 0000000000000006 RBX: ffffc9000000a0b8 RCX: 0000000000000000
[ 29.019950] RDX: ffffffff810c57a9 RSI: 0000000000000001 RDI: 0000000000000096
[ 29.029274] RBP: ffffc90000009f18 R08: 0000000000000001 R09: 0000000000000000
git bisect start 158edabbb20e066ec7381f62fcd2901eaeb505a3 9395452b4aab7bc2475ef8935b4a4fb99d778d70 --
git bisect bad c165bc2d5f248444036050ba70612d5aadc8b1c2 # 05:37 0- 22 Merge 'linux-review/Stefan-Assmann/i40e-clean-up-and-fix-for-the-i40e-debug-code/20160915-221245' into devel-spot-201609160346
git bisect bad d6749849a291ef90e04105940306b1b5b3554479 # 05:58 0- 14 Merge 'linux-review/Alexander-Shishkin/perf-x86-intel-pt-Address-filtering-fixes-for-perf-urgent/20160916-012943' into devel-spot-201609160346
git bisect bad 9c51380d8bb19770648a051bff6a910b84c4ca22 # 06:46 0- 16 Merge 'linux-review/Chuck-Lever/xprtrdma-Eliminate-INLINE_THRESHOLD-macros/20160916-021817' into devel-spot-201609160346
git bisect good 89f0df8f3bd37debe4834f2951199781efbeb9e1 # 07:18 22+ 1 Merge 'linux-review/Eric-Dumazet/net-avoid-sk_forward_alloc-overflows/20160916-030759' into devel-spot-201609160346
git bisect good 5f1df84fcf36fef0bb5ff29b012879296a6349c1 # 07:29 22+ 0 Merge 'tip/irq/core' into devel-spot-201609160346
git bisect good c0bc0c39cf85e22d5a579bdf15182944f43aeed3 # 07:44 22+ 0 Merge 'linux-review/Aidan-Thornton/ch341-cleanup-and-parity-etc-support/20160916-024549' into devel-spot-201609160346
git bisect bad 9554be085d53a9d0d7ba4ba4e584227485524265 # 07:56 0- 7 Merge 'luto/x86/vmap_stack' into devel-spot-201609160346
git bisect good 2b3061c77ce7e429b25a25560ba088e8e7193a67 # 09:11 22+ 0 Merge branch 'x86/mm' into x86/asm, to unify the two branches for simplicity
git bisect good fcd709ef20a9d83bdb7524d27cd6719dac8690a0 # 09:37 22+ 0 x86/dumpstack: Add recursion checking for all stacks
git bisect good c65eacbe290b8141554c71b2c94489e73ade8c8d # 11:00 22+ 1 sched/core: Allow putting thread_info into task_struct
git bisect good 17a434f50ad3d77000104f21666575e10a9c1fbd # 11:29 22+ 0 sched: Add try_get_task_stack() and put_task_stack()
git bisect bad cf0082cde65d1941a996d026f2b2cdbfaca17bfa # 11:43 0- 4 x86/dumpstack: Pin the target stack when dumping it
git bisect good cb9b16bbc19d4aea4507ab0552e4644c1211d130 # 12:07 21+ 1 kthread: to_live_kthread() needs try_get_task_stack()
# first bad commit: [cf0082cde65d1941a996d026f2b2cdbfaca17bfa] x86/dumpstack: Pin the target stack when dumping it
git bisect good cb9b16bbc19d4aea4507ab0552e4644c1211d130 # 12:11 67+ 3 kthread: to_live_kthread() needs try_get_task_stack()
# extra tests on HEAD of linux-devel/devel-spot-201609160346
git bisect bad 158edabbb20e066ec7381f62fcd2901eaeb505a3 # 12:11 0- 78 0day head guard for 'devel-spot-201609160346'
# extra tests on tree/branch luto/x86/vmap_stack
git bisect bad d93c9c345dd8cff8a426a455cd94d9468d8a4e76 # 15:25 0- 15 scatterlist: Support virtually-mapped stacks in sg_init_stackbuf()
# extra tests with first bad commit reverted
git bisect bad de9e09a6a17fcd4956224f6e0b500d60dcbac2d0 # 02:32 10- 1 Revert "x86/dumpstack: Pin the target stack when dumping it"
# extra tests on tree/branch linus/master
git bisect good 024c7e3756d8a42fc41fe8a9488488b9b09d1dcc # 02:59 122+ 2 Merge tag 'for-linus' of git://git.kernel.org/pub/scm/virt/kvm/kvm
# extra tests on tree/branch linux-next/master
git bisect good ddf9d15aed5ba5786cf4b082576c44ce37cd29c2 # 03:06 121+ 66 Add linux-next specific files for 20160916
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 4 months
Tree build request
by Jarod Wilson
Hello,
I'd like to get a tree full of test patches I'm submitting to netdev built
across more arches than I have directly available, and it was suggested
that I send a request here. Work-in-progress patches are available here
(including one that fixes a problem reported by your infra w/the initial
patches I sent to netdev).
Tree location:
https://github.com/jarodwilson/linux-muck
Thanks much, please let me know if any additional information is required.
--
Jarod Wilson
jarod(a)redhat.com
4 years, 4 months
Re: [LKP] [netfilter-core] [lkp] [netfilter] 68263ddb47: WARNING: CPU: 0 PID: 1225 at net/netfilter/nf_conntrack_seqadj.c:232 nf_ct_seq_offset+0x7a/0x9a
by Florian Westphal
Gao Feng <fgao(a)ikuai8.com> wrote:
> > [ 23.465616] ------------[ cut here ]------------
> > [ 23.466477] WARNING: CPU: 0 PID: 1225 at net/netfilter/nf_conntrack_seqadj.c:232
> > nf_ct_seq_offset+0x7a/0x9a
> > [ 23.468458] Missing nfct_seqadj_ext_add() setup call
> >
>
> It should be that nf_ct_add_synproxy failed and the seqadj extentision is
> not added.
Note that nfct_synproxy_ext_add always returns NULL if
CONFIG_NETFILTER_SYNPROXY=n
The warning should also be removed.
> When nf_ct_add_synproxy fails, the init_conntrack fails too and return
> ERR_PTR(-ENOMEM). In this case, the packet should be dropped directly, and
> should not be processed by the latter codes.
This means the commit breaks conntrack if SYNPROXY=n
4 years, 4 months
[lkp] [sched/numa, mm] d59dc7bcfa: No primary change: reaim.time.voluntary_context_switches +110.7%
by kernel test robot
reaim.time.voluntary_context_switches +110.7%
Reply-To: kernel test robot <xiaolong.ye(a)intel.com>
User-Agent: Heirloom mailx 12.5 6/20/10
FYI, we noticed reaim.time.voluntary_context_switches +110.7% due to commit:
commit d59dc7bcfa649ef2128a76b6487b16f4b3f14d23 ("sched/numa, mm: Revert to checking pmd/pte_write instead of VMA flags")
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
in testcase: reaim
on test machine: 72 threads Intel(R) Xeon(R) CPU E5-2699 v3 @ 2.30GHz with 128G memory
with following parameters:
runtime: 300s
nr_task: 100%
nr_job: 10000
test: short
cpufreq_governor: performance
REAIM is an updated and improved version of AIM 7 benchmark.
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
=========================================================================================
compiler/cpufreq_governor/kconfig/nr_job/nr_task/rootfs/runtime/tbox_group/test/testcase:
gcc-6/performance/x86_64-rhel-7.2/10000/100%/debian-x86_64-2016-08-31.cgz/300s/lkp-hsw-ep2/short/reaim
commit:
b95202a3b6 ("sched/deadline: Document behavior of sched_yield()")
d59dc7bcfa ("sched/numa, mm: Revert to checking pmd/pte_write instead of VMA flags")
b95202a3b6bb8715 d59dc7bcfa649ef2128a76b648
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
%stddev %change %stddev
\ | \
2.06 ± 1% -2.7% 2.00 ± 0% reaim.std_dev_percent
328520 ± 6% +110.7% 692172 ± 2% reaim.time.voluntary_context_switches
1078864 ± 1% +332.2% 4662990 ± 1% interrupts.CAL:Function_call_interrupts
20354 ± 7% +37.5% 27995 ± 6% meminfo.Shmem
186030 ± 2% +22.9% 228590 ± 1% softirqs.SCHED
6511 ± 1% +36.6% 8893 ± 1% vmstat.system.cs
77823 ± 0% +16.6% 90739 ± 0% vmstat.system.in
3386 ± 6% -13.6% 2925 ± 4% numa-meminfo.node0.PageTables
819.33 ± 39% +516.0% 5047 ± 81% numa-meminfo.node1.Inactive(anon)
7767 ± 0% +32.0% 10249 ± 23% numa-meminfo.node1.Mapped
2853 ± 8% +16.9% 3336 ± 4% numa-meminfo.node1.PageTables
849.00 ± 6% -13.8% 732.25 ± 4% numa-vmstat.node0.nr_page_table_pages
204.33 ± 39% +517.3% 1261 ± 82% numa-vmstat.node1.nr_inactive_anon
715.33 ± 9% +16.4% 832.75 ± 4% numa-vmstat.node1.nr_page_table_pages
204.33 ± 39% +517.3% 1261 ± 82% numa-vmstat.node1.nr_zone_inactive_anon
1.58 ± 3% +11.1% 1.76 ± 4% perf-profile.children.cycles-pp.__do_page_fault
1.61 ± 2% +11.6% 1.80 ± 5% perf-profile.children.cycles-pp.do_page_fault
1.21 ± 3% +14.6% 1.39 ± 5% perf-profile.children.cycles-pp.handle_mm_fault
1.62 ± 2% +11.6% 1.81 ± 4% perf-profile.children.cycles-pp.page_fault
18555610 ± 5% +142.8% 45052001 ± 2% cpuidle.C1-HSW.time
284802 ± 5% +148.3% 707062 ± 2% cpuidle.C1-HSW.usage
10511856 ± 4% +100.4% 21062895 ± 8% cpuidle.C1E-HSW.time
84157 ± 2% +152.3% 212324 ± 1% cpuidle.C1E-HSW.usage
5259 ± 3% +197.5% 15647 ± 0% cpuidle.POLL.usage
125636 ± 0% +285.1% 483761 ± 1% latency_stats.hits.wait_on_page_bit.__migration_entry_wait.migration_entry_wait.do_swap_page.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
10887 ± 85% -98.7% 141.25 ±109% latency_stats.max.call_rwsem_down_write_failed.unlink_file_vma.free_pgtables.unmap_region.do_munmap.SyS_munmap.do_syscall_64.return_from_SYSCALL_64
2007 ± 64% +281.0% 7649 ± 37% latency_stats.max.wait_on_page_bit.__migration_entry_wait.migration_entry_wait.do_swap_page.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
10975 ± 85% -97.9% 234.75 ±102% latency_stats.sum.call_rwsem_down_write_failed.unlink_file_vma.free_pgtables.unmap_region.do_munmap.SyS_munmap.do_syscall_64.return_from_SYSCALL_64
13269626 ± 0% +293.0% 52145660 ± 1% latency_stats.sum.max
13202213 ± 1% +295.0% 52145660 ± 1% latency_stats.sum.wait_on_page_bit.__migration_entry_wait.migration_entry_wait.do_swap_page.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
1970029 ± 2% +37.0% 2699350 ± 2% perf-stat.context-switches
83062 ± 3% +11.2% 92391 ± 2% perf-stat.cpu-migrations
0.01 ± 0% +5.1% 0.01 ± 3% perf-stat.dTLB-store-miss-rate%
4.265e+09 ± 1% +1.9% 4.345e+09 ± 0% perf-stat.iTLB-loads
2.228e+09 ± 0% +1.9% 2.271e+09 ± 0% perf-stat.node-load-misses
1.113e+09 ± 0% +1.3% 1.127e+09 ± 0% perf-stat.node-store-misses
4.582e+08 ± 1% +1.9% 4.669e+08 ± 0% perf-stat.node-stores
5092 ± 7% +37.3% 6991 ± 6% proc-vmstat.nr_shmem
435033 ± 0% -15.8% 366135 ± 0% proc-vmstat.numa_hint_faults
237335 ± 0% -13.7% 204706 ± 0% proc-vmstat.numa_hint_faults_local
41453 ± 2% +232.2% 137724 ± 1% proc-vmstat.numa_pages_migrated
7184 ± 9% +48.6% 10678 ± 3% proc-vmstat.pgactivate
1193 ± 2% +419.5% 6197 ± 1% proc-vmstat.pgmigrate_fail
41453 ± 2% +232.2% 137724 ± 1% proc-vmstat.pgmigrate_success
0.49 ± 8% -16.1% 0.41 ± 13% sched_debug.cfs_rq:/.nr_running.avg
6.79 ± 7% -19.5% 5.47 ± 12% sched_debug.cfs_rq:/.runnable_load_avg.avg
649539 ± 7% +9.4% 710821 ± 7% sched_debug.cpu.avg_idle.avg
6.62 ± 9% -19.9% 5.31 ± 13% sched_debug.cpu.cpu_load[0].avg
7.18 ± 9% -20.7% 5.70 ± 11% sched_debug.cpu.cpu_load[1].avg
7.10 ± 9% -20.6% 5.64 ± 12% sched_debug.cpu.cpu_load[2].avg
7.01 ± 8% -19.8% 5.62 ± 12% sched_debug.cpu.cpu_load[3].avg
6.96 ± 6% -19.2% 5.62 ± 12% sched_debug.cpu.cpu_load[4].avg
16907 ± 3% -17.0% 14029 ± 16% sched_debug.cpu.load.avg
460630 ± 22% -36.1% 294449 ± 35% sched_debug.cpu.load.max
0.52 ± 9% -18.4% 0.43 ± 14% sched_debug.cpu.nr_running.avg
14012 ± 2% +35.2% 18940 ± 2% sched_debug.cpu.nr_switches.avg
37653 ± 3% +13.4% 42694 ± 6% sched_debug.cpu.nr_switches.max
8357 ± 4% +44.7% 12093 ± 3% sched_debug.cpu.nr_switches.min
0.00 ±353% +650.0% 0.01 ± 67% sched_debug.cpu.nr_uninterruptible.avg
35378 ± 0% +14.0% 40319 ± 0% sched_debug.cpu.sched_count.avg
29104 ± 1% +12.7% 32810 ± 1% sched_debug.cpu.sched_count.min
3146 ± 4% +77.0% 5568 ± 2% sched_debug.cpu.sched_goidle.avg
1931 ± 4% +114.4% 4141 ± 2% sched_debug.cpu.sched_goidle.min
40729 ± 2% +9.8% 44726 ± 4% sched_debug.cpu.ttwu_count.max
0.02 ± 3% +8.0% 0.02 ± 2% sched_debug.rt_rq:/.rt_time.avg
perf-stat.context-switches
3e+06 ++----------------------------------------------------------------+
| O O O O O O O O O |
2.5e+06 O+ O O O O |
| |
| .*. .*..*. .*.. .*..*..*.*..* *.. .*.. |
2e+06 *+. .*..*.*. *. *. * : : * * *..*
| *.*. : : : : |
1.5e+06 ++ : : : : |
| : : : : |
1e+06 ++ : : : : |
| : : : : |
| : : : : |
500000 ++ :: :: |
| : : |
0 ++-O--------------------------------------------*------------*----+
reaim.time.voluntary_context_switches
800000 ++-----------------------------------------------------------------+
| O O |
700000 O+ O O O O O O O O O O O |
| |
600000 ++ |
500000 ++ |
| |
400000 ++ .*. |
| *..*.. .*..*.. .*.. .*. *..*..* *..*.*..* .*
300000 ++ + * * *. : : : *. |
200000 *+.*.*.. .* : : : : |
| *. : : : : |
100000 ++ : : : : |
| :: : |
0 ++-O--------------------------------------------*-------------*----+
interrupts.CAL:Function_call_interrupts
5e+06 ++----------------------------------------------------------------+
4.5e+06 O+ O O O O O O O O O O O O O |
| |
4e+06 ++ |
3.5e+06 ++ |
| |
3e+06 ++ |
2.5e+06 ++ |
2e+06 ++ |
| |
1.5e+06 ++ |
1e+06 *+.*.*..*..*.*..*.*..*..*.*..*..*.*..*..*.*..* *..*.*..* *..*
| + : + : |
500000 ++ + : + : |
0 ++-O--------------------------------------------*------------*----+
vmstat.system.in
100000 ++-----------------------------------------------------------------+
90000 O+ O O O O O O O O O O O O O |
| |
80000 *+.*.*..*..*.*..*..*.*..*..*.*..*..*..*.*..*..* *..*.*..* *..*
70000 ++ : : : : |
| : : : : |
60000 ++ : : : : |
50000 ++ : : : : |
40000 ++ : : : : |
| : : : : |
30000 ++ : : : : |
20000 ++ : : : : |
| :: :: |
10000 ++ : : |
0 ++-O--------------------------------------------*-------------*----+
vmstat.system.cs
10000 ++------------------------------------------------------------------+
9000 ++ O O O O O |
O O O O O O O O O |
8000 ++ |
7000 ++ .*.*.. .*..*..*.* *. |
*.. .*..*..*.*..*. *..*..*.*. : : *..*..* *..*
6000 ++ *.*. : : : : |
5000 ++ : : : : |
4000 ++ : : : : |
| : : : : |
3000 ++ : : : : |
2000 ++ : : : : |
| : : :: |
1000 ++ : : |
0 ++-O---------------------------------------------*-------------*----+
[*] bisect-good sample
[O] bisect-bad sample
Thanks,
Xiaolong
4 years, 4 months
[lkp] [netfilter] 68263ddb47: WARNING: CPU: 0 PID: 1225 at net/netfilter/nf_conntrack_seqadj.c:232 nf_ct_seq_offset+0x7a/0x9a
by kernel test robot
FYI, we noticed the following commit:
https://github.com/0day-ci/linux fgao-ikuai8-com/netfilter-seqadj-Fix-some-possible-panics-of-seqadj-when-mem-is-exhausted/20160902-095727
commit 68263ddb4777cc996868498e3d56f616851966d2 ("netfilter: seqadj: Fix some possible panics of seqadj when mem is exhausted")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 320M
caused below changes:
+------------------------------------------------------------------+------------+------------+
| | c73c248490 | 68263ddb47 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 7 | 0 |
| boot_failures | 7 | 14 |
| BUG:kernel_reboot-without-warning_in_test_stage | 7 | |
| WARNING:at_net/netfilter/nf_conntrack_seqadj.c:#nf_ct_seq_offset | 0 | 14 |
| calltrace:SyS_connect | 0 | 14 |
| calltrace:SyS_socketcall | 0 | 14 |
| invoked_oom-killer:gfp_mask=0x | 0 | 1 |
| Mem-Info | 0 | 1 |
+------------------------------------------------------------------+------------+------------+
[ 22.475640] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[ 22.602089] Kernel tests: Boot OK!
[ 23.465616] ------------[ cut here ]------------
[ 23.466477] WARNING: CPU: 0 PID: 1225 at net/netfilter/nf_conntrack_seqadj.c:232 nf_ct_seq_offset+0x7a/0x9a
[ 23.468458] Missing nfct_seqadj_ext_add() setup call
[ 23.469319] CPU: 0 PID: 1225 Comm: busybox Not tainted 4.8.0-rc2-00241-g68263dd #1
[ 23.470629] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 23.472138] 00000000 8ca4db58 8ca4db28 8a045c76 8ca4db44 89e4a2a3 000000e8 8a97c7b6
[ 23.491476] 00000001 00000000 00000001 8ca4db60 89e4a2e7 00000009 00000000 8ca4db58
[ 23.493027] 8b2c55b0 8ca4db74 8ca4db80 8a97c7b6 8b2c55da 000000e8 8b2c55b0 8e358000
[ 23.494559] Call Trace:
[ 23.495013] [<8a045c76>] dump_stack+0x16/0x18
[ 23.495777] [<89e4a2a3>] __warn+0xaa/0xc1
[ 23.496500] [<8a97c7b6>] ? nf_ct_seq_offset+0x7a/0x9a
[ 23.497392] [<89e4a2e7>] warn_slowpath_fmt+0x2d/0x32
[ 23.498270] [<8a97c7b6>] nf_ct_seq_offset+0x7a/0x9a
[ 23.499134] [<8a97b2a3>] tcp_packet+0x63f/0xb93
[ 23.499924] [<89f39167>] ? cache_alloc_refill+0x203/0x80a
[ 23.500896] [<89e6863e>] ? preempt_count_sub+0x8f/0xd4
[ 23.501824] [<8a976d99>] ? nf_conntrack_in+0x2f9/0x53e
[ 23.502747] [<8a976dc2>] ? nf_conntrack_in+0x322/0x53e
[ 23.521594] [<89e6863e>] ? preempt_count_sub+0x8f/0xd4
[ 23.522508] [<8a976f5b>] nf_conntrack_in+0x4bb/0x53e
[ 23.523399] [<8a9e6a9a>] ipv4_conntrack_local+0x40/0x48
[ 23.524338] [<8a9729c5>] nf_iterate+0x3b/0x8e
[ 23.525128] [<8a972a5f>] nf_hook_slow+0x47/0xc7
[ 23.525925] [<8a9a47f1>] __ip_local_out+0xdb/0xea
[ 23.526766] [<8a9a3750>] ? ip_options_rcv_srr+0x30e/0x30e
[ 23.527785] [<8a9a4818>] ip_local_out+0x18/0x9e
[ 23.528600] [<8a9a4dd8>] ip_queue_xmit+0x395/0x41e
[ 23.529469] [<8a9b93cb>] tcp_transmit_skb+0x701/0x740
[ 23.530420] [<8a9bbcba>] tcp_connect+0x65a/0x6be
[ 23.531247] [<8a9bd9e3>] tcp_v4_connect+0x41d/0x45c
[ 23.532115] [<8a9d1a6c>] __inet_stream_connect+0x77/0x27e
[ 23.533107] [<89e6863e>] ? preempt_count_sub+0x8f/0xd4
[ 23.534018] [<89e4d8e1>] ? __local_bh_enable_ip+0xc4/0xea
[ 23.534949] [<8a9d1c9e>] inet_stream_connect+0x2b/0x3e
[ 23.535862] [<8a935255>] SyS_connect+0x77/0x9d
[ 23.536662] [<89f74959>] ? __fd_install+0x163/0x1c5
[ 23.538079] [<89e6863e>] ? preempt_count_sub+0x8f/0xd4
Thanks,
Xiaolong
4 years, 4 months
[lkp] [net] 70a8118a03: BUG: workqueue leaked lock or atomic: kworker/0:1/0x00000000/28
by kernel test robot
FYI, we noticed the following commit:
https://github.com/0day-ci/linux Christophe-JAILLET/net-inet-diag-Fix-an-error-handling/20160912-140503
commit 70a8118a03243de2aba508d79cc1a042db094191 ("net: inet: diag: Fix an error handling")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -smp 2 -m 512M
caused below changes:
+----------------------------------------------------+------------+------------+
| | 373df3131a | 70a8118a03 |
+----------------------------------------------------+------------+------------+
| boot_successes | 6 | 3 |
| boot_failures | 17 | 19 |
| BUG:unable_to_handle_kernel | 2 | |
| Oops | 2 | |
| calltrace:compat_SyS_ipc | 2 | |
| Kernel_panic-not_syncing:Fatal_exception | 2 | |
| invoked_oom-killer:gfp_mask=0x | 4 | 2 |
| Mem-Info | 4 | 2 |
| BUG:kernel_reboot-without-warning_in_test_stage | 11 | 5 |
| Out_of_memory:Kill_process | 1 | 1 |
| BUG:kernel_hang_in_test_stage | 0 | 2 |
| BUG:workqueue_leaked_lock_or_atomic:kworker | 0 | 11 |
| calltrace:dump_stack | 0 | 11 |
| INFO:possible_circular_locking_dependency_detected | 0 | 11 |
| calltrace:ret_from_fork | 0 | 11 |
| calltrace:sock_diag_broadcast_destroy_work | 0 | 11 |
| calltrace:lock_acquire | 0 | 11 |
| calltrace:inet_diag_lock_handler | 0 | 11 |
+----------------------------------------------------+------------+------------+
[ 34.367674] init: tty3 main process ended, respawning
[ 34.444537] init: tty6 main process (356) terminated with status 1
[ 34.445711] init: tty6 main process ended, respawning
[ 34.657943] BUG: workqueue leaked lock or atomic: kworker/0:1/0x00000000/28
[ 34.657943] last function: sock_diag_broadcast_destroy_work
[ 34.674672] 1 lock held by kworker/0:1/28:
[ 34.675402] #0: (inet_diag_table_mutex){+.+...}, at: [<ffffffff9dee5f51>] inet_diag_lock_handler+0x4e/0x6b
[ 34.685206] CPU: 0 PID: 28 Comm: kworker/0:1 Not tainted 4.8.0-rc4-00239-g70a8118 #2
[ 34.686489] Workqueue: sock_diag_events sock_diag_broadcast_destroy_work
[ 34.688013] 0000000000000000 ffff88001bc9bc68 ffffffff9dcce3fa ffff88001bc94740
[ 34.689383] ffff88001d614cc0 ffff88001bc7ab40 ffff88001bc94740 ffff88001bc9bd48
[ 34.690737] ffffffff9dac0997 ffffffff9dac088f ffff88001bc94740 ffffe8ffffc02f05
[ 34.691995] Call Trace:
[ 34.692409] [<ffffffff9dcce3fa>] dump_stack+0x89/0xcb
[ 34.693268] [<ffffffff9dac0997>] process_one_work+0x2e4/0x414
[ 34.694280] [<ffffffff9dac088f>] ? process_one_work+0x1dc/0x414
[ 34.695115] trinity-main (440) used greatest stack depth: 10480 bytes left
[ 34.697086] [<ffffffff9dac0b1a>] ? worker_thread+0x53/0x3ea
[ 34.698036] [<ffffffff9dac0d82>] worker_thread+0x2bb/0x3ea
[ 34.699007] [<ffffffff9dfd51ad>] ? _raw_spin_unlock_irqrestore+0x42/0x64
[ 34.700143] [<ffffffff9dac0ac7>] ? process_one_work+0x414/0x414
[ 34.701154] [<ffffffff9dac0ac7>] ? process_one_work+0x414/0x414
[ 34.702154] [<ffffffff9dfd00ee>] ? schedule+0x9f/0xb4
[ 34.703184] [<ffffffff9dac0ac7>] ? process_one_work+0x414/0x414
[ 34.704158] [<ffffffff9dac566f>] kthread+0xe6/0xee
[ 34.704956] [<ffffffff9dfd55cf>] ret_from_fork+0x1f/0x40
[ 34.705848] [<ffffffff9dac5589>] ? __init_kthread_worker+0x59/0x59
[ 34.742163]
[ 34.746911] ======================================================
[ 34.747955] [ INFO: possible circular locking dependency detected ]
[ 34.749010] 4.8.0-rc4-00239-g70a8118 #2 Not tainted
[ 34.749842] -------------------------------------------------------
[ 34.751043] kworker/0:1/28 is trying to acquire lock:
[ 34.751905] ((&bsk->work)){+.+.+.}, at: [<ffffffff9dac088f>] process_one_work+0x1dc/0x414
[ 34.753383]
[ 34.753383] but task is already holding lock:
[ 34.754357] (inet_diag_table_mutex){+.+...}, at: [<ffffffff9dee5f51>] inet_diag_lock_handler+0x4e/0x6b
[ 34.756018]
[ 34.756018] which lock already depends on the new lock.
[ 34.756018]
[ 34.757342]
[ 34.757342] the existing dependency chain (in reverse order) is:
[ 34.758710]
-> #1 (inet_diag_table_mutex){+.+...}:
[ 34.759634] [<ffffffff9daed6cf>] validate_chain+0x5ac/0x6d5
[ 34.760629] [<ffffffff9daedc2c>] __lock_acquire+0x434/0x4e8
[ 34.761608] [<ffffffff9daee024>] __lock_release+0x287/0x309
[ 34.762616] [<ffffffff9daee105>] lock_release+0x5f/0x93
[ 34.763557] [<ffffffff9dfd0e15>] __mutex_unlock_slowpath+0xef/0x175
[ 34.764637] [<ffffffff9dfd0f28>] mutex_unlock+0x9/0xb
[ 34.765523] [<ffffffff9de6d843>] sock_diag_broadcast_destroy_work+0xea/0x134
[ 34.766836] [<ffffffff9dac08f9>] process_one_work+0x246/0x414
[ 34.767827] [<ffffffff9dac0d82>] worker_thread+0x2bb/0x3ea
[ 34.768809] [<ffffffff9dac566f>] kthread+0xe6/0xee
[ 34.769704] [<ffffffff9dfd55cf>] ret_from_fork+0x1f/0x40
[ 34.770715]
-> #0 ((&bsk->work)){+.+.+.}:
[ 34.771490] [<ffffffff9daecc1e>] check_prev_add+0x114/0x619
[ 34.772515] [<ffffffff9daed6cf>] validate_chain+0x5ac/0x6d5
[ 34.773547] [<ffffffff9daedc2c>] __lock_acquire+0x434/0x4e8
[ 34.774717] [<ffffffff9daedd7c>] lock_acquire+0x9c/0xbd
[ 34.775681] [<ffffffff9dac08f3>] process_one_work+0x240/0x414
[ 34.776760] [<ffffffff9dac0d82>] worker_thread+0x2bb/0x3ea
[ 34.777802] [<ffffffff9dac566f>] kthread+0xe6/0xee
[ 34.778760] [<ffffffff9dfd55cf>] ret_from_fork+0x1f/0x40
[ 34.779776]
[ 34.779776] other info that might help us debug this:
[ 34.779776]
[ 34.781109] Possible unsafe locking scenario:
[ 34.781109]
[ 34.782101] CPU0 CPU1
[ 34.782980] ---- ----
[ 34.783713] lock(inet_diag_table_mutex);
[ 34.784422] lock((&bsk->work));
[ 34.785399] lock(inet_diag_table_mutex);
[ 34.786381] lock((&bsk->work));
[ 34.786920]
[ 34.786920] *** DEADLOCK ***
[ 34.786920]
[ 34.787763] 2 locks held by kworker/0:1/28:
[ 34.788370] #0: (inet_diag_table_mutex){+.+...}, at: [<ffffffff9dee5f51>] inet_diag_lock_handler+0x4e/0x6b
[ 34.789850] #1: ("sock_diag_events"){.+.+.+}, at: [<ffffffff9dac088f>] process_one_work+0x1dc/0x414
[ 34.791363]
[ 34.791363] stack backtrace:
[ 34.791996] CPU: 0 PID: 28 Comm: kworker/0:1 Not tainted 4.8.0-rc4-00239-g70a8118 #2
[ 34.793098] Workqueue: sock_diag_events sock_diag_broadcast_destroy_work
[ 34.794066] 0000000000000000 ffff88001bc9b9d8 ffffffff9dcce3fa ffff88001bc94740
[ 34.795344] 0000000000000000 0000000000000000 0000000000000000 ffff88001bc9ba28
[ 34.796669] ffffffff9daec947 ffff88001bc9ba48 ffff88001bc9ba48 ffff88001bc9ba28
[ 34.797978] Call Trace:
[ 34.798408] [<ffffffff9dcce3fa>] dump_stack+0x89/0xcb
[ 34.799425] [<ffffffff9daec947>] print_circular_bug+0xcf/0xe0
[ 34.800409] [<ffffffff9daecc1e>] check_prev_add+0x114/0x619
[ 34.801358] [<ffffffff9daed6cf>] validate_chain+0x5ac/0x6d5
[ 34.802324] [<ffffffff9daedc2c>] __lock_acquire+0x434/0x4e8
[ 34.803309] [<ffffffff9daedd7c>] lock_acquire+0x9c/0xbd
[ 34.804205] [<ffffffff9dac088f>] ? process_one_work+0x1dc/0x414
[ 34.805226] [<ffffffff9dac08f3>] process_one_work+0x240/0x414
[ 34.806205] [<ffffffff9dac088f>] ? process_one_work+0x1dc/0x414
[ 34.807366] [<ffffffff9dac0b1a>] ? worker_thread+0x53/0x3ea
[ 34.808317] [<ffffffff9dac0d82>] worker_thread+0x2bb/0x3ea
[ 34.809266] [<ffffffff9dfd51ad>] ? _raw_spin_unlock_irqrestore+0x42/0x64
[ 34.810331] [<ffffffff9dac0ac7>] ? process_one_work+0x414/0x414
[ 34.811280] [<ffffffff9dac0ac7>] ? process_one_work+0x414/0x414
[ 34.812236] [<ffffffff9dfd00ee>] ? schedule+0x9f/0xb4
[ 34.813064] [<ffffffff9dac0ac7>] ? process_one_work+0x414/0x414
[ 34.813983] [<ffffffff9dac566f>] kthread+0xe6/0xee
[ 34.814798] [<ffffffff9dfd55cf>] ret_from_fork+0x1f/0x40
[ 34.815551] [<ffffffff9dac5589>] ? __init_kthread_worker+0x59/0x59
[ 42.173507] ls /sys/class/net
[ 42.216524] lo
Thanks,
Xiaolong
4 years, 4 months
[fsnotify] ced8959181: kernel BUG at fs/notify/notification.c:66!
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit ced89591817caae5d5e1c0c1e76a7f809d004f57 ("fsnotify: convert notification_mutex to a spinlock")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3 -smp 2 -m 8G
caused below changes:
+------------------------------------------+------------+------------+
| | f82fa3d0e7 | ced8959181 |
+------------------------------------------+------------+------------+
| boot_successes | 8 | 0 |
| boot_failures | 0 | 8 |
| kernel_BUG_at_fs/notify/notification.c | 0 | 8 |
| invalid_opcode:#[##]DEBUG_PAGEALLOC | 0 | 8 |
| RIP:fsnotify_notify_queue_is_empty | 0 | 8 |
| calltrace:SyS_poll | 0 | 4 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 8 |
| calltrace:SyS_epoll_ctl | 0 | 4 |
+------------------------------------------+------------+------------+
[ 17.967731] random: systemd: uninitialized urandom read (16 bytes read)
[ 17.969211] random: systemd: uninitialized urandom read (16 bytes read)
[ 17.971379] ------------[ cut here ]------------
[ 17.972173] kernel BUG at fs/notify/notification.c:66!
[ 17.973243] invalid opcode: 0000 [#1] DEBUG_PAGEALLOC
[ 17.974000] Modules linked in:
[ 17.974487] CPU: 0 PID: 1 Comm: systemd Not tainted 4.8.0-rc6-00236-gced8959 #1
[ 17.975597] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 17.976943] task: ffffa1d9f2be7000 task.stack: ffffa1d9f2bfc000
[ 17.977825] RIP: 0010:[<ffffffff979ab459>] [<ffffffff979ab459>] fsnotify_notify_queue_is_empty+0x9/0x10
[ 17.979342] RSP: 0018:ffffa1d9f2bffe58 EFLAGS: 00010297
[ 17.980134] RAX: ffffa1d9f2be7000 RBX: ffffa1d9f2bfff00 RCX: ffffa1d9eb8ce408
[ 17.981244] RDX: 0000000000000000 RSI: ffffa1d9eb8ce358 RDI: ffffa1d9eb8ce3e8
[ 17.982452] RBP: ffffa1d9f2bffe58 R08: 0000000000000004 R09: ffffa1d9eb8ce340
[ 17.983499] R10: 00000000000001c4 R11: ffffa1d9eb8ce000 R12: ffffa1d9eb8ce3e8
[ 17.984594] R13: ffffa1d9eb8cea00 R14: ffffa1d9eb8ce408 R15: ffffffff979ae630
[ 17.985710] FS: 00007f81ac2c5900(0000) GS:ffffffff9801f000(0000) knlGS:0000000000000000
[ 17.986903] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 17.987772] CR2: 00007f81aadc12a0 CR3: 000000022a715000 CR4: 00000000000006b0
[ 17.988921] Stack:
[ 17.989237] ffffa1d9f2bffe90 ffffffff979ada7f fffffffffffffff4 ffffa1d9eb8ce200
[ 17.990420] 317ed0e000000019 ffffa1d9eb8cea00 ffffa1d9ebefdd00 ffffa1d9f2bfff48
[ 17.991618] ffffffff979afd0a ffffa1d9eb8cea00 ffffa1d9eb8cea00 ffffa1d9f2bffed0
[ 17.992832] Call Trace:
[ 17.993215] [<ffffffff979ada7f>] inotify_poll+0x5f/0x80
[ 17.994015] [<ffffffff979afd0a>] SyS_epoll_ctl+0x55a/0xce0
[ 17.994858] [<ffffffff979ae630>] ? ep_poll_wakeup_proc+0x30/0x30
[ 17.995775] [<ffffffff97d13bf3>] entry_SYSCALL_64_fastpath+0x1e/0xa8
[ 17.996778] Code: 66 90 55 48 89 e5 e8 f7 d4 f0 ff b8 01 00 00 00 0f c1 05 db 8b 86 00 83 c0 01 5d c3 66 0f 1f 44 00 00 55 48 89 e5 e8 d7 d4 f0 ff <0f> 0b 0f 1f 44 00 00 55 48 89 e5 41 54 53 48 89 f3 49 89 fc e8
[ 18.001178] RIP [<ffffffff979ab459>] fsnotify_notify_queue_is_empty+0x9/0x10
[ 18.002268] RSP <ffffa1d9f2bffe58>
[ 18.002809] ---[ end trace 299e1f1032a0eef3 ]---
[ 18.003501] Kernel panic - not syncing: Fatal exception
Thanks,
Kernel Test Robot
4 years, 4 months
[block] d96a38d5c3: WARNING: CPU: 0 PID: 1 at mm/slab_common.c:98 kmem_cache_create+0xbc/0x18b
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/kas/linux.git hugeext4/v3
commit d96a38d5c3b59d57cb49a8f341ad49493be313be ("block: define BIO_MAX_PAGES to HPAGE_PMD_NR if huge page cache enabled")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 320M
caused below changes:
+------------------------------------------------+------------+------------+
| | 5d3cc217b6 | d96a38d5c3 |
+------------------------------------------------+------------+------------+
| boot_successes | 6 | 0 |
| boot_failures | 2 | 8 |
| BUG:unable_to_handle_kernel | 2 | 3 |
| Oops | 2 | 3 |
| calltrace:SyS_ipc | 2 | 3 |
| Kernel_panic-not_syncing:Fatal_exception | 2 | 3 |
| WARNING:at_mm/slab_common.c:#kmem_cache_create | 0 | 8 |
| calltrace:init_bio | 0 | 8 |
+------------------------------------------------+------------+------------+
[ 2.555776] PCI: Using configuration type 1 for base access
[ 2.870504] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 2.885252] ------------[ cut here ]------------
[ 2.887501] WARNING: CPU: 0 PID: 1 at mm/slab_common.c:98 kmem_cache_create+0xbc/0x18b
[ 2.891987] Modules linked in:
[ 2.893538] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.8.0-rc6-00021-gd96a38d #421
[ 2.897164] 00000000 00200246 cef0decc c141aa9e 00000000 c11279b6 cef0dee4 c104990a
[ 2.908396] 00000062 c1a820f0 c1b752f4 c1999b09 cef0def8 c104999c 00000009 00000000
[ 2.912555] 00000000 cef0df1c c11279b6 00000000 c1999afe 00001800 6bc01ac0 c1b3d0bc
[ 2.916733] Call Trace:
[ 2.930981] [<c141aa9e>] dump_stack+0x74/0xa7
[ 2.933128] [<c11279b6>] ? kmem_cache_create+0xbc/0x18b
[ 2.935630] [<c104990a>] __warn+0xbc/0xd3
[ 2.937586] [<c1b752f4>] ? x509_key_init+0xf/0xf
[ 2.939837] [<c104999c>] warn_slowpath_null+0x16/0x1b
[ 2.942279] [<c11279b6>] kmem_cache_create+0xbc/0x18b
[ 2.957745] [<c1b752f4>] ? x509_key_init+0xf/0xf
[ 2.960010] [<c1b7534c>] init_bio+0x58/0x94
[ 2.962048] [<c10004a8>] do_one_initcall+0x83/0x103
[ 2.964428] [<c1061d24>] ? parse_args+0x1c9/0x29c
[ 2.966725] [<c1b41cc0>] ? kernel_init_freeable+0x16f/0x20c
[ 2.969400] [<c1b41ce0>] kernel_init_freeable+0x18f/0x20c
[ 2.994076] [<c1731e6a>] kernel_init+0xd/0xd5
[ 2.996185] [<c17385ae>] ret_from_kernel_thread+0xe/0x30
[ 2.998748] [<c1731e5d>] ? rest_init+0xa6/0xa6
[ 3.000968] ---[ end trace 197bc755366f9a86 ]---
[ 3.021244] ACPI: Added _OSI(Module Device)
Thanks,
Kernel Test Robot
4 years, 4 months
814319da30: BUG: kernel reboot-without-warning in boot stage
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://internal_merge_and_test_tree devel-spot-201609150326
commit 814319da309f05bb4b2fb5eef7ce3f04fdbbb5bf
Merge: 9fe9b21 d59dc7b
Author: 0day robot <fengguang.wu(a)intel.com>
AuthorDate: Thu Sep 15 03:40:50 2016 +0800
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Thu Sep 15 03:40:50 2016 +0800
Merge 'linux-review/Cheng-Chao/sched-core-remove-unnecessary-initialization-in-sched_init/20160914-104004' into devel-spot-201609150326
+------------------------------------------------------------------+------------+------------+------------+------------+
| | 9fe9b21756 | d59dc7bcfa | 814319da30 | cdb44ed160 |
+------------------------------------------------------------------+------------+------------+------------+------------+
| boot_successes | 72 | 82 | 0 | 0 |
| boot_failures | 22 | 10 | 28 | 13 |
| INFO:rcu_sched_self-detected_stall_on_CPU | 16 | 10 | | |
| INFO:rcu_sched_detected_stalls_on_CPUs/tasks | 12 | 5 | | |
| calltrace:mark_rodata_ro | 16 | 10 | | |
| invoked_oom-killer:gfp_mask=0x | 3 | | | |
| Mem-Info | 3 | | | |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 2 | | | |
| BUG:kernel_reboot-without-warning_in_boot_stage | 0 | 0 | 28 | 13 |
+------------------------------------------------------------------+------------+------------+------------+------------+
[ 0.000000] ACPI: RSDT 0x000000001FFE1780 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
Elapsed time: 10
BUG: kernel reboot-without-warning in boot stage
kvm=(
)
git bisect start cdb44ed1606d1c5a233652bfd96695f3d60b3363 9395452b4aab7bc2475ef8935b4a4fb99d778d70 --
git bisect good 006164d02dd5a0dc258e15f35a04c19d98065304 # 05:48 20+ 5 Merge 'integrity/next-restore-kexec' into devel-spot-201609150326
git bisect good 66f0b72fc724cd832c192c175d9eeefe9f020a68 # 06:12 20+ 1 Merge 'linux-review/Arvind-Yadav/power-reset-xgene-reboot-Unmap-region-obtained-by-of_iomap/20160914-195849' into devel-spot-201609150326
git bisect good 53940204790d48aff59e500479858774c2c2399e # 06:25 20+ 5 Merge 'linux-review/Baoyou-Xie/clk-mmp-add-missing-header-dependencies/20160914-144004' into devel-spot-201609150326
git bisect bad 6ac3e12053339c73dbe90a6b25fb313992547f5c # 06:37 0- 19 Merge 'linux-review/Tony-Lindgren/Fix-wlcore-config-firwmare-annoyances/20160914-055819' into devel-spot-201609150326
git bisect bad 814319da309f05bb4b2fb5eef7ce3f04fdbbb5bf # 07:47 0- 22 Merge 'linux-review/Cheng-Chao/sched-core-remove-unnecessary-initialization-in-sched_init/20160914-104004' into devel-spot-201609150326
git bisect good 72db9a8959384cd0e630a254c150d1473b545fa0 # 08:13 21+ 3 Merge 'linux-review/Ian-Kent/fs-make-is_local_mountpoint-usable-by-others/20160914-142415' into devel-spot-201609150326
git bisect good 9fe9b2175687bc6cf419c7e682dd73ef401197be # 08:30 20+ 2 Merge 'linux-review/Peng-Sun/staging-slicoss-fix-different-address-space-sparse-warnings/20160914-112212' into devel-spot-201609150326
git bisect good cd92bfd3b8cb0ec2ee825e55a3aee704cd55aea9 # 08:52 22+ 5 sched/core: Store maximum per-CPU capacity in root domain
git bisect good efca03ecbe29a46c2c5ae539563b6326af9dcba7 # 09:08 21+ 3 schedcore: Remove duplicated init_task's preempt_notifiers init
git bisect good 20e1d4863bfa7152e98f94e5bcdda3e7db41d899 # 09:29 21+ 0 sched/debug: Rename 'schedstat_val()' -> 'schedstat_val_or_zero()'
git bisect good de58af878d9146e5decc0cdd7acabaa82881cbe4 # 09:49 22+ 1 Revert "sched/fair: Make update_min_vruntime() more readable"
git bisect good d59dc7bcfa649ef2128a76b6487b16f4b3f14d23 # 11:25 22+ 5 sched/numa, mm: Revert to checking pmd/pte_write instead of VMA flags
# first bad commit: [814319da309f05bb4b2fb5eef7ce3f04fdbbb5bf] Merge 'linux-review/Cheng-Chao/sched-core-remove-unnecessary-initialization-in-sched_init/20160914-104004' into devel-spot-201609150326
git bisect good 9fe9b2175687bc6cf419c7e682dd73ef401197be # 11:35 61+ 19 Merge 'linux-review/Peng-Sun/staging-slicoss-fix-different-address-space-sparse-warnings/20160914-112212' into devel-spot-201609150326
git bisect good d59dc7bcfa649ef2128a76b6487b16f4b3f14d23 # 11:42 62+ 10 sched/numa, mm: Revert to checking pmd/pte_write instead of VMA flags
# extra tests on HEAD of linux-devel/devel-spot-201609150326
git bisect bad cdb44ed1606d1c5a233652bfd96695f3d60b3363 # 11:42 0- 13 0day head guard for 'devel-spot-201609150326'
# extra tests on tree/branch linux-devel/devel-spot-201609150326
git bisect bad cdb44ed1606d1c5a233652bfd96695f3d60b3363 # 11:44 0- 13 0day head guard for 'devel-spot-201609150326'
# extra tests on tree/branch linus/master
git bisect good 4cea8776571b18db7485930cb422faa739580c8c # 12:09 66+ 70 Merge tag 'pci-v4.8-fixes-2' of git://git.kernel.org/pub/scm/linux/kernel/git/helgaas/pci
# extra tests on tree/branch linux-next/master
git bisect good b224027bb70c10c44137b7dff0ef67a02b8b4ce1 # 12:37 64+ 30 Add linux-next specific files for 20160914
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 4 months
[ima] c49613740f: BUG: spinlock trylock failure on UP on CPU#0, swapper/1
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/zohar/linux-integrity.git next-restore-kexec
commit c49613740f17d2e5085a93ef4ab289e5e502b53d ("ima: store the builtin/custom template definitions in a list")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 320M
caused below changes:
+-------------------------------------------+------------+------------+
| | 6c3d0673b8 | c49613740f |
+-------------------------------------------+------------+------------+
| boot_successes | 4 | 0 |
| boot_failures | 0 | 8 |
| BUG:spinlock_trylock_failure_on_UP_on_CPU | 0 | 8 |
| calltrace:init_ima | 0 | 8 |
| BUG:spinlock_lockup_suspected_on_CPU | 0 | 2 |
+-------------------------------------------+------------+------------+
[ 1.030103] page_owner is disabled
[ 1.030632] Key type trusted registered
[ 1.031236] Key type encrypted registered
[ 1.031702] BUG: spinlock trylock failure on UP on CPU#0, swapper/1
[ 1.032399] lock: template_list+0x0/0x48, .magic: 00000000, .owner: <none>/-1, .owner_cpu: 0
[ 1.033344] CPU: 0 PID: 1 Comm: swapper Not tainted 4.8.0-rc1-00027-gc496137 #1
[ 1.034164] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.035150] ffffffffbc457420 ffff8800129cfdd8 ffffffffbac301d3 ffff8800129cfdf8
[ 1.036041] ffffffffbaab750d ffffffffbc457420 0000000000000000 ffff8800129cfe18
[ 1.036923] ffffffffbaab7790 ffffffffbc457438 ffffffffbc457420 ffff8800129cfe40
[ 1.037812] Call Trace:
[ 1.038100] [<ffffffffbac301d3>] dump_stack+0x19/0x26
[ 1.038685] [<ffffffffbaab750d>] spin_dump+0x6d/0xc0
[ 1.039247] [<ffffffffbaab7790>] do_raw_spin_trylock+0x50/0x60
[ 1.039908] [<ffffffffbb0097ff>] _raw_spin_lock+0x3f/0x70
[ 1.040521] [<ffffffffbabf4f8a>] ? ima_init_template_list+0x2a/0x60
[ 1.041304] [<ffffffffbabf4f8a>] ima_init_template_list+0x2a/0x60
[ 1.042071] [<ffffffffbb8ef850>] ? hash_setup+0xb3/0xb3
[ 1.042667] [<ffffffffbabf4fd9>] ima_init_template_list+0x19/0x20
[ 1.043350] [<ffffffffbb8ef85a>] init_ima+0xa/0x35
[ 1.043901] [<ffffffffbb8ef850>] ? hash_setup+0xb3/0xb3
[ 1.044496] [<ffffffffbb8d6050>] do_one_initcall+0x98/0x13d
[ 1.045137] [<ffffffffbb8d58a3>] ? set_debug_rodata+0x12/0x12
[ 1.045791] [<ffffffffbb8d61fa>] kernel_init_freeable+0x105/0x18d
[ 1.046471] [<ffffffffbb003149>] kernel_init+0x9/0xf0
[ 1.047061] [<ffffffffbb00a69f>] ret_from_fork+0x1f/0x40
[ 1.047670] [<ffffffffbb003140>] ? rest_init+0xc0/0xc0
Elapsed time: 10
Thanks,
Kernel Test Robot
4 years, 4 months
