Git tree request
by Jessica Yu
Hi 0-day team,
I'd like to swap out the repo I currently have listed (github) to
the following new repo at git.kernel.org:
git://git.kernel.org/pub/scm/linux/kernel/git/jeyu/linux.git
Could you add the above repo to the 0-day testing list? I'd also like
to have the modules-next and modules-linus branches regularly tested,
and would also like to request build success notifications from the
kbuild bot.
Thank you!
Jessica Yu
4 years, 1 month
[mm] ee85715d28: BUG: sleeping function called from invalid context at mm/swap.c:741
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/paulmck/linux-rcu.git rcu/next
commit ee85715d2898ab6cf61e30db4d2df8f7e58bd82d
Author: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
AuthorDate: Fri Nov 25 12:48:10 2016 -0800
Commit: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
CommitDate: Fri Nov 25 12:48:10 2016 -0800
mm: Prevent release_pages() RCU CPU stall warnings
This commit adds a cond_resched_rcu_qs() calls in release_pages() in
order to prevent RCU CPU stall warnings. More importantly, this change
allows RCU grace periods to complete despite "longer than average"
loops in release_pages().
Note that this is a case of "treating the symptoms". In addition, it
is quite possible that the cond_resched_rcu_qs() needs to be in some
lower-level function that might have been inlined into release_pages().
In short, this one is experimental in nature.
Reported-by: Boris Zhmurov <bb(a)kernelpanic.ru>
Signed-off-by: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
Cc: Michal Hocko <mhocko(a)kernel.org>
Cc: Andrew Morton <akpm(a)linux-foundation.org>
Cc: Johannes Weiner <hannes(a)cmpxchg.org>
Cc: Vlastimil Babka <vbabka(a)suse.cz>
Cc: <linux-mm(a)kvack.org>
+----------------------------------------------------------------------+------------+------------+------------+
| | d2db185bfe | ee85715d28 | e7c1db75fe |
+----------------------------------------------------------------------+------------+------------+------------+
| boot_successes | 67 | 0 | 0 |
| boot_failures | 0 | 26 | 19 |
| BUG:sleeping_function_called_from_invalid_context_at_mm/swap.c | 0 | 26 | 19 |
| calltrace:run_init_process | 0 | 26 | 19 |
| calltrace:SyS_execve | 0 | 3 | 1 |
| calltrace:SyS_munmap | 0 | 21 | 14 |
| calltrace:SyS_exit_group | 0 | 20 | 15 |
| calltrace:SyS_mmap_pgoff | 0 | 1 | 3 |
| calltrace:SyS_read | 0 | 1 | 1 |
| BUG:sleeping_function_called_from_invalid_context_at_mm/page_alloc.c | 0 | 0 | 1 |
| calltrace:SyS_write | 0 | 0 | 1 |
+----------------------------------------------------------------------+------------+------------+------------+
[ 20.414907] Freeing unused kernel memory: 2156K (c26c4000 - c28df000)
[ 20.417108] Write protecting the kernel text: 12104k
[ 20.418712] Write protecting the kernel read-only data: 6708k
[ 20.419860] BUG: sleeping function called from invalid context at mm/swap.c:741
[ 20.421058] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: init
[ 20.421906] 1 lock held by init/1:
[ 20.422372] #0:
[ 20.422628] (
&mm->mmap_sem
[ 20.423059] ){......}
, at:
[ 20.423556] [<c11ec371>] setup_arg_pages+0x11c/0x497
[ 20.424197] CPU: 0 PID: 1 Comm: init Not tainted 4.9.0-rc1-00046-gee85715 #74
[ 20.425090] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 20.426190] d4667db4 c1647e08 d4664000 00000000 00000000 d4667dc8 c109d094 d4a3e7e0
[ 20.427306] 000006fc d4cc6ea0 d4667e10 c11786df 00000000 0000000d d4a3e7e8 d4667dfc
[ 20.428420] d4664000 00000000 d4667e00 c10c389d 00000000 00200246 00000000 d4667dfc
[ 20.429553] Call Trace:
[ 20.429872] [<c1647e08>] dump_stack+0x8a/0xb9
[ 20.430429] [<c109d094>] ___might_sleep+0x16a/0x17e
[ 20.431056] [<c11786df>] release_pages+0x5a/0x537
[ 20.431658] [<c10c389d>] ? do_raw_spin_unlock+0xef/0x12b
[ 20.432327] [<c1178ca0>] pagevec_lru_move_fn+0xe4/0xf2
[ 20.433046] [<c117792e>] ? lru_deactivate_fn+0x326/0x326
[ 20.433721] [<c1179b31>] __pagevec_lru_add+0x14/0x16
[ 20.434346] [<c1179eb1>] lru_add_drain_cpu+0x3b/0x12a
[ 20.434988] [<c1179fbc>] lru_add_drain+0x1c/0x53
[ 20.435578] [<c11ec62c>] setup_arg_pages+0x3d7/0x497
[ 20.436203] [<c178fac7>] ? get_random_long+0x6e/0x8e
[ 20.436847] [<c12602c9>] load_elf_binary+0x53a/0x1185
[ 20.437492] [<c11cd76e>] ? mem_cgroup_hierarchy_write+0x32/0xfb
[ 20.438232] [<c11ed5d8>] search_binary_handler+0x6f/0x110
[ 20.438917] [<c11eddba>] do_execveat_common+0x741/0xa5e
[ 20.439582] [<c1bc0f5d>] ? rest_init+0xa6/0xa6
[ 20.440145] [<c11ee0f0>] do_execve+0x19/0x1b
[ 20.440729] [<c1000371>] run_init_process+0x21/0x23
[ 20.441349] [<c1bc0fd0>] kernel_init+0x73/0x15a
[ 20.441932] [<c1bcfe03>] ret_from_fork+0x1b/0x28
[ 20.461054] random: init: uninitialized urandom read (12 bytes read)
[ 20.635098] random: trinity: uninitialized urandom read (4 bytes read)
mountall: Event failed
git bisect start e7c1db75fed821a961ce1ca2b602b08e75de0cd8 1001354ca34179f3db924eb66672442a173147dc --
git bisect good be6321b237c89ce46b09f99887d887a7c7ce3024 # 09:03 21+ 1 torture: Add tests without slow grace period setup/cleanup
git bisect good 68ad1194cfc010f5f627dd9eec827e8ee94c18df # 09:14 21+ 0 rcu: Remove unneeded rcu_process_callbacks() declarations
git bisect good 80c099e11c191b0e3c1c7dac2d460cb12d0ea3c7 # 09:20 22+ 0 mm: Prevent shrink_node() RCU CPU stall warnings
git bisect bad ee85715d2898ab6cf61e30db4d2df8f7e58bd82d # 09:26 0- 10 mm: Prevent release_pages() RCU CPU stall warnings
git bisect good d2db185bfee894c573faebed93461e9938bdbb61 # 09:34 22+ 0 rcu: Remove short-term CPU kicking
# first bad commit: [ee85715d2898ab6cf61e30db4d2df8f7e58bd82d] mm: Prevent release_pages() RCU CPU stall warnings
git bisect good d2db185bfee894c573faebed93461e9938bdbb61 # 09:37 63+ 0 rcu: Remove short-term CPU kicking
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad ee85715d2898ab6cf61e30db4d2df8f7e58bd82d # 09:47 0- 1 mm: Prevent release_pages() RCU CPU stall warnings
# extra tests on HEAD of rcu/rcu/next
git bisect bad e7c1db75fed821a961ce1ca2b602b08e75de0cd8 # 09:47 0- 19 mm: Prevent __alloc_pages_nodemask() RCU CPU stall warnings
# extra tests on tree/branch rcu/rcu/next
git bisect bad e7c1db75fed821a961ce1ca2b602b08e75de0cd8 # 09:47 0- 19 mm: Prevent __alloc_pages_nodemask() RCU CPU stall warnings
# extra tests with first bad commit reverted
git bisect bad 5f3fe5fd5df25ddf2a262f8b1859df161632f285 # 09:59 0- 8 Revert "mm: Prevent release_pages() RCU CPU stall warnings"
# extra tests on tree/branch linus/master
git bisect good 3ad0e83cf86bcaeb6ca3c37060a3ce866b25fb42 # 10:11 66+ 1 Merge branch 'parisc-4.9-4' of git://git.kernel.org/pub/scm/linux/kernel/git/deller/parisc-linux
# extra tests on tree/branch linux-next/master
git bisect good 09bfc750ec237029d69ebb749602b6f1304d028a # 10:16 60+ 1 Add linux-next specific files for 20161125
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 1 month
[mm] 68ab21008a: BUG:unable_to_handle_kernel
by kernel test robot
FYI, we noticed the following commit:
commit 68ab21008a656abeb1fe2c7117a67eeab4d68ded ("mm: ovl: copy-up on MAP_SHARED")
url: https://github.com/0day-ci/linux/commits/Miklos-Szeredi/overlayfs-fix-ro-...
base: https://git.kernel.org/pub/scm/linux/kernel/git/mszeredi/vfs.git overlayfs-next
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu IvyBridge -m 360M
caused below changes:
+------------------------------------------+------------+------------+
| | b45dbaab96 | 68ab21008a |
+------------------------------------------+------------+------------+
| boot_successes | 4 | 0 |
| boot_failures | 0 | 5 |
| BUG:unable_to_handle_kernel | 0 | 5 |
| Oops | 0 | 5 |
| RIP:vm_mmap_pgoff | 0 | 5 |
| calltrace:SyS_mmap_pgoff | 0 | 5 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 5 |
+------------------------------------------+------------+------------+
[ 5.829350] job=/lkp/scheduled/vm-ivb41-yocto-ia32-22/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-68ab21008a656abeb1fe2c7117a67eeab4d68ded-20161126-104135-1r99nk0-0.yaml
[ 5.829350] run-job /lkp/scheduled/vm-ivb41-yocto-ia32-22/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-68ab21008a656abeb1fe2c7117a67eeab4d68ded-20161126-104135-1r99nk0-0.yaml
[ 5.829350] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled... -O /dev/null
[ 16.146778] BUG: unable to handle kernel NULL pointer dereference at 0000000000000018
[ 16.150092] IP: [<ffffffff811eb290>] vm_mmap_pgoff+0x63/0xe7
[ 16.152858] PGD 71bf067
[ 16.153453] PUD 688b067
PMD 0
[ 16.154890]
[ 16.156053] Oops: 0000 [#1] SMP
[ 16.157251] Modules linked in:
[ 16.158444] CPU: 0 PID: 1483 Comm: trinity Not tainted 4.9.0-rc4-00032-g68ab210 #1
[ 16.160691] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 16.162901] task: ffff880006cc1c00 task.stack: ffffc9000027c000
[ 16.164145] RIP: 0010:[<ffffffff811eb290>] [<ffffffff811eb290>] vm_mmap_pgoff+0x63/0xe7
[ 16.166525] RSP: 0000:ffffc9000027fe90 EFLAGS: 00010246
[ 16.167620] RAX: ffff880006cc1c00 RBX: 0000000000000000 RCX: 0000000000000001
[ 16.169186] RDX: 0000000000001000 RSI: 0000000000000000 RDI: 0000000000000000
[ 16.170761] RBP: ffffc9000027fed8 R08: 0000000000000021 R09: 0000000000000000
[ 16.172283] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001
[ 16.173878] R13: 0000000000001000 R14: ffff880006cc8800 R15: 0000000000000000
[ 16.175332] FS: 0000000000000000(0000) GS:ffff880016000000(0063) knlGS:0000000009af2840
[ 16.177455] CS: 0010 DS: 002b ES: 002b CR0: 0000000080050033
[ 16.178827] CR2: 0000000000000018 CR3: 00000000071b4000 CR4: 00000000001406f0
[ 16.180266] Stack:
[ 16.181094] 0000000000000000 0000000000001000 0000000000000000 ffff880004b6ff00
[ 16.183365] 0000000000001000 0000000000000021 0000000000001000 0000000000000000
[ 16.185548] 0000000000000000 ffffc9000027ff30 ffffffff812036f8 0000000600270000
[ 16.187824] Call Trace:
[ 16.188656] [<ffffffff812036f8>] SyS_mmap_pgoff+0x184/0x1a9
[ 16.189918] [<ffffffff810019e8>] do_int80_syscall_32+0x64/0xbf
[ 16.191138] [<ffffffff81ae3938>] entry_INT80_compat+0x38/0x50
[ 16.192397] Code: 03 00 00 75 21 49 83 c6 68 4c 89 45 b8 49 c7 c5 fc ff ff ff 4c 89 f7 e8 c0 4c 8f 00 85 c0 4c 8b 45 b8 75 79 eb 37 f6 c1 02 75 da <48> 8b 47 18 8b 10 0f ba e2 1a 73 19 48 8b 48 60 4c 89 45 b8 ba
[ 16.201562] RIP [<ffffffff811eb290>] vm_mmap_pgoff+0x63/0xe7
[ 16.203254] RSP <ffffc9000027fe90>
[ 16.204501] CR2: 0000000000000018
[ 16.206063] ---[ end trace 910d3120db07d449 ]---
[ 16.226016] Kernel panic - not syncing: Fatal exception
[ 16.227115] Kernel Offset: disabled
Elapsed time: 20
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Kernel Test Robot
4 years, 1 month
[mm: ovl] cedf014d34: BUG: unable to handle kernel NULL pointer dereference at 0000000c
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/mszeredi/vfs.git overlayfs-rorw
commit cedf014d34d66438f99234f89d0db97889d9fc50
Author: Miklos Szeredi <mszeredi(a)redhat.com>
AuthorDate: Thu Nov 24 11:34:50 2016 +0100
Commit: Miklos Szeredi <mszeredi(a)redhat.com>
CommitDate: Thu Nov 24 11:34:50 2016 +0100
mm: ovl: copy-up on MAP_SHARED
A corner case of a corner case is when
- file opened for O_RDONLY
- which is then memory mapped SHARED
- file opened for O_WRONLY
- contents modified
- contents read back though the shared mapping
Unfortunately it looks very difficult to do anything about the established
shared map after the file is copied up. Instead when a read-only file is
mapped shared overlayfs copies up the file before actually doing the map.
This may result in unnecessary copy-ups (but so may copy-up on open(O_RDWR)
for exampe).
We can revisit this later if it turns out to be a performance problem in
real life.
Signed-off-by: Miklos Szeredi <mszeredi(a)redhat.com>
+------------------------------------------+------------+------------+------------+
| | 16aa350507 | cedf014d34 | cee50d8c76 |
+------------------------------------------+------------+------------+------------+
| boot_successes | 65 | 0 | 0 |
| boot_failures | 0 | 26 | 19 |
| Oops | 0 | 26 | 19 |
| EIP_is_at_vm_mmap_pgoff | 0 | 26 | 19 |
| calltrace:SyS_mmap_pgoff | 0 | 26 | 19 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 25 | 19 |
| BUG:unable_to_handle_kernel | 0 | 15 | 17 |
+------------------------------------------+------------+------------+------------+
[ 20.616479] job=/lkp/scheduled/vm-ivb41-yocto-i386-19/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-cedf014d34d66438f99234f89d0db97889d9fc50-20161124-64340-k7h1jg-1.yaml
[ 20.616479] run-job /lkp/scheduled/vm-ivb41-yocto-i386-19/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-cedf014d34d66438f99234f89d0db97889d9fc50-20161124-64340-k7h1jg-1.yaml
[ 20.616479] skip http request: cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-ivb41-yocto-i386-19/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-cedf014d34d66438f99234f89d0db97889d9fc50-20161124-64340-k7h1jg-1.yaml&job_state=running -O /dev/null
[ 31.715816] BUG: unable to handle kernel NULL pointer dereference at 0000000c
[ 31.717061] IP: [<b10c707e>] vm_mmap_pgoff+0x3e/0xa6
[ 31.717887] *pde = 00000000
[ 31.718407] Oops: 0000 [#1] SMP
[ 31.718975] Modules linked in:
[ 31.719535] CPU: 1 PID: 242 Comm: trinity Not tainted 4.9.0-rc4-00032-gcedf014 #638
[ 31.721026] task: c258d000 task.stack: c2402000
[ 31.721869] EIP: 0060:[<b10c707e>] EFLAGS: 00010246 CPU: 1
[ 31.722741] EIP is at vm_mmap_pgoff+0x3e/0xa6
[ 31.723840] EAX: c258d000 EBX: 00001000 ECX: 00001000 EDX: 00000000
[ 31.724747] ESI: c27cc2c0 EDI: 00000000 EBP: c2403f44 ESP: c2403f2c
[ 31.726021] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[ 31.726810] CR0: 80050033 CR2: 0000000c CR3: 1245a000 CR4: 00000690
[ 31.728080] Stack:
[ 31.728597] 00001000 00000000 c258d000 00001000 00000000 00000001 c2403f6c b10d3921
[ 31.729978] 00000001 00000021 00000000 00000000 b10ceca4 c2403fb4 a77acb19 c258d000
[ 31.731639] c2403f80 b10d463c 00000021 00000003 00000000 c2403fac b1000f69 00000000
[ 31.733215] Call Trace:
[ 31.733622] [<b10d3921>] SYSC_mmap_pgoff+0xf4/0x13a
[ 31.734456] [<b10ceca4>] ? __might_fault+0x2d/0x6b
[ 31.735215] [<b10d463c>] SyS_mmap_pgoff+0x1a/0x1c
[ 31.735974] [<b1000f69>] do_fast_syscall_32+0x9b/0xda
[ 31.736821] [<b14edcb8>] sysenter_past_esp+0x45/0x74
[ 31.737610] Code: 0c 01 89 55 ec 89 4d e8 8b b0 1c 02 00 00 75 15 83 c6 54 bb fc ff ff ff 89 f0 e8 65 57 42 00 85 c0 75 66 eb 29 f6 45 08 02 75 e5 <8b> 47 0c f7 00 00 00 00 04 74 0d 8b 58 7c b9 01 00 00 00 31 d2
[ 31.741886] EIP: [<b10c707e>]
[ 31.742315] vm_mmap_pgoff+0x3e/0xa6
[ 31.742837] SS:ESP 0068:c2403f2c
[ 31.743315] CR2: 000000000000000c
[ 31.743832] ---[ end trace a9080f55400d7639 ]---
[ 31.764663] Kernel panic - not syncing: Fatal exception
git bisect start cee50d8c76273e6237287dcac52b5394e2b640e9 9c763584b7c8911106bb77af7e648bef09af9d80 --
git bisect good 06d23357597be0e5f3b41e7fb2fafe25653201c0 # 22:39 22+ 0 Merge 'linux-review/Miroslav-Lichvar/net-ethtool-don-t-require-CAP_NET_ADMIN-for-ETHTOOL_GLINKSETTINGS/20161124-201244' into devel-catchup-201611242214
git bisect bad 5f050f047c14054b1b307ffea9565b57348a4842 # 22:42 0- 12 Merge 'miklos-vfs/overlayfs-rorw' into devel-catchup-201611242214
git bisect good 3c66ac86b7f7690b04796ea41f23a712aaa5f829 # 22:50 22+ 0 ovl: check lower existence of rename target
git bisect good 404dfcebed548e9c5b028b0fe911b4d6372e9d37 # 22:55 22+ 0 ovl: check for emptiness of redirect dir
git bisect bad cedf014d34d66438f99234f89d0db97889d9fc50 # 22:58 0- 22 mm: ovl: copy-up on MAP_SHARED
git bisect good 7f4c584fb46b5e9b10b813888d5e236415176fd1 # 23:02 22+ 0 ovl: allow setting max size of redirect
git bisect good 16aa35050702792e09313c232c28aede7f05fda0 # 23:09 22+ 0 vfs: export filp_clone_open()
# first bad commit: [cedf014d34d66438f99234f89d0db97889d9fc50] mm: ovl: copy-up on MAP_SHARED
git bisect good 16aa35050702792e09313c232c28aede7f05fda0 # 23:11 63+ 0 vfs: export filp_clone_open()
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad cedf014d34d66438f99234f89d0db97889d9fc50 # 23:19 0- 5 mm: ovl: copy-up on MAP_SHARED
# extra tests on HEAD of linux-devel/devel-catchup-201611242214
git bisect bad cee50d8c76273e6237287dcac52b5394e2b640e9 # 23:19 0- 19 0day head guard for 'devel-catchup-201611242214'
# extra tests on tree/branch miklos-vfs/overlayfs-rorw
git bisect bad c3f7262168faf7ee1fef08f8a98e63003a72abc8 # 23:26 0- 59 ovl: intercept fsync
# extra tests with first bad commit reverted
git bisect good d9f6d0d8ea4e442d3a969f4ae2fc7cbd891a4bd7 # 23:38 66+ 1 Revert "mm: ovl: copy-up on MAP_SHARED"
# extra tests on tree/branch linus/master
git bisect good 10b9dd56860e93f11cd352e8c75a33357b80b70b # 23:41 65+ 0 Merge tag 'nfs-for-4.9-4' of git://git.linux-nfs.org/projects/anna/linux-nfs
# extra tests on tree/branch linux-next/master
git bisect good 4bb70039934e3b322a962da0b39a6eb941614e40 # 23:44 65+ 0 Add linux-next specific files for 20161124
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 1 month
[lkp] [x86/cpufeature] c109bf9599: PANIC:double_fault
by kernel test robot
FYI, we noticed the following commit:
commit c109bf95992b391bb40bc37c5d309d13fead99b5 ("x86/cpufeature: Remove cpu_has_pge")
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-i386 -enable-kvm -smp 2 -m 320M
caused below changes:
+--------------------------------------------------------------------------+------------+------------+
| | 054efb6467 | c109bf9599 |
+--------------------------------------------------------------------------+------------+------------+
| boot_successes | 85 | 23 |
| boot_failures | 65 | 127 |
| invoked_oom-killer:gfp_mask=0x | 65 | 9 |
| Mem-Info | 65 | 9 |
| BUG:unable_to_handle_kernel | 0 | 96 |
| Oops | 0 | 96 |
| EIP_is_at_strlen | 0 | 13 |
| PANIC:double_fault | 0 | 30 |
| calltrace:SyS_init_module | 0 | 72 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 82 |
| EIP_is_at_get_ksymbol | 0 | 17 |
| EIP_is_at_try_module_get | 0 | 1 |
| calltrace:SyS_socketcall | 0 | 7 |
| calltrace:kasprintf | 0 | 12 |
| EIP_is_at_caif_stream_sendmsg[caif_socket] | 0 | 2 |
| calltrace:SyS_sendto | 0 | 1 |
| EIP_is_at_caif_create[caif_socket] | 0 | 6 |
| EIP_is_at_caif_stream_recvmsg[caif_socket] | 0 | 2 |
| calltrace:SyS_recvfrom | 0 | 1 |
| EIP_is_at_load_module | 0 | 43 |
| BUG:kernel_hang_in_test_stage | 0 | 20 |
| BUG:kernel_reboot-without-warning_in_test_stage | 0 | 1 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 1 |
| EIP_is_at_caif_sktinit_module[caif_socket] | 0 | 3 |
| calltrace:SyS_sendmsg | 0 | 1 |
| WARNING:at_kernel/module.c:#module_put | 0 | 1 |
| EIP_is_at_strcmp | 0 | 2 |
| EIP_is_at_sect_empty | 0 | 2 |
| calltrace:caif_sktinit_module | 0 | 1 |
| EIP_is_at_cmp_ex | 0 | 1 |
| WARNING:at_fs/sysfs/dir.c:#sysfs_warn_dup | 0 | 1 |
| WARNING:at_arch/x86/kernel/traps.c:#do_debug | 0 | 1 |
| BUG:sleeping_function_called_from_invalid_context_at_arch/x86/mm/fault.c | 0 | 1 |
+--------------------------------------------------------------------------+------------+------------+
[ 91.997312] Bluetooth: HCI socket layer initialized
[ 91.997946] Bluetooth: L2CAP socket layer initialized
[ 91.998639] Bluetooth: SCO socket layer initialized
[ 92.054441] PANIC: double fault, gdt at c1b7d000 [255 bytes]
[ 92.055035] double fault, tss at c1b8e340
[ 92.055435] eip = c130f80c, esp = ccc4f000
[ 92.055787] eax = 00000000, ebx = f4dc084c, ecx = c130f80c, edx = e9b81098
[ 92.056379] esi = 00000062, edi = 00000000
Elapsed time: 2450
BUG: kernel hang in test stage
initrds=(
/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
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
Thanks,
Xiaolong
4 years, 1 month
[lkp] [block] ef295ecf09: [No primary change] iostat.sda.avgqu-sz +4.4% increase
by kernel test robot
Greeting,
There is no primary kpi change in this test, below is the data collected through multiple monitors running background just for your information.
commit ef295ecf090d3e86e5b742fc6ab34f1122a43773 ("block: better op and flags encoding")
https://git.kernel.org/pub/scm/linux/kernel/git/axboe/linux-block.git for-4.10/block
in testcase: fileio
on test machine: 32 threads Intel(R) Core(TM) i5-2300 CPU @ 2.80GHz with 4G memory
with following parameters:
period: 600s
nr_threads: 100%
disk: 1HDD
fs: btrfs
size: 64G
filenum: 1024f
rwmode: seqwr
iomode: sync
cpufreq_governor: performance
test-description: fileio is a subtest of SysBench benchmark suite to measure file IO performance.
test-url: https://github.com/akopytov/sysbench
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/disk/filenum/fs/iomode/kconfig/nr_threads/period/rootfs/rwmode/size/tbox_group/testcase:
gcc-6/performance/1HDD/1024f/btrfs/sync/x86_64-rhel-7.2/100%/600s/debian-x86_64-2016-08-31.cgz/seqwr/64G/lkp-sb02/fileio
commit:
e806402130 ("block: split out request-only flags into a new namespace")
ef295ecf09 ("block: better op and flags encoding")
e806402130c9c494 ef295ecf090d3e86e5b742fc6a
---------------- --------------------------
%stddev %change %stddev
\ | \
2933152 ± 1% -1.4% 2891323 ± 0% fileio.time.voluntary_context_switches
1.109e+08 ± 7% +13.9% 1.263e+08 ± 3% cpuidle.C1-SNB.time
4402777 ± 21% +35.7% 5972614 ± 7% cpuidle.C1-SNB.usage
2416 ± 24% +44.3% 3486 ± 16% sched_debug.cfs_rq:/.min_vruntime.stddev
2416 ± 24% +44.3% 3486 ± 16% sched_debug.cfs_rq:/.spread0.stddev
140.55 ± 0% +4.4% 146.77 ± 0% iostat.sda.avgqu-sz
940.15 ± 0% +4.0% 978.10 ± 0% iostat.sda.await
940.36 ± 0% +4.0% 978.35 ± 0% iostat.sda.w_await
347520 ±158% -83.1% 58688 ±157% latency_stats.avg.btrfs_async_run_delayed_refs.[btrfs].__btrfs_end_transaction.[btrfs].btrfs_end_transaction.[btrfs].btrfs_cont_expand.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_pwrite64.entry_SYSCALL_64_fastpath
30896 ± 7% +109.8% 64819 ± 58% latency_stats.avg.btrfs_commit_transaction.[btrfs].btrfs_commit_transaction.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 149632 ±135% latency_stats.avg.call_rwsem_down_write_failed.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 65589 ±125% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.btrfs_map_bio.[btrfs].btrfs_submit_bio_hook.[btrfs].submit_one_bio.[btrfs].flush_epd_write_bio.[btrfs].extent_writepages.[btrfs].btrfs_writepages.[btrfs].do_writepages.__filemap_fdatawrite_range
127775 ±113% -92.9% 9078 ±100% latency_stats.avg.rpc_wait_bit_killable.__rpc_wait_for_completion_task._nfs4_proc_open_confirm.[nfsv4].nfs4_do_open.[nfsv4].nfs4_atomic_open.[nfsv4].nfs_atomic_open.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
74228 ±108% -100.0% 0.00 ± -1% latency_stats.avg.wait_on_page_bit.read_extent_buffer_pages.[btrfs].btree_read_extent_buffer_pages.[btrfs].read_tree_block.[btrfs].read_block_for_search.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_csums_range.[btrfs].csum_exist_in_range.[btrfs].run_delalloc_nocow.[btrfs].run_delalloc_range.[btrfs].writepage_delalloc.[btrfs].__extent_writepage.[btrfs]
0.00 ± -1% +Inf% 149632 ±135% latency_stats.max.call_rwsem_down_write_failed.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 65589 ±125% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.btrfs_map_bio.[btrfs].btrfs_submit_bio_hook.[btrfs].submit_one_bio.[btrfs].flush_epd_write_bio.[btrfs].extent_writepages.[btrfs].btrfs_writepages.[btrfs].do_writepages.__filemap_fdatawrite_range
6259 ± 78% -85.6% 904.00 ±162% latency_stats.max.kthread_park.watchdog_park_threads.watchdog_enable_all_cpus.proc_watchdog_update.proc_watchdog_common.proc_nmi_watchdog.proc_sys_call_handler.proc_sys_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
127775 ±113% -92.9% 9078 ±100% latency_stats.max.rpc_wait_bit_killable.__rpc_wait_for_completion_task._nfs4_proc_open_confirm.[nfsv4].nfs4_do_open.[nfsv4].nfs4_atomic_open.[nfsv4].nfs_atomic_open.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
100375 ±100% -100.0% 0.00 ± -1% latency_stats.max.wait_on_page_bit.read_extent_buffer_pages.[btrfs].btree_read_extent_buffer_pages.[btrfs].read_tree_block.[btrfs].read_block_for_search.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_csums_range.[btrfs].csum_exist_in_range.[btrfs].run_delalloc_nocow.[btrfs].run_delalloc_range.[btrfs].writepage_delalloc.[btrfs].__extent_writepage.[btrfs]
0.00 ± -1% +Inf% 149632 ±135% latency_stats.sum.call_rwsem_down_write_failed.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 65589 ±125% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.btrfs_map_bio.[btrfs].btrfs_submit_bio_hook.[btrfs].submit_one_bio.[btrfs].flush_epd_write_bio.[btrfs].extent_writepages.[btrfs].btrfs_writepages.[btrfs].do_writepages.__filemap_fdatawrite_range
8788 ± 97% -89.2% 945.25 ±162% latency_stats.sum.kthread_park.watchdog_park_threads.watchdog_enable_all_cpus.proc_watchdog_update.proc_watchdog_common.proc_nmi_watchdog.proc_sys_call_handler.proc_sys_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
127775 ±113% -92.9% 9078 ±100% latency_stats.sum.rpc_wait_bit_killable.__rpc_wait_for_completion_task._nfs4_proc_open_confirm.[nfsv4].nfs4_do_open.[nfsv4].nfs4_atomic_open.[nfsv4].nfs_atomic_open.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
6824945 ±109% +207.6% 20991695 ±152% latency_stats.sum.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_start_transaction.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
483996 ± 76% +632.2% 3544040 ±159% latency_stats.sum.wait_log_commit.[btrfs].btrfs_sync_log.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
7560189 ± 70% -73.7% 1985977 ± 72% latency_stats.sum.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.btrfs_wait_ordered_range.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
100376 ±100% -100.0% 0.00 ± -1% latency_stats.sum.wait_on_page_bit.read_extent_buffer_pages.[btrfs].btree_read_extent_buffer_pages.[btrfs].read_tree_block.[btrfs].read_block_for_search.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_csums_range.[btrfs].csum_exist_in_range.[btrfs].run_delalloc_nocow.[btrfs].run_delalloc_range.[btrfs].writepage_delalloc.[btrfs].__extent_writepage.[btrfs]
1.77 ± 3% +10.1% 1.96 ± 3% perf-profile.calltrace.cycles.__btrfs_submit_bio_start.run_one_async_start.btrfs_scrubparity_helper.btrfs_worker_helper.process_one_work
1.41 ± 2% +12.1% 1.58 ± 7% perf-profile.calltrace.cycles.__extent_writepage.extent_write_cache_pages.extent_writepages.btrfs_writepages.do_writepages
1.70 ± 3% +9.0% 1.85 ± 4% perf-profile.calltrace.cycles.__irqentry_text_start.crypto_shash_update.btrfs_crc32c.btrfs_csum_data.btrfs_csum_one_bio
1.77 ± 2% +13.4% 2.01 ± 7% perf-profile.calltrace.cycles.__writeback_inodes_wb.wb_writeback.wb_workfn.process_one_work.worker_thread
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.calltrace.cycles.__writeback_single_inode.writeback_sb_inodes.__writeback_inodes_wb.wb_writeback.wb_workfn
1.73 ± 3% +9.3% 1.89 ± 4% perf-profile.calltrace.cycles.btrfs_crc32c.btrfs_csum_data.btrfs_csum_one_bio.__btrfs_submit_bio_start.run_one_async_start
1.73 ± 3% +10.0% 1.91 ± 4% perf-profile.calltrace.cycles.btrfs_csum_data.btrfs_csum_one_bio.__btrfs_submit_bio_start.run_one_async_start.btrfs_scrubparity_helper
1.74 ± 3% +9.6% 1.91 ± 4% perf-profile.calltrace.cycles.btrfs_csum_one_bio.__btrfs_submit_bio_start.run_one_async_start.btrfs_scrubparity_helper.btrfs_worker_helper
1.79 ± 4% +10.1% 1.97 ± 3% perf-profile.calltrace.cycles.btrfs_scrubparity_helper.btrfs_worker_helper.process_one_work.worker_thread.kthread
1.79 ± 4% +10.1% 1.97 ± 3% perf-profile.calltrace.cycles.btrfs_worker_helper.process_one_work.worker_thread.kthread.ret_from_fork
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.calltrace.cycles.btrfs_writepages.do_writepages.__writeback_single_inode.writeback_sb_inodes.__writeback_inodes_wb
1.72 ± 3% +8.8% 1.88 ± 4% perf-profile.calltrace.cycles.crypto_shash_update.btrfs_crc32c.btrfs_csum_data.btrfs_csum_one_bio.__btrfs_submit_bio_start
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.calltrace.cycles.do_writepages.__writeback_single_inode.writeback_sb_inodes.__writeback_inodes_wb.wb_writeback
2.24 ± 7% -43.7% 1.26 ± 27% perf-profile.calltrace.cycles.enqueue_hrtimer.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt
1.73 ± 2% +13.0% 1.96 ± 6% perf-profile.calltrace.cycles.extent_write_cache_pages.extent_writepages.btrfs_writepages.do_writepages.__writeback_single_inode
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.calltrace.cycles.extent_writepages.btrfs_writepages.do_writepages.__writeback_single_inode.writeback_sb_inodes
15.10 ± 16% -94.3% 0.86 ± 16% perf-profile.calltrace.cycles.hrtimer_forward.perf_mux_hrtimer_handler.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt
1.70 ± 3% +8.7% 1.85 ± 4% perf-profile.calltrace.cycles.hrtimer_interrupt.local_apic_timer_interrupt.smp_apic_timer_interrupt.__irqentry_text_start.crypto_shash_update
5.25 ± 2% +9.2% 5.74 ± 5% perf-profile.calltrace.cycles.kthread.ret_from_fork
2.55 ± 8% +66.6% 4.26 ± 6% perf-profile.calltrace.cycles.ktime_get.clockevents_program_event.tick_program_event.hrtimer_interrupt.local_apic_timer_interrupt
1.70 ± 3% +9.0% 1.85 ± 4% perf-profile.calltrace.cycles.local_apic_timer_interrupt.smp_apic_timer_interrupt.__irqentry_text_start.crypto_shash_update.btrfs_crc32c
3.72 ± 2% +11.6% 4.15 ± 4% perf-profile.calltrace.cycles.process_one_work.worker_thread.kthread.ret_from_fork
1.59 ± 10% +91.7% 3.06 ± 7% perf-profile.calltrace.cycles.read_tsc.ktime_get.clockevents_program_event.tick_program_event.hrtimer_interrupt
5.25 ± 2% +9.2% 5.74 ± 5% perf-profile.calltrace.cycles.ret_from_fork
1.77 ± 3% +10.1% 1.96 ± 3% perf-profile.calltrace.cycles.run_one_async_start.btrfs_scrubparity_helper.btrfs_worker_helper.process_one_work.worker_thread
1.70 ± 3% +9.0% 1.85 ± 4% perf-profile.calltrace.cycles.smp_apic_timer_interrupt.__irqentry_text_start.crypto_shash_update.btrfs_crc32c.btrfs_csum_data
1.86 ± 8% -48.9% 0.95 ± 31% perf-profile.calltrace.cycles.timerqueue_add.enqueue_hrtimer.__hrtimer_run_queues.hrtimer_interrupt.local_apic_timer_interrupt
1.83 ± 2% +13.3% 2.07 ± 6% perf-profile.calltrace.cycles.wb_workfn.process_one_work.worker_thread.kthread.ret_from_fork
1.77 ± 2% +13.4% 2.01 ± 7% perf-profile.calltrace.cycles.wb_writeback.wb_workfn.process_one_work.worker_thread.kthread
3.83 ± 2% +10.9% 4.24 ± 4% perf-profile.calltrace.cycles.worker_thread.kthread.ret_from_fork
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.calltrace.cycles.writeback_sb_inodes.__writeback_inodes_wb.wb_writeback.wb_workfn.process_one_work
1.77 ± 3% +10.1% 1.96 ± 3% perf-profile.children.cycles.__btrfs_submit_bio_start
1.41 ± 2% +12.1% 1.58 ± 7% perf-profile.children.cycles.__extent_writepage
1.59 ± 6% -44.5% 0.88 ± 6% perf-profile.children.cycles.__hrtimer_get_next_event
1.77 ± 2% +13.4% 2.01 ± 7% perf-profile.children.cycles.__writeback_inodes_wb
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.children.cycles.__writeback_single_inode
1.73 ± 3% +9.3% 1.89 ± 4% perf-profile.children.cycles.btrfs_crc32c
1.73 ± 3% +10.0% 1.91 ± 4% perf-profile.children.cycles.btrfs_csum_data
1.74 ± 3% +9.6% 1.91 ± 4% perf-profile.children.cycles.btrfs_csum_one_bio
1.87 ± 3% +10.4% 2.06 ± 3% perf-profile.children.cycles.btrfs_scrubparity_helper
1.79 ± 4% +10.1% 1.97 ± 3% perf-profile.children.cycles.btrfs_worker_helper
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.children.cycles.btrfs_writepages
1.72 ± 3% +8.8% 1.88 ± 4% perf-profile.children.cycles.crypto_shash_update
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.children.cycles.do_writepages
5.51 ± 8% -20.0% 4.41 ± 6% perf-profile.children.cycles.enqueue_hrtimer
1.73 ± 2% +13.0% 1.96 ± 6% perf-profile.children.cycles.extent_write_cache_pages
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.children.cycles.extent_writepages
15.54 ± 16% -91.6% 1.30 ± 18% perf-profile.children.cycles.hrtimer_forward
5.25 ± 2% +9.2% 5.74 ± 5% perf-profile.children.cycles.kthread
3.15 ± 9% +64.4% 5.18 ± 5% perf-profile.children.cycles.ktime_get
0.78 ± 17% +28.8% 1.01 ± 4% perf-profile.children.cycles.lapic_next_deadline
3.72 ± 2% +11.6% 4.15 ± 4% perf-profile.children.cycles.process_one_work
0.91 ± 7% -28.5% 0.65 ± 7% perf-profile.children.cycles.rb_insert_color
1.93 ± 10% +90.8% 3.68 ± 7% perf-profile.children.cycles.read_tsc
5.25 ± 2% +9.3% 5.74 ± 5% perf-profile.children.cycles.ret_from_fork
1.77 ± 3% +10.1% 1.96 ± 3% perf-profile.children.cycles.run_one_async_start
4.75 ± 9% -28.3% 3.40 ± 5% perf-profile.children.cycles.timerqueue_add
1.83 ± 2% +13.3% 2.07 ± 6% perf-profile.children.cycles.wb_workfn
1.77 ± 2% +13.4% 2.01 ± 7% perf-profile.children.cycles.wb_writeback
3.83 ± 2% +10.9% 4.24 ± 4% perf-profile.children.cycles.worker_thread
1.77 ± 2% +13.3% 2.00 ± 7% perf-profile.children.cycles.writeback_sb_inodes
1.59 ± 6% -44.5% 0.88 ± 6% perf-profile.self.cycles.__hrtimer_get_next_event
4.62 ± 10% -34.9% 3.00 ± 5% perf-profile.self.cycles.__hrtimer_run_queues
0.87 ± 7% +36.2% 1.19 ± 7% perf-profile.self.cycles.enqueue_hrtimer
15.54 ± 16% -91.6% 1.30 ± 18% perf-profile.self.cycles.hrtimer_forward
1.40 ± 12% -82.3% 0.25 ± 12% perf-profile.self.cycles.hrtimer_interrupt
1.34 ± 6% +29.5% 1.73 ± 2% perf-profile.self.cycles.ktime_get
0.78 ± 17% +28.8% 1.01 ± 4% perf-profile.self.cycles.lapic_next_deadline
22.37 ± 8% +61.4% 36.09 ± 2% perf-profile.self.cycles.perf_mux_hrtimer_handler
0.91 ± 7% -28.5% 0.65 ± 7% perf-profile.self.cycles.rb_insert_color
1.93 ± 10% +90.8% 3.68 ± 7% perf-profile.self.cycles.read_tsc
4.04 ± 11% -28.9% 2.87 ± 8% perf-profile.self.cycles.timerqueue_add
iostat.sda.avgqu-sz
160 ++--------------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O O O
140 *+.*..*...*..*..*..*..*..*...*..*..*..*..*...*..*..*..*..*..*...*..*..*
120 ++ |
| |
100 ++ |
| |
80 ++ |
| |
60 ++ |
40 ++ |
| |
20 ++ |
| |
0 ++---------------------------------------O----------------------------+
[*] bisect-good sample
[O] bisect-bad sample
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.
Thanks,
Xiaolong
4 years, 1 month
[net] 34fad54c25: kernel BUG at include/linux/skbuff.h:1935!
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 34fad54c2537f7c99d07375e50cb30aa3c23bd83 ("net: __skb_flow_dissect() must cap its return value")
in testcase: pbzip2
with following parameters:
nr_threads: 25%
blocksize: 900K
cpufreq_governor: performance
on test machine: 48 threads 2 sockets Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 64G memory
caused below changes:
+------------------------------------------------------------------+------------+------------+
| | 79774d6bfa | 34fad54c25 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 2 |
| boot_failures | 2 | 20 |
| invoked_oom-killer:gfp_mask=0x | 2 | 2 |
| Mem-Info | 2 | 2 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 2 | 2 |
| kernel_BUG_at_include/linux/skbuff.h | 0 | 16 |
| invalid_opcode:#[##]SMP | 0 | 16 |
| RIP:eth_type_trans | 0 | 16 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 15 |
| calltrace:hub_event | 0 | 1 |
| WARNING:at_fs/sysfs/dir.c:#sysfs_warn_dup | 0 | 2 |
| calltrace:parport_pc_init | 0 | 2 |
| calltrace:SyS_finit_module | 0 | 2 |
| WARNING:at_lib/kobject.c:#kobject_add_internal | 0 | 2 |
+------------------------------------------------------------------+------------+------------+
[ 19.375251] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 19.388892] Sending DHCP requests .
[ 19.388892] ------------[ cut here ]------------
[ 19.388894] kernel BUG at include/linux/skbuff.h:1935!
[ 19.388895] invalid opcode: 0000 [#1] SMP
[ 19.388896] Modules linked in:
[ 19.388897] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.9.0-rc3-00320-g34fad54 #1
[ 19.388898] Hardware name: Intel Corporation S2600WP/S2600WP, BIOS SE5C600.86B.02.02.0002.122320131210 12/23/2013
[ 19.388899] task: ffffffff81e0e4c0 task.stack: ffffffff81e00000
[ 19.388904] RIP: 0010:[<ffffffff81837c48>] [<ffffffff81837c48>] eth_type_trans+0xe8/0x140
[ 19.388904] RSP: 0000:ffff88081e803db8 EFLAGS: 00010297
[ 19.388905] RAX: 0000000000000152 RBX: ffff88080221f200 RCX: 0000000000001073
[ 19.388905] RDX: ffff8808013afdc0 RSI: ffff880801114000 RDI: ffff880819407c00
[ 19.388906] RBP: ffff88081e803e20 R08: ffff880801114000 R09: 0000000000000800
[ 19.388907] R10: ffff8808013afec0 R11: ffffea003fd5a880 R12: ffff880819407c00
[ 19.388907] R13: ffff881033408000 R14: ffffc9000843e000 R15: 0000000000000158
[ 19.388908] FS: 0000000000000000(0000) GS:ffff88081e800000(0000) knlGS:0000000000000000
[ 19.388909] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 19.388910] CR2: ffff88103ffff000 CR3: 0000000001e07000 CR4: 00000000001406f0
[ 19.388910] Stack:
[ 19.388912] ffffffff816905a7 ffffea003fd5a880 ffffea0000000008 ffff88080221f050
[ 19.388913] ffff88080221f000 0000004000000160 ffffea003fd5a880 0000000000000000
[ 19.388915] 0000000000000040 0000000000000000 ffff88080221f050 ffff88100d216000
[ 19.388915] Call Trace:
[ 19.388919] <IRQ>
[ 19.388919] [<ffffffff816905a7>] ? igb_clean_rx_irq+0x6a7/0x7d0
[ 19.388921] [<ffffffff81690a52>] igb_poll+0x382/0x700
[ 19.388922] [<ffffffff81690a67>] ? igb_poll+0x397/0x700
[ 19.388925] [<ffffffff8180f2d7>] net_rx_action+0x217/0x360
[ 19.388928] [<ffffffff81957fb4>] __do_softirq+0x104/0x2ab
[ 19.388931] [<ffffffff81086961>] irq_exit+0xf1/0x100
[ 19.388932] [<ffffffff81957cf4>] do_IRQ+0x54/0xd0
[ 19.388935] [<ffffffff81955b8c>] common_interrupt+0x8c/0x8c
[ 19.388938] <EOI>
[ 19.388938] [<ffffffff817c1d12>] ? cpuidle_enter_state+0x122/0x2e0
[ 19.388939] [<ffffffff817c1f07>] cpuidle_enter+0x17/0x20
[ 19.388942] [<ffffffff810c64c3>] call_cpuidle+0x23/0x40
[ 19.388944] [<ffffffff810c66f4>] cpu_startup_entry+0x114/0x200
[ 19.388946] [<ffffffff81947675>] rest_init+0x85/0x90
[ 19.388950] [<ffffffff81ffbf5c>] start_kernel+0x407/0x414
[ 19.388952] [<ffffffff81ffb120>] ? early_idt_handler_array+0x120/0x120
[ 19.388953] [<ffffffff81ffb2d6>] x86_64_start_reservations+0x2a/0x2c
[ 19.388955] [<ffffffff81ffb415>] x86_64_start_kernel+0x13d/0x14c
[ 19.388968] Code: 00 04 00 00 c9 c3 48 33 86 70 03 00 00 48 c1 e0 10 48 85 c0 0f b6 87 90 00 00 00 75 28 83 e0 f8 83 c8 01 88 87 90 00 00 00 eb 82 <0f> 0b 0f b6 87 90 00 00 00 83 e0 f8 83 c8 03 88 87 90 00 00 00
[ 19.388970] RIP [<ffffffff81837c48>] eth_type_trans+0xe8/0x140
[ 19.388970] RSP <ffff88081e803db8>
[ 19.388996] ---[ end trace 107996155a43a15c ]---
[ 19.393422] Kernel panic - not syncing: Fatal exception in interrupt
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
Thanks,
Kernel Test Robot
4 years, 1 month
[lkp] [brd] ff26956875: RIP:delay_tsc
by kernel test robot
FYI, we noticed the following commit:
commit ff26956875c2f05e12ecec9938411a2c7dfc767d ("brd: remove support for BLKFLSBUF")
https://git.kernel.org/pub/scm/linux/kernel/git/axboe/linux-block.git for-4.10/block
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -smp 2 -m 512M
caused below changes:
+-----------------------------------------------------+------------+------------+
| | 366f4aea64 | ff26956875 |
+-----------------------------------------------------+------------+------------+
| boot_successes | 0 | 1 |
| boot_failures | 28 | 30 |
| calltrace:init | 28 | 30 |
| WARNING:at_kernel/locking/lockdep.c:#__lock_acquire | 13 | 7 |
| calltrace:eth_driver_init | 25 | 27 |
| BUG:key_not_in.data | 18 | |
| BUG:unable_to_handle_kernel | 23 | 7 |
| Oops:#[##] | 23 | 7 |
| calltrace:SyS_read | 13 | |
| Kernel_panic-not_syncing:Fatal_exception | 23 | 7 |
| INFO:trying_to_register_non-static_key | 11 | 20 |
| RIP:klist_add_tail | 6 | 4 |
| RIP:kobject_get | 3 | 3 |
| RIP:delay_tsc | 0 | 21 |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 23 |
| RIP:do_raw_spin_lock | 0 | 2 |
+-----------------------------------------------------+------------+------------+
[ 12.893876] ffff88001d06bb18 ffffffff816122cb ffff88001d06bb38 ffffffff81614d1f
[ 12.895521] ffff880017324f48 ffff880017324ec8 ffff88001d06bb50 ffffffff81bea2b3
[ 12.897154] ffff880017324f48 ffff88001d06bb68 ffffffff81beae26 ffff880016c58408
[ 12.898784] Call Trace:
[ 12.899316] [<ffffffff816122cb>] dump_stack+0x1e/0x20
[ 12.900403] [<ffffffff81614d1f>] kobject_init+0x45/0xa5
[ 12.901516] [<ffffffff81bea2b3>] device_initialize+0x28/0xf1
[ 12.902726] [<ffffffff81beae26>] device_register+0x15/0x20
[ 12.903902] [<ffffffff82254bbe>] usb_add_gadget_udc_release+0xdf/0x349
[ 12.905286] [<ffffffff82254edf>] usb_add_gadget_udc+0x24/0x26
[ 12.906543] [<ffffffff82257722>] dummy_udc_probe+0x1c2/0x219
[ 12.907757] [<ffffffff82257560>] ? dummy_urb_enqueue+0x222/0x222
[ 12.909036] [<ffffffff81bf0d48>] platform_drv_probe+0x4e/0xa6
[ 12.910260] [<ffffffff81bf0cfa>] ? platform_drv_remove+0x55/0x55
[ 12.911551] [<ffffffff81bef102>] driver_probe_device+0x169/0x3e1
[ 12.912829] [<ffffffff81bef47a>] ? driver_allows_async_probing+0x1e/0x1e
[ 12.914255] [<ffffffff81bef52e>] __device_attach_driver+0xb4/0xd5
[ 12.915513] [<ffffffff81bed5e4>] bus_for_each_drv+0x7e/0xcf
[ 12.916449] [<ffffffff81beee33>] __device_attach+0xc4/0x14a
[ 12.917388] [<ffffffff81bef702>] device_initial_probe+0x1c/0x1e
[ 12.918503] [<ffffffff81bed874>] bus_probe_device+0x46/0xd7
[ 12.919457] [<ffffffff81beabe3>] device_add+0x4d7/0x705
[ 12.920420] [<ffffffff81bf1661>] platform_device_add+0x1c9/0x24c
[ 12.921421] [<ffffffff83451d3c>] init+0x32b/0x4b3
[ 12.922339] [<ffffffff83451988>] ? ci_hdrc_pci_driver_init+0x20/0x20
[ 12.923528] [<ffffffff83451a11>] ? usb_udc_init+0x66/0x66
[ 12.924401] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 12.925488] [<ffffffff833fe2d3>] do_one_initcall+0xaf/0x172
[ 12.926405] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 12.927309] [<ffffffff833fe55b>] kernel_init_freeable+0x1c5/0x28c
[ 12.928303] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 12.929180] [<ffffffff82831ad1>] kernel_init+0xf/0x13d
[ 12.930017] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 12.930861] [<ffffffff8283a355>] ret_from_fork+0x25/0x30
[ 12.932652] using random self ethernet address
[ 12.933370] using random host ethernet address
[ 12.934665] INFO: trying to register non-static key.
[ 12.935584] the code is fine but needs lockdep annotation.
[ 12.936530] turning off the locking correctness validator.
[ 12.937537] CPU: 0 PID: 1 Comm: swapper Not tainted 4.9.0-rc1-00014-gff26956 #1
[ 12.938691] ffff88001d06ba68 ffffffff816122cb ffff88001d06bad0 ffffffff810f4370
[ 12.939933] ffff88001732a000 0000000000000494 0000000000000494 000000001d06baa0
[ 12.941317] ffffffff811614c4 ffff88001d06bac0 0000000000000246 ffff88001d066000
[ 12.942557] Call Trace:
[ 12.943004] [<ffffffff816122cb>] dump_stack+0x1e/0x20
[ 12.943822] [<ffffffff810f4370>] register_lock_class+0x462/0x4d1
[ 12.944793] [<ffffffff811614c4>] ? __free_pages+0x27/0x30
[ 12.945692] [<ffffffff810f5316>] __lock_acquire+0xa4/0x7d2
[ 12.946705] [<ffffffff810f5d6d>] lock_acquire+0x52/0x75
[ 12.947553] [<ffffffff82830eaf>] ? klist_add_tail+0x25/0x50
[ 12.948583] [<ffffffff82839889>] _raw_spin_lock+0x29/0x38
[ 12.949492] [<ffffffff82830eaf>] ? klist_add_tail+0x25/0x50
[ 12.950386] [<ffffffff82830eaf>] klist_add_tail+0x25/0x50
[ 12.951178] [<ffffffff81beac04>] device_add+0x4f8/0x705
[ 12.952022] [<ffffffff825b6053>] netdev_register_kobject+0x82/0x156
[ 12.953030] [<ffffffff82596ae7>] register_netdevice+0x690/0x6af
[ 12.953989] [<ffffffff82596b22>] register_netdev+0x1c/0x29
[ 12.954896] [<ffffffff8227b7a4>] gether_register_netdev+0x45/0x110
[ 12.956059] [<ffffffff8227e578>] ecm_bind+0x6e/0x330
[ 12.956997] [<ffffffff8227e50a>] ? ecm_alloc+0x11b/0x11b
[ 12.957862] [<ffffffff8224fa3e>] usb_add_function+0xa2/0x18c
[ 12.958782] [<ffffffff82292bbc>] eth_do_config+0x8d/0x12f
[ 12.959656] [<ffffffff82292b2f>] ? eth_bind+0x2a7/0x2a7
[ 12.960505] [<ffffffff8224fd33>] usb_add_config+0x51/0x123
[ 12.961380] [<ffffffff82292a21>] eth_bind+0x199/0x2a7
[ 12.962196] [<ffffffff82250621>] composite_bind+0xa4/0x212
[ 12.963124] [<ffffffff82836553>] ? mutex_unlock+0x9/0xb
[ 12.963973] [<ffffffff82254131>] udc_bind_to_driver+0x38/0x107
[ 12.964916] [<ffffffff82255163>] usb_gadget_probe_driver+0x190/0x1c3
[ 12.965941] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 12.966936] [<ffffffff82250847>] usb_composite_probe+0xb8/0xc7
[ 12.967888] [<ffffffff83452113>] ? midimod_init+0x17/0x17
[ 12.968761] [<ffffffff83452128>] eth_driver_init+0x15/0x17
[ 12.969684] [<ffffffff833fe2d3>] do_one_initcall+0xaf/0x172
[ 12.970591] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 12.971479] [<ffffffff833fe55b>] kernel_init_freeable+0x1c5/0x28c
[ 12.972478] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 12.973348] [<ffffffff82831ad1>] kernel_init+0xf/0x13d
[ 12.974188] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 12.975052] [<ffffffff8283a355>] ret_from_fork+0x25/0x30
[ 40.305430] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
[ 40.308964] CPU: 0 PID: 1 Comm: swapper Not tainted 4.9.0-rc1-00014-gff26956 #1
[ 40.310734] task: ffff88001d066000 task.stack: ffff88001d068000
[ 40.312163] RIP: 0010:[<ffffffff8161f929>] [<ffffffff8161f929>] delay_tsc+0xf/0x45
[ 40.314055] RSP: 0000:ffff88001d06bb20 EFLAGS: 00000202
[ 40.315343] RAX: 0000000045b754dc RBX: ffff880016c59db8 RCX: 0000002045b75482
[ 40.317070] RDX: 0000000000000020 RSI: ffffffff82f7ac49 RDI: 0000000000000001
[ 40.318786] RBP: ffff88001d06bb20 R08: 0000000000000000 R09: 0000000000000000
[ 40.320503] R10: 00000000acef7faa R11: 0000000067f2be7c R12: 0000000030fc940b
[ 40.322211] R13: 00000000a08baba0 R14: 0000000000000000 R15: ffffffff83332840
[ 40.323929] FS: 0000000000000000(0000) GS:ffffffff83230000(0000) knlGS:0000000000000000
[ 40.325876] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 40.327283] CR2: 0000000000000000 CR3: 000000000320c000 CR4: 00000000000006b0
[ 40.329012] Stack:
[ 40.329522] ffff88001d06bb30 ffffffff8161fa36 ffff88001d06bb58 ffffffff810f772f
[ 40.331424] ffff880016c59db8 ffff880016c5d8b8 ffff8800173264f0 ffff88001d06bb78
[ 40.333292] ffffffff82839891 ffffffff82830eaf ffff880016c59db8 ffff88001d06bb98
[ 40.335172] Call Trace:
[ 40.335789] [<ffffffff8161fa36>] __delay+0xa/0xc
[ 40.336936] [<ffffffff810f772f>] do_raw_spin_lock+0x82/0xb9
[ 40.338313] [<ffffffff82839891>] _raw_spin_lock+0x31/0x38
[ 40.339658] [<ffffffff82830eaf>] ? klist_add_tail+0x25/0x50
[ 40.341030] [<ffffffff82830eaf>] klist_add_tail+0x25/0x50
[ 40.342360] [<ffffffff81beac04>] device_add+0x4f8/0x705
[ 40.343661] [<ffffffff825b6053>] netdev_register_kobject+0x82/0x156
[ 40.345200] [<ffffffff82596ae7>] register_netdevice+0x690/0x6af
[ 40.346668] [<ffffffff82596b22>] register_netdev+0x1c/0x29
[ 40.348094] [<ffffffff8227b7a4>] gether_register_netdev+0x45/0x110
[ 40.349633] [<ffffffff8227e578>] ecm_bind+0x6e/0x330
[ 40.350835] [<ffffffff8227e50a>] ? ecm_alloc+0x11b/0x11b
[ 40.352085] [<ffffffff8224fa3e>] usb_add_function+0xa2/0x18c
[ 40.353490] [<ffffffff82292bbc>] eth_do_config+0x8d/0x12f
[ 40.354816] [<ffffffff82292b2f>] ? eth_bind+0x2a7/0x2a7
[ 40.356095] [<ffffffff8224fd33>] usb_add_config+0x51/0x123
[ 40.357448] [<ffffffff82292a21>] eth_bind+0x199/0x2a7
[ 40.358697] [<ffffffff82250621>] composite_bind+0xa4/0x212
[ 40.360040] [<ffffffff82836553>] ? mutex_unlock+0x9/0xb
[ 40.361331] [<ffffffff82254131>] udc_bind_to_driver+0x38/0x107
[ 40.362777] [<ffffffff82255163>] usb_gadget_probe_driver+0x190/0x1c3
[ 40.364330] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 40.365714] [<ffffffff82250847>] usb_composite_probe+0xb8/0xc7
[ 40.367148] [<ffffffff83452113>] ? midimod_init+0x17/0x17
[ 40.368484] [<ffffffff83452128>] eth_driver_init+0x15/0x17
[ 40.369838] [<ffffffff833fe2d3>] do_one_initcall+0xaf/0x172
[ 40.371209] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 40.372594] [<ffffffff833fe55b>] kernel_init_freeable+0x1c5/0x28c
[ 40.374091] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 40.375363] [<ffffffff82831ad1>] kernel_init+0xf/0x13d
[ 40.376643] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 40.377912] [<ffffffff8283a355>] ret_from_fork+0x25/0x30
[ 40.379217] Code: 66 90 eb 0e 0f 1f 40 00 66 2e 0f 1f 84 00 00 00 00 00 48 ff c8 75 fb 48 ff c8 5d c3 55 48 89 e5 ff 05 1c f7 c0 01 0f ae e8 0f 31 <48> c1 e2 20 48 89 d1 48 09 c1 0f ae e8 0f 31 48 c1 e2 20 48 09
[ 40.385209] Kernel panic - not syncing: softlockup: hung tasks
[ 40.386635] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.9.0-rc1-00014-gff26956 #1
[ 40.388700] ffffffff83233e88 ffffffff816122cb ffffffff83233f08 ffffffff81155d07
[ 40.390572] ffffffff00000008 ffffffff83233f18 ffffffff83233eb0 0000000000000000
[ 40.392442] 0000000000000000 ffff88001d066000 0000000000000000 0000000000000001
[ 40.394303] Call Trace:
[ 40.395069] <IRQ>
[ 40.395561] [<ffffffff816122cb>] dump_stack+0x1e/0x20
[ 40.396833] [<ffffffff81155d07>] panic+0xee/0x2a8
[ 40.397999] [<ffffffff81136fe8>] watchdog_timer_fn+0x1a9/0x1d6
[ 40.399442] [<ffffffff81136e3f>] ? __touch_watchdog+0x1b/0x1b
[ 40.400856] [<ffffffff8110cbac>] __hrtimer_run_queues+0xea/0x1c1
[ 40.402579] [<ffffffff8110d25f>] hrtimer_interrupt+0x99/0x1c4
[ 40.403996] [<ffffffff8102ea74>] smp_trace_apic_timer_interrupt+0x6a/0x79
[ 40.405661] [<ffffffff8102ea8c>] smp_apic_timer_interrupt+0x9/0xb
[ 40.407150] [<ffffffff8283ab8d>] apic_timer_interrupt+0x7d/0x90
[ 40.408601] <EOI>
[ 40.409080] [<ffffffff8161f929>] ? delay_tsc+0xf/0x45
[ 40.410344] [<ffffffff8161fa36>] __delay+0xa/0xc
[ 40.411494] [<ffffffff810f772f>] do_raw_spin_lock+0x82/0xb9
[ 40.412862] [<ffffffff82839891>] _raw_spin_lock+0x31/0x38
[ 40.414190] [<ffffffff82830eaf>] ? klist_add_tail+0x25/0x50
[ 40.415568] [<ffffffff82830eaf>] klist_add_tail+0x25/0x50
[ 40.416902] [<ffffffff81beac04>] device_add+0x4f8/0x705
[ 40.418185] [<ffffffff825b6053>] netdev_register_kobject+0x82/0x156
[ 40.419723] [<ffffffff82596ae7>] register_netdevice+0x690/0x6af
[ 40.421168] [<ffffffff82596b22>] register_netdev+0x1c/0x29
[ 40.422517] [<ffffffff8227b7a4>] gether_register_netdev+0x45/0x110
[ 40.424023] [<ffffffff8227e578>] ecm_bind+0x6e/0x330
[ 40.425250] [<ffffffff8227e50a>] ? ecm_alloc+0x11b/0x11b
[ 40.426560] [<ffffffff8224fa3e>] usb_add_function+0xa2/0x18c
[ 40.427958] [<ffffffff82292bbc>] eth_do_config+0x8d/0x12f
[ 40.429279] [<ffffffff82292b2f>] ? eth_bind+0x2a7/0x2a7
[ 40.430569] [<ffffffff8224fd33>] usb_add_config+0x51/0x123
[ 40.431912] [<ffffffff82292a21>] eth_bind+0x199/0x2a7
[ 40.433151] [<ffffffff82250621>] composite_bind+0xa4/0x212
[ 40.434493] [<ffffffff82836553>] ? mutex_unlock+0x9/0xb
[ 40.435775] [<ffffffff82254131>] udc_bind_to_driver+0x38/0x107
[ 40.437221] [<ffffffff82255163>] usb_gadget_probe_driver+0x190/0x1c3
[ 40.438777] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 40.440153] [<ffffffff82250847>] usb_composite_probe+0xb8/0xc7
[ 40.441587] [<ffffffff83452113>] ? midimod_init+0x17/0x17
[ 40.442917] [<ffffffff83452128>] eth_driver_init+0x15/0x17
[ 40.444266] [<ffffffff833fe2d3>] do_one_initcall+0xaf/0x172
[ 40.445645] [<ffffffff833fd768>] ? do_early_param+0xc0/0xc0
[ 40.447017] [<ffffffff833fe55b>] kernel_init_freeable+0x1c5/0x28c
[ 40.448519] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 40.449791] [<ffffffff82831ad1>] kernel_init+0xf/0x13d
[ 40.451064] [<ffffffff82831ac2>] ? rest_init+0xc9/0xc9
[ 40.452344] [<ffffffff8283a355>] ret_from_fork+0x25/0x30
[ 40.453709] Kernel Offset: disabled
Elapsed time: 50
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
4 years, 1 month
[lkp] [net] 50d4a9ef15: INFO:suspicious_RCU_usage
by kernel test robot
FYI, we noticed the following commit:
commit 50d4a9ef15cee93344653f6ca8f9bab62e76e972 ("net: ipv6: avoid errors due to per-cpu atomic alloc")
url: https://github.com/0day-ci/linux/commits/Mike-Manning/net-ipv6-avoid-erro...
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-i386 -enable-kvm -m 320M
caused below changes:
+-----------------------------------------------------------------------------+------------+------------+
| | 3b404a5198 | 50d4a9ef15 |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 2 | 0 |
| boot_failures | 4 | 8 |
| calltrace:init | 4 | 8 |
| IP-Config:Auto-configuration_of_network_failed | 4 | 8 |
| INFO:suspicious_RCU_usage | 0 | 8 |
| calltrace:addrconf_notify | 0 | 8 |
| calltrace:ip_auto_config | 0 | 8 |
| WARNING:at_kernel/locking/mutex.c:#mutex_lock_nested | 0 | 8 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 1 |
| INFO:lockdep_is_turned_off | 0 | 1 |
| calltrace:SyS_ioctl | 0 | 1 |
+-----------------------------------------------------------------------------+------------+------------+
[ 20.158999] ### dt-test ### end of unittest - 149 passed, 0 failed
[ 20.170208]
[ 20.172448] ===============================
[ 20.178239] [ INFO: suspicious RCU usage. ]
[ 20.183917] 4.9.0-rc6-00087-g50d4a9e #1 Not tainted
[ 20.190554] -------------------------------
[ 20.196388] kernel/sched/core.c:7729 Illegal context switch in RCU-bh read-side critical section!
[ 20.210614]
[ 20.210614] other info that might help us debug this:
[ 20.210614]
[ 20.221405]
[ 20.221405] rcu_scheduler_active = 1, debug_locks = 1
[ 20.230284] 3 locks held by swapper/0/1:
[ 20.235791] #0:
[ 20.238261] (
rtnl_mutex
[ 20.242057] ){+.+.+.}
, at:
[ 20.246518] [<43f61b50>] rtnl_lock+0xf/0x11
[ 20.252294] #1:
[ 20.254819] (
rcu_read_lock_bh
[ 20.259422] ){......}
, at:
[ 20.263542] [<4401af1b>] ipv6_add_addr+0x47/0x43e
[ 20.270211] #2:
[ 20.272644] (
addrconf_hash_lock
[ 20.277542] ){+.....}
, at:
[ 20.281681] [<4401afaa>] ipv6_add_addr+0xd6/0x43e
[ 20.288459]
[ 20.288459] stack backtrace:
[ 20.294554] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.9.0-rc6-00087-g50d4a9e #1
[ 20.305143] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 20.317757] 5344bc84 43bf3a0a 53450000 00000001 5344bca0 43aa5c4f 4441008b 4440e52f
[ 20.330680] 4440fa0e 0000026c 00000000 5344bcb4 43a896a6 53450000 00000000 00000000
[ 20.358226] 5344bcd0 43a89863 43b2b5c1 02080020 5344bcd4 4450ff80 4ffa5000 5344bd00
[ 20.367429] Call Trace:
[ 20.370671] [<43bf3a0a>] dump_stack+0x75/0xa9
[ 20.376473] [<43aa5c4f>] lockdep_rcu_suspicious+0xbb/0xc4
[ 20.383600] [<43a896a6>] ___might_sleep+0x82/0x1d2
[ 20.389988] [<43a89863>] __might_sleep+0x6d/0x74
[ 20.396177] [<43b2b5c1>] ? __slab_alloc+0x49/0x59
[ 20.404660] [<441541ee>] mutex_lock_nested+0x1e/0x2c5
[ 20.411304] [<43aa3263>] ? trace_hardirqs_on+0xb/0xd
[ 20.417850] [<43b14b56>] pcpu_alloc+0x84/0x42d
[ 20.423785] [<43f5a9a2>] ? dst_alloc+0x5f/0x6e
[ 20.429678] [<43b15308>] __alloc_percpu_gfp+0xb/0xd
[ 20.436133] [<44023f10>] ip6_dst_alloc+0x23/0x70
[ 20.442194] [<440265cf>] addrconf_dst_alloc+0x34/0xce
[ 20.448861] [<4401b02f>] ipv6_add_addr+0x15b/0x43e
[ 20.455188] [<4401eec6>] add_addr+0x19/0x5a
[ 20.460731] [<44020bf5>] addrconf_notify+0x565/0x93e
[ 20.467366] [<43f719a0>] ? pktgen_device_event+0x100/0x25c
[ 20.474501] [<43a7fd41>] notifier_call_chain+0x25/0x47
[ 20.481300] [<43a7fff4>] raw_notifier_call_chain+0xc/0xe
[ 20.488305] [<43f4ceef>] call_netdevice_notifiers_info+0x41/0x49
[ 20.496217] [<43f4ff3d>] call_netdevice_notifiers+0xc/0xe
[ 20.503271] [<43f53e20>] __dev_notify_flags+0x4a/0x74
[ 20.509920] [<43f543ab>] dev_change_flags+0x43/0x4b
[ 20.516371] [<445ec72b>] ip_auto_config+0xc0/0xddb
[ 20.522658] [<43aab38d>] ? vprintk_default+0x12/0x14
[ 20.529297] [<43fd20c5>] ? tcp_set_default_congestion_control+0x10/0x58
[ 20.537921] [<43fd2108>] ? tcp_set_default_congestion_control+0x53/0x58
[ 20.546538] [<44156fc9>] ? _raw_spin_unlock+0x1d/0x27
[ 20.553212] [<445ec66b>] ? root_nfs_parse_addr+0x8d/0x8d
[ 20.560246] [<445a7c29>] do_one_initcall+0x8b/0x11d
[ 20.585588] [<43a7e80c>] ? parse_args+0x198/0x26c
[ 20.592263] [<445a7da7>] kernel_init_freeable+0xec/0x174
[ 20.599324] [<4414fed1>] ? rest_init+0x111/0x111
[ 20.605448] [<4414fed9>] kernel_init+0x8/0xd0
[ 20.611253] [<44157503>] ret_from_fork+0x1b/0x28
[ 20.617704] ------------[ cut here ]------------
[ 20.623731] WARNING: CPU: 0 PID: 1 at kernel/locking/mutex.c:529 mutex_lock_nested+0x86/0x2c5
[ 20.637016] DEBUG_LOCKS_WARN_ON(in_interrupt())
[ 20.642915] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.9.0-rc6-00087-g50d4a9e #1
[ 20.652634] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 20.663948] 5344bc8c 43bf3a0a 5344bcb8 44154256 5344bca4 43a6a143 00000211 4450ff80
[ 20.675261] 53450000 00000000 5344bcc0 43a6a182 00000009 00000000 5344bcb8 44404245
[ 20.686568] 5344bcd4 5344bd00 44154256 4440fa0e 00000211 44404245 4440f9ff 43aa3263
[ 20.697838] Call Trace:
[ 20.701178] [<43bf3a0a>] dump_stack+0x75/0xa9
[ 20.724842] [<44154256>] ? mutex_lock_nested+0x86/0x2c5
[ 20.731774] [<43a6a143>] __warn+0xad/0xc4
[ 20.737146] [<43a6a182>] warn_slowpath_fmt+0x28/0x2d
[ 20.743729] [<44154256>] mutex_lock_nested+0x86/0x2c5
[ 20.750400] [<43aa3263>] ? trace_hardirqs_on+0xb/0xd
[ 20.756950] [<43b14b56>] pcpu_alloc+0x84/0x42d
[ 20.762878] [<43f5a9a2>] ? dst_alloc+0x5f/0x6e
[ 20.768744] [<43b15308>] __alloc_percpu_gfp+0xb/0xd
[ 20.775201] [<44023f10>] ip6_dst_alloc+0x23/0x70
[ 20.781388] [<440265cf>] addrconf_dst_alloc+0x34/0xce
[ 20.787994] [<4401b02f>] ipv6_add_addr+0x15b/0x43e
[ 20.794380] [<4401eec6>] add_addr+0x19/0x5a
[ 20.799984] [<44020bf5>] addrconf_notify+0x565/0x93e
[ 20.806598] [<43f719a0>] ? pktgen_device_event+0x100/0x25c
[ 20.813858] [<43a7fd41>] notifier_call_chain+0x25/0x47
[ 20.820633] [<43a7fff4>] raw_notifier_call_chain+0xc/0xe
[ 20.827629] [<43f4ceef>] call_netdevice_notifiers_info+0x41/0x49
[ 20.835598] [<43f4ff3d>] call_netdevice_notifiers+0xc/0xe
[ 20.842725] [<43f53e20>] __dev_notify_flags+0x4a/0x74
[ 20.849370] [<43f543ab>] dev_change_flags+0x43/0x4b
[ 20.855851] [<445ec72b>] ip_auto_config+0xc0/0xddb
[ 20.862271] [<43aab38d>] ? vprintk_default+0x12/0x14
[ 20.868812] [<43fd20c5>] ? tcp_set_default_congestion_control+0x10/0x58
[ 20.877495] [<43fd2108>] ? tcp_set_default_congestion_control+0x53/0x58
[ 20.886053] [<44156fc9>] ? _raw_spin_unlock+0x1d/0x27
[ 20.892791] [<445ec66b>] ? root_nfs_parse_addr+0x8d/0x8d
[ 20.899731] [<445a7c29>] do_one_initcall+0x8b/0x11d
[ 20.906240] [<43a7e80c>] ? parse_args+0x198/0x26c
[ 20.912468] [<445a7da7>] kernel_init_freeable+0xec/0x174
[ 20.919462] [<4414fed1>] ? rest_init+0x111/0x111
[ 20.925597] [<4414fed9>] kernel_init+0x8/0xd0
[ 20.931391] [<44157503>] ret_from_fork+0x1b/0x28
[ 20.937679] ---[ end trace 3474b18006c0c631 ]---
[ 20.944287] IP-Config: Failed to open ipddp0
[ 20.950711] usb0: eth_open
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
Thanks,
Xiaolong
4 years, 1 month