[lkp] [writeback] 59125c46ef: BUG: spinlock trylock failure on UP on CPU#0, procd/1
by kernel test robot
FYI, we noticed the following commit:
https://github.com/kdave/btrfs-devel.git foreign/josef/no-btree-inode-4.9
commit 59125c46ef024b5e8b4029e4fec07e4dcb897807 ("writeback: introduce super_operations->write_metadata")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 320M
caused below changes:
+--------------------------------------------------------------+------------+------------+
| | 1006ae3f32 | 59125c46ef |
+--------------------------------------------------------------+------------+------------+
| boot_successes | 3 | 2 |
| boot_failures | 5 | 6 |
| BUG:kernel_reboot-without-warning_in_test_stage | 2 | |
| Kernel_panic-not_syncing:Attempted_to_kill_init!exitcode= | 2 | 2 |
| WARNING:at_arch/x86/include/asm/fpu/internal.h:#fpu__restore | 1 | |
| BUG:spinlock_trylock_failure_on_UP_on_CPU | 0 | 4 |
+--------------------------------------------------------------+------------+------------+
[ 8.953073] kmodloader (133) used greatest stack depth: 30000 bytes left
[ 9.952390] init: - preinit -
[ 9.958574] procd: - early -
[ 9.980264] BUG: spinlock trylock failure on UP on CPU#0, procd/1
[ 9.980953] lock: noop_backing_dev_info+0x3c0/0x440, .magic: 00000000, .owner: <none>/-1, .owner_cpu: 0
[ 9.981966] CPU: 0 PID: 1 Comm: procd Not tainted 4.8.0-rc5-00018-g59125c4 #1
[ 9.982738] ffffffff9b4112c0 ffff8800001bfdd0 ffffffff9a473e41 ffff8800001bfdf0
[ 9.983586] ffffffff9a35a4b0 ffffffff9b4112c0 ffffffff9b01707f ffff8800001bfe10
[ 9.984447] ffffffff9a35a4d6 ffffffff9b4112c0 ffffffff9b4112d8 ffff8800001bfe28
[ 9.985309] Call Trace:
[ 9.985583] [<ffffffff9a473e41>] dump_stack+0x19/0x28
[ 9.986145] [<ffffffff9a35a4b0>] spin_dump+0x85/0x8a
[ 9.986699] [<ffffffff9a35a4d6>] spin_bug+0x21/0x26
[ 9.987236] [<ffffffff9a2ffc8d>] do_raw_spin_trylock+0x3d/0x50
[ 9.987884] [<ffffffff9ab02633>] _raw_spin_lock+0x43/0x70
[ 9.988478] [<ffffffff9a3b6dee>] ? deactivate_locked_super+0x6e/0xb0
[ 9.989179] [<ffffffff9a3b6dee>] deactivate_locked_super+0x6e/0xb0
[ 9.989861] [<ffffffff9a3b6e82>] deactivate_super+0x52/0x60
[ 9.990481] [<ffffffff9a3d305d>] cleanup_mnt+0x4d/0x70
[ 9.991056] [<ffffffff9a3d30bd>] __cleanup_mnt+0xd/0x10
[ 9.991638] [<ffffffff9a2e99a3>] task_work_run+0x73/0xb0
[ 9.992218] [<ffffffff9a2cc6ef>] exit_to_usermode_loop+0x5e/0x88
[ 9.992885] [<ffffffff9a201335>] do_int80_syscall_32+0x95/0xa0
[ 9.993519] [<ffffffff9ab04670>] entry_INT80_compat+0x30/0x40
Elapsed time: 20
Thanks,
Xiaolong
5 years, 8 months
[lkp] [mm, page_alloc] 67e245dc32: INFO: trying to register non-static key.
by kernel test robot
FYI, we noticed the following commit:
git://bee.sh.intel.com/git/yhuang/linux.git percpug
commit 67e245dc32d36fccbe944f661be8f6155631965b ("mm, page_alloc: Use per CPU group page set")
in testcase: trinity
with following parameters:
runtime: 300s
on test machine: qemu-system-i386 -enable-kvm -m 320M
caused below changes:
+-------------------------------------------------+------------+------------+
| | fdc463b953 | 67e245dc32 |
+-------------------------------------------------+------------+------------+
| boot_successes | 5 | 2 |
| boot_failures | 5 | 6 |
| BUG:kernel_reboot-without-warning_in_test_stage | 5 | |
| INFO:trying_to_register_non-static_key | 0 | 6 |
| BUG:unable_to_handle_kernel | 0 | 6 |
| Oops | 0 | 6 |
| EIP_is_at__list_del_entry | 0 | 6 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 6 |
+-------------------------------------------------+------------+------------+
[ 0.000000] Good, all 253 testcases passed! |
[ 0.000000] ---------------------------------
[ 0.000000] CPU group number: 0
[ 0.000000] INFO: trying to register non-static key.
[ 0.000000] the code is fine but needs lockdep annotation.
[ 0.000000] turning off the locking correctness validator.
[ 0.000000] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.8.0-rc5-00007-g67e245d #60
[ 0.000000] 00000000 00200002 c1483d30 c117a802 00000000 00000001 c1483d6c c1072b66
[ 0.000000] c141ba25 c141ba7f c141ba55 c1483d74 00000000 c1485e34 c141cbf1 c1485880
[ 0.000000] c1483dd0 00000000 00000000 00000001 c1485880 c1483d98 c1076ccd 00000000
[ 0.000000] Call Trace:
[ 0.000000] [<c117a802>] dump_stack+0x75/0xa9
[ 0.000000] [<c1072b66>] register_lock_class+0x167/0x3fb
[ 0.000000] [<c1076ccd>] __lock_acquire+0x9e/0x61c
[ 0.000000] [<c107241e>] ? __bfs+0x13/0x1d1
[ 0.000000] [<c107755f>] lock_acquire+0x6a/0x88
[ 0.000000] [<c10c3244>] ? get_page_from_freelist+0x128/0x5a8
[ 0.000000] [<c130c042>] _raw_spin_lock+0x22/0x31
[ 0.000000] [<c10c3244>] ? get_page_from_freelist+0x128/0x5a8
[ 0.000000] [<c10c3244>] get_page_from_freelist+0x128/0x5a8
[ 0.000000] [<c10c3ae2>] __alloc_pages_nodemask+0xd2/0x869
[ 0.000000] [<c10c4288>] __get_free_pages+0xf/0x1f
[ 0.000000] [<c1030f70>] pte_alloc_one_kernel+0xf/0x11
[ 0.000000] [<c10da8fd>] __pte_alloc_kernel+0x12/0x8d
[ 0.000000] [<c117c00c>] ioremap_page_range+0x236/0x2de
[ 0.000000] [<c102da74>] __ioremap_caller+0x28c/0x322
[ 0.000000] [<c102db17>] ioremap_nocache+0xd/0xf
[ 0.000000] [<c14dc6f3>] ? hpet_enable+0x256/0x273
[ 0.000000] [<c14dc6f3>] hpet_enable+0x256/0x273
[ 0.000000] [<c14d22b1>] hpet_time_init+0x8/0x18
[ 0.000000] [<c14d2287>] x86_late_time_init+0x9/0x10
[ 0.000000] [<c14cdb3c>] start_kernel+0x374/0x401
[ 0.000000] [<c14cd2ca>] i386_start_kernel+0x91/0x95
[ 0.000000] BUG: unable to handle kernel NULL pointer dereference at (null)
[ 0.000000] IP: [<c119bf16>] __list_del_entry+0x0/0x71
[ 0.000000] *pdpt = 0000000000000000 *pde = f000ff53f000ff53
[ 0.000000] Oops: 0000 [#1] SMP
[ 0.000000] Modules linked in:
[ 0.000000] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.8.0-rc5-00007-g67e245d #60
[ 0.000000] task: c1485880 task.stack: c1482000
[ 0.000000] EIP: 0060:[<c119bf16>] EFLAGS: 00210046 CPU: 0
[ 0.000000] EIP is at __list_del_entry+0x0/0x71
[ 0.000000] EAX: 00000000 EBX: 00000000 ECX: c1485880 EDX: 00200046
[ 0.000000] ESI: ffffffec EDI: c14bf000 EBP: c1483dec ESP: c1483de4
[ 0.000000] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 0.000000] CR0: 80050033 CR2: 00000000 CR3: 0152e000 CR4: 000006b0
[ 0.000000] Stack:
[ 0.000000] c119bf92 d34512d0 c1483e48 c10c332d 0000042f 00000000 ffffffff c1965150
[ 0.000000] d3451304 00000000 00000000 00000000 00000001 c131231c c14bf840 026080c0
[ 0.000000] d34512dc 00000000 00000001 00200246 00000000 c14bf000 00208000 c14bf840
[ 0.000000] Call Trace:
[ 0.000000] [<c119bf92>] ? list_del+0xb/0x1b
[ 0.000000] [<c10c332d>] get_page_from_freelist+0x211/0x5a8
[ 0.000000] [<c10c3ae2>] __alloc_pages_nodemask+0xd2/0x869
[ 0.000000] [<c10c4288>] __get_free_pages+0xf/0x1f
[ 0.000000] [<c1030f70>] pte_alloc_one_kernel+0xf/0x11
[ 0.000000] [<c10da8fd>] __pte_alloc_kernel+0x12/0x8d
[ 0.000000] [<c117c00c>] ioremap_page_range+0x236/0x2de
[ 0.000000] [<c102da74>] __ioremap_caller+0x28c/0x322
[ 0.000000] [<c102db17>] ioremap_nocache+0xd/0xf
[ 0.000000] [<c14dc6f3>] ? hpet_enable+0x256/0x273
[ 0.000000] [<c14dc6f3>] hpet_enable+0x256/0x273
[ 0.000000] [<c14d22b1>] hpet_time_init+0x8/0x18
[ 0.000000] [<c14d2287>] x86_late_time_init+0x9/0x10
[ 0.000000] [<c14cdb3c>] start_kernel+0x374/0x401
[ 0.000000] [<c14cd2ca>] i386_start_kernel+0x91/0x95
[ 0.000000] Code: 75 17 56 53 57 68 d9 0d 43 c1 6a 24 68 6a 0d 43 c1 e8 09 1f ea ff 83 c4 18 89 7e 04 89 37 89 5f 04 89 3b 8d 65 f4 5b 5e 5f 5d c3 <8b> 08 55 8b 50 04 89 e5 53 81 f9 00 01 00 00 75 41 68 00 01 00
[ 0.000000] EIP: [<c119bf16>] __list_del_entry+0x0/0x71 SS:ESP 0068:c1483de4
[ 0.000000] CR2: 0000000000000000
[ 0.000000] ---[ end trace f68728a0d3053b52 ]---
[ 0.000000] Kernel panic - not syncing: Fatal exception
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
5 years, 8 months
[lkp] [x86/alternatives] 7c14957c0c: INFO: possible circular locking dependency detected ]
by kernel test robot
FYI, we noticed the following commit:
https://github.com/0day-ci/linux Rui-Xiang/x86-alternatives-add-missing-mutex-lock-in-alternatives_text_reserved/20160912-152147
commit 7c14957c0c077a9267149a4a0aefebe89f560e12 ("x86/alternatives: add missing mutex lock in,alternatives_text_reserved")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 320M
caused below changes:
+--------------------------------------------------------------------------------------+------------+------------+
| | 3c84aabde9 | 7c14957c0c |
+--------------------------------------------------------------------------------------+------------+------------+
| boot_successes | 5 | 0 |
| boot_failures | 1 | 8 |
| BUG:kernel_hang_in_early-boot_stage,last_printk:Probing_EDD(edd=off_to_disable)...ok | 1 | |
| INFO:possible_circular_locking_dependency_detected | 0 | 8 |
| calltrace:init_kprobes | 0 | 8 |
+--------------------------------------------------------------------------------------+------------+------------+
[ 18.166305] Kprobe smoke test: started
[ 18.172662]
[ 18.173782] ======================================================
[ 18.177716] [ INFO: possible circular locking dependency detected ]
[ 18.181715] 4.8.0-rc5-00438-g7c14957 #1 Not tainted
[ 18.184921] -------------------------------------------------------
[ 18.188716] swapper/0/1 is trying to acquire lock:
[ 18.191783] (smp_alt){+.+...}, at: [<ffffffffa9026e7b>] alternatives_text_reserved+0x1b/0x90
[ 18.197820]
[ 18.197820] but task is already holding lock:
[ 18.201537] (text_mutex){+.+...}, at: [<ffffffffa914f803>] register_kprobe+0x643/0x720
[ 18.207035]
[ 18.207035] which lock already depends on the new lock.
[ 18.207035]
[ 18.212068]
[ 18.212068] the existing dependency chain (in reverse order) is:
[ 18.216850]
-> #1 (text_mutex){+.+...}:
[ 18.219802] [<ffffffffa90e6891>] lock_acquire+0x71/0x90
[ 18.223633] [<ffffffffa98dcbb1>] mutex_lock_nested+0x71/0x370
[ 18.227711] [<ffffffffa90279de>] alternatives_smp_module_add+0xee/0x160
[ 18.232118] [<ffffffffaa09a136>] alternative_instructions+0x6f/0xc6
[ 18.236675] [<ffffffffaa09bdc2>] check_bugs+0x13/0x39
[ 18.240424] [<ffffffffaa0910d6>] start_kernel+0x464/0x480
[ 18.244492] [<ffffffffaa09028d>] x86_64_start_reservations+0x24/0x26
[ 18.249126] [<ffffffffaa090372>] x86_64_start_kernel+0xe3/0xf0
[ 18.253397]
-> #0 (smp_alt){+.+...}:
[ 18.256056] [<ffffffffa90e5fc9>] __lock_acquire+0xcd9/0x11c0
[ 18.260064] [<ffffffffa90e6891>] lock_acquire+0x71/0x90
[ 18.263879] [<ffffffffa98dcbb1>] mutex_lock_nested+0x71/0x370
[ 18.268131] [<ffffffffa9026e7b>] alternatives_text_reserved+0x1b/0x90
[ 18.276715] [<ffffffffa90439fd>] arch_prepare_kprobe+0x1d/0x110
[ 18.284026] [<ffffffffa914f80b>] register_kprobe+0x64b/0x720
[ 18.289064] [<ffffffffa914a07d>] init_test_probes+0x5d/0x5b0
[ 18.300388] [<ffffffffaa0afb01>] init_kprobes+0x21c/0x261
[ 18.304374] [<ffffffffa90004ae>] do_one_initcall+0x9e/0x180
[ 18.308501] [<ffffffffaa091309>] kernel_init_freeable+0x217/0x2d8
[ 18.313007] [<ffffffffa98d1e53>] kernel_init+0x13/0x160
[ 18.316859] [<ffffffffa98e1baa>] ret_from_fork+0x2a/0x40
[ 18.320449]
[ 18.320449] other info that might help us debug this:
[ 18.320449]
[ 18.325461] Possible unsafe locking scenario:
[ 18.325461]
[ 18.329341] CPU0 CPU1
[ 18.332070] ---- ----
[ 18.335136] lock(text_mutex);
[ 18.337314] lock(smp_alt);
[ 18.340894] lock(text_mutex);
[ 18.344641] lock(smp_alt);
[ 18.346744]
[ 18.346744] *** DEADLOCK ***
[ 18.346744]
[ 18.350563] 2 locks held by swapper/0/1:
[ 18.353217] #0: (kprobe_mutex){+.+...}, at: [<ffffffffa914f331>] register_kprobe+0x171/0x720
[ 18.358937] #1: (text_mutex){+.+...}, at: [<ffffffffa914f803>] register_kprobe+0x643/0x720
[ 18.364580]
[ 18.364580] stack backtrace:
[ 18.367479] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.8.0-rc5-00438-g7c14957 #1
[ 18.380205] ffffab5f80073bd0 ffffffffa953120a ffffffffaa8a1c40 ffffffffaa8a1c40
[ 18.397397] ffffffffaa89d490 ffff99f10e8be820 ffffab5f80073c10 ffffffffa90e26d1
[ 18.409510] 000000000e8be820 ffff99f10e8be858 ffff99f10e8be820 9b6c97af48d594d9
[ 18.423190] Call Trace:
[ 18.424696] [<ffffffffa953120a>] dump_stack+0xb3/0x119
[ 18.432176] [<ffffffffa90e26d1>] print_circular_bug+0x201/0x220
[ 18.440272] [<ffffffffa90e5fc9>] __lock_acquire+0xcd9/0x11c0
[ 18.445740] [<ffffffffa9149d40>] ? stop_machine_from_inactive_cpu+0x160/0x160
[ 18.450226] [<ffffffffa90e6891>] lock_acquire+0x71/0x90
[ 18.453574] [<ffffffffa9026e7b>] ? alternatives_text_reserved+0x1b/0x90
[ 18.470881] [<ffffffffa98dcbb1>] mutex_lock_nested+0x71/0x370
[ 18.474546] [<ffffffffa9026e7b>] ? alternatives_text_reserved+0x1b/0x90
[ 18.478720] [<ffffffffa90e3be2>] ? trace_hardirqs_on_caller+0x192/0x1d0
[ 18.496043] [<ffffffffa9026e7b>] ? alternatives_text_reserved+0x1b/0x90
Thanks,
Xiaolong
5 years, 8 months
[lkp] [nbd] 3fabab1cec: BUG: unable to handle kernel NULL pointer dereference at (null)
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 3fabab1cec36f1873049de5dd05a9ed28ec168e2 ("nbd: add multi-connection support")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu Westmere -m 512M
caused below changes:
+-----------------------------------------------------------------------------+------------+------------+
| | 0eadf37afc | 3fabab1cec |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 2 | 4 |
| boot_failures | 12 | 10 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 10 | |
| calltrace:compat_SyS_mount | 10 | 10 |
| BUG:kernel_reboot-without-warning_in_test_stage | 2 | |
| BUG:unable_to_handle_kernel | 0 | 10 |
| Oops | 0 | 10 |
| RIP:nbd_queue_rq | 0 | 10 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 10 |
+-----------------------------------------------------------------------------+------------+------------+
[ 17.846260] floppy: error -5 while reading block 0
[ 17.870246] floppy: error -5 while reading block 0
[ 17.894227] floppy: error -5 while reading block 0
[ 17.904564] BUG: unable to handle kernel NULL pointer dereference at (null)
[ 17.904571] IP: [<ffffffff81c2a3e8>] nbd_queue_rq+0x7f/0x349
[ 17.904574] PGD 35d14067 PUD 358b3067 PMD 0
[ 17.904576] Oops: 0000 [#1] SMP
[ 17.904578] Modules linked in:
[ 17.904581] CPU: 0 PID: 1009 Comm: mount Not tainted 4.8.0-rc4-00011-g3fabab1 #65
[ 17.904582] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 17.904583] task: ffff880035868300 task.stack: ffff880034ce4000
[ 17.904587] RIP: 0010:[<ffffffff81c2a3e8>] [<ffffffff81c2a3e8>] nbd_queue_rq+0x7f/0x349
[ 17.904589] RSP: 0000:ffff880034ce7a68 EFLAGS: 00010246
[ 17.904590] RAX: 0000000000000000 RBX: ffff88003d2d98b0 RCX: 0000000000000006
[ 17.904591] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000202
[ 17.904592] RBP: ffff880034ce7ae8 R08: 00000007857977bf R09: ffffffff8502fe60
[ 17.904593] R10: ffff880034ce78f0 R11: 0000000000000001 R12: ffff880034ce7b10
[ 17.904594] R13: ffff8800394f0170 R14: ffff880039335848 R15: ffff8800394f0000
[ 17.904597] FS: 0000000000000000(0000) GS:ffff88003f600000(0063) knlGS:00000000f77d96c0
[ 17.904598] CS: 0010 DS: 002b ES: 002b CR0: 0000000080050033
[ 17.904599] CR2: 0000000000000000 CR3: 0000000034e0e000 CR4: 00000000000006f0
[ 17.904603] Stack:
[ 17.904606] ffff880035868ba8 ffff880034ce7a80 0000000000000246 ffff880034ce7ac8
[ 17.904609] 0000000181123900 0000000000000246 ffff880035868b70 000000000000010f
[ 17.904612] ffffe8ffffc14500 ffff880034ce7b10 0000000000000000 ffff880039335800
[ 17.904613] Call Trace:
[ 17.904617] [<ffffffff81772b66>] __blk_mq_run_hw_queue+0x1c7/0x2c8
[ 17.904620] [<ffffffff81772942>] blk_mq_run_hw_queue+0x5e/0xbb
[ 17.904622] [<ffffffff81773d53>] blk_sq_make_request+0x1a1/0x1ba
[ 17.904626] [<ffffffff81768e24>] generic_make_request+0xbd/0x160
[ 17.904628] [<ffffffff81768fbd>] submit_bio+0xf6/0xff
[ 17.904632] [<ffffffff81252806>] submit_bh_wbc+0x136/0x143
[ 17.904634] [<ffffffff81252c00>] submit_bh+0x10/0x12
[ 17.904636] [<ffffffff81252c52>] __bread_gfp+0x50/0x6f
[ 17.904639] [<ffffffff812f290a>] ext4_fill_super+0x1f4/0x27ec
[ 17.904643] [<ffffffff81798a59>] ? vsnprintf+0x22d/0x3b7
[ 17.904647] [<ffffffff812265e3>] mount_bdev+0x144/0x197
[ 17.904649] [<ffffffff812f2716>] ? ext4_calculate_overhead+0x2bd/0x2bd
[ 17.904653] [<ffffffff812ede93>] ext4_mount+0x15/0x17
[ 17.904655] [<ffffffff81227049>] mount_fs+0x67/0x131
[ 17.904657] [<ffffffff8123ee2f>] vfs_kern_mount+0x6b/0xdb
[ 17.904659] [<ffffffff81241759>] do_mount+0x708/0x97d
[ 17.904663] [<ffffffff811e87f0>] ? __might_fault+0x7e/0x84
[ 17.904665] [<ffffffff811da511>] ? strndup_user+0x3f/0x53
[ 17.904668] [<ffffffff81268eb6>] compat_SyS_mount+0x185/0x1ae
[ 17.904671] [<ffffffff81003b6f>] do_int80_syscall_32+0x5c/0x6b
[ 17.904676] [<ffffffff82efb6d8>] entry_INT80_compat+0x38/0x50
[ 17.904705] Code: 74 1c f6 03 02 74 17 48 8b bb a8 01 00 00 48 c7 c6 0a 6e b1 83 48 81 c7 a0 00 00 00 eb 4b 49 63 97 78 01 00 00 48 8b 43 10 31 f6 <4c> 8b 34 d0 41 c7 87 04 01 00 00 00 00 00 00 4d 8d 66 08 4c 89
[ 17.904708] RIP [<ffffffff81c2a3e8>] nbd_queue_rq+0x7f/0x349
[ 17.904708] RSP <ffff880034ce7a68>
[ 17.904709] CR2: 0000000000000000
[ 17.904713] ---[ end trace 2dd9ac588a589f99 ]---
[ 17.904715] Kernel panic - not syncing: Fatal exception
Thanks,
Xiaolong
5 years, 8 months
[linux] 71f7f5b9c7: BUG: key ffff880018294a98 not in .data!
by kernel test robot
FYI, we noticed the following commit:
git://git.cmpxchg.org/linux-mmotm.git master
commit 71f7f5b9c708bf871b1679c2c0c70152a43fb40a ("linux-next")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -m 512M
caused below changes:
+-------------------------------------------------------+------------+------------+
| | fb6b799817 | 71f7f5b9c7 |
+-------------------------------------------------------+------------+------------+
| boot_successes | 20 | 0 |
| boot_failures | 0 | 20 |
| BUG:key#not_in.data | 0 | 20 |
| WARNING:at_kernel/locking/lockdep.c:#lockdep_init_map | 0 | 20 |
| calltrace:vhci_hcd_init | 0 | 20 |
| BUG:unable_to_handle_kernel | 0 | 6 |
| Oops | 0 | 6 |
| RIP:nbd_queue_rq | 0 | 6 |
| calltrace:SyS_mount | 0 | 6 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 6 |
+-------------------------------------------------------+------------+------------+
[ 10.848805] usbip_core: USB/IP Core v1.0.0
[ 10.850328] vhci_hcd vhci_hcd: USB/IP Virtual Host Controller
[ 10.852237] vhci_hcd vhci_hcd: new USB bus registered, assigned bus number 1
[ 10.853627] BUG: key ffff880018294a98 not in .data!
[ 10.854615] ------------[ cut here ]------------
[ 10.855591] WARNING: CPU: 0 PID: 1 at kernel/locking/lockdep.c:3131 lockdep_init_map+0x11c/0x1c6
[ 10.857718] DEBUG_LOCKS_WARN_ON(1)
[ 10.858186] Modules linked in:
[ 10.859360] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.8.0-rc5-mm1-00197-g71f7f5b9 #2
[ 10.861172] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 10.863176] ffffc900000d3a18 ffffffff8179e7a4 ffffc900000d3a68 0000000000000000
[ 10.865113] ffffc900000d3a58 ffffffff810e70f4 00000c3b000d3ad0 ffff88001836bb50
[ 10.867049] ffff880018294a98 0000000000000000 0000000000001000 0000000000000004
[ 10.868991] Call Trace:
[ 10.869743] [<ffffffff8179e7a4>] dump_stack+0x82/0xb8
[ 10.870858] [<ffffffff810e70f4>] __warn+0xc2/0xdd
[ 10.871917] [<ffffffff810e715a>] warn_slowpath_fmt+0x4b/0x53
[ 10.873113] [<ffffffff81124d58>] lockdep_init_map+0x11c/0x1c6
[ 10.874408] [<ffffffff812969f4>] __kernfs_create_file+0x7a/0xc9
[ 10.875730] [<ffffffff812971eb>] sysfs_add_file_mode_ns+0xcf/0x176
[ 10.877104] [<ffffffff81297c4f>] internal_create_group+0x18f/0x268
[ 10.878477] [<ffffffff8267d7b4>] ? vhci_urb_dequeue+0x204/0x204
[ 10.879822] [<ffffffff81297d3b>] sysfs_create_group+0x13/0x15
[ 10.881143] [<ffffffff8267d579>] vhci_start+0x187/0x1be
[ 10.882385] [<ffffffff825c6d0d>] usb_add_hcd+0x416/0x787
[ 10.883635] [<ffffffff8267ce3b>] vhci_hcd_probe+0x58/0x7d
[ 10.884893] [<ffffffff81c07788>] platform_drv_probe+0x3a/0x7d
[ 10.886186] [<ffffffff81c05c7c>] driver_probe_device+0x113/0x25c
[ 10.887436] [<ffffffff81c05efe>] __device_attach_driver+0x85/0x8c
[ 10.888682] [<ffffffff81c05e79>] ? driver_allows_async_probing+0x2e/0x2e
[ 10.890005] [<ffffffff81c04112>] bus_for_each_drv+0x7b/0x8a
[ 10.891191] [<ffffffff81c05add>] __device_attach+0x93/0xf2
[ 10.892355] [<ffffffff81c0605d>] device_initial_probe+0x13/0x15
[ 10.893561] [<ffffffff81c05073>] bus_probe_device+0x33/0x9e
[ 10.894713] [<ffffffff81c034df>] device_add+0x409/0x509
[ 10.895812] [<ffffffff8462c7c5>] ? set_debug_rodata+0x12/0x12
[ 10.909986] [<ffffffff81c075e8>] platform_device_add+0x141/0x1a1
[ 10.911182] [<ffffffff8462c7c5>] ? set_debug_rodata+0x12/0x12
[ 10.912346] [<ffffffff81c07dc7>] platform_device_register_full+0xb3/0x109
[ 10.913628] [<ffffffff846bcfcf>] vhci_hcd_init+0xb9/0x118
[ 10.914715] [<ffffffff846bcf16>] ? usbip_core_init+0x17/0x17
[ 10.915828] [<ffffffff810021b0>] do_one_initcall+0x90/0x11f
[ 10.916914] [<ffffffff8462c7c5>] ? set_debug_rodata+0x12/0x12
[ 10.918042] [<ffffffff8462d146>] kernel_init_freeable+0x1c6/0x253
[ 10.919204] [<ffffffff82f31224>] ? rest_init+0x13b/0x13b
[ 10.920259] [<ffffffff82f31232>] kernel_init+0xe/0xf5
[ 10.921294] [<ffffffff82f3d92a>] ret_from_fork+0x2a/0x40
[ 10.922394] ---[ end trace fbfa4e12aa9cdf76 ]---
[ 10.928419] vhci_hcd: created sysfs vhci_hcd
Thanks,
Kernel Test Robot
5 years, 8 months
[lkp] [tty] 761ed4a945: BUG: unable to handle kernel NULL pointer dereference at 000000000000046c
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 761ed4a94582ab291aa24dcbea4e01e8936488c8 ("tty: serial_core: convert uart_close to use tty_port_close")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes:
+-------------------------------------------------+------------+------------+
| | b3b5764618 | 761ed4a945 |
+-------------------------------------------------+------------+------------+
| boot_successes | 14 | 5 |
| boot_failures | 188 | 71 |
| BUG:kernel_reboot-without-warning_in_test_stage | 188 | 34 |
| BUG:unable_to_handle_kernel | 0 | 37 |
| Oops | 0 | 33 |
| RIP:serial8250_tx_chars | 0 | 28 |
| calltrace:SyS_open | 0 | 4 |
| calltrace:SyS_read | 0 | 5 |
| calltrace:SyS_newfstat | 0 | 1 |
| calltrace:ring_buffer_producer_thread | 0 | 1 |
| calltrace:SyS_newstat | 0 | 3 |
| calltrace:SyS_sendmsg | 0 | 1 |
| calltrace:SyS_readlinkat | 0 | 1 |
| calltrace:SyS_readlink | 0 | 1 |
| calltrace:schedule | 0 | 1 |
| calltrace:schedule_timeout | 0 | 1 |
+-------------------------------------------------+------------+------------+
[ 142.283744] systemd-udevd[212]: starting version 215
Starting Copy rules generated while the root was ro...
Starting LSB: MD array assembly...
[ 143.836086] BUG: unable to handle kernel NULL pointer dereference at 000000000000046c
[ 143.845764] IP: [<ffffffff816582d0>] serial8250_tx_chars+0x5a/0x2a2
[ 143.845764] PGD 0
[ 143.845764] Oops: 0000 [#1] PREEMPT SMP
[ 143.845764] Modules linked in:
[ 143.845764] CPU: 0 PID: 189 Comm: systemd-journal Not tainted 4.8.0-rc2-00007-g761ed4a #1
[ 143.884224] task: ffff88011f9162c0 task.stack: ffff88011f930000
Thanks,
Xiaolong
5 years, 8 months
Re: [LKP] [lkp] [sched/core] 3d26b7622f: BUG: unable to handle kernel NULL pointer dereference at 00000001
by Ye Xiaolong
Hi, cheng hao,
On 09/09, chengchao wrote:
>Hi, xiaolong
>
> where can I find the commit 3d26b7622f3bab689696900ffd33c6dd7849d7c2?
This is the commit by 0Day bot for your original email patch sent on Sep 05 where
both CONFIG_PREEMPT_NONE and CONFIG_PREEMPT_VOLUNTARY will turn the
logic on.
+/**
+ * the caller keeps task_on_rq_queued, so it's more suitable for
+ * sched_exec on the case when needs migration
+ */
+void stop_one_cpu_sync(unsigned int cpu, cpu_stop_fn_t fn, void *arg)
+{
+ struct cpu_stop_work work = { .fn = fn, .arg = arg, .done = NULL };
+
+ if (!cpu_stop_queue_work(cpu, &work))
+ return;
+
+#if defined(CONFIG_PREEMPT_NONE) || defined(CONFIG_PREEMPT_VOLUNTARY)
+ /*
+ * CONFIG_PREEMPT doesn't need call schedule here, because
+ * preempt_enable already does the similar thing when call
+ * cpu_stop_queue_work
+ */
+ schedule();
+#endif
+}
+
> config-4.8.0-rc5-00001-g3d26b76:
># CONFIG_PREEMPT_NONE is not set
>CONFIG_PREEMPT_VOLUNTARY=y
># CONFIG_PREEMPT is not set
>
> the patch ("sched/core: simpler function for sched_exec migration") only is on if CONFIG_PREEMPT_NONE=y,
>so it doesn't cause the panic when CONFIG_PREEMPT_VOLUNTARY=y.
>
>
>the latest patch is here:
>https://lkml.org/lkml/2016/9/7/819
Will you send a formal updated patch to LKML? Then 0day will auto capture
and test it.
Thanks,
Xiaolong
>
>int stop_one_cpu(unsigned int cpu, cpu_stop_fn_t fn, void *arg)
>{
> struct cpu_stop_done done;
> struct cpu_stop_work work = { .fn = fn, .arg = arg, .done = &done };
>
> cpu_stop_init_done(&done, 1);
> if (!cpu_stop_queue_work(cpu, &work))
> return -ENOENT;
>
>+#if defined(CONFIG_PREEMPT_NONE)
>+ /*
>+ * let the stopper thread runs as soon as possible,
>+ * and keep current TASK_RUNNING.
>+ */
>+ scheudle();
>+#endif
> wait_for_completion(&done.completion);
> return done.ret;
>}
>
>Thanks,
>Cheng
>
>on 09/09/2016 09:39 AM, kernel test robot wrote:
>>
>> FYI, we noticed the following commit:
>>
>> https://github.com/0day-ci/linux cheng-chao/sched-core-simpler-function-for-sched_exec-migration/20160905-142452
>> commit 3d26b7622f3bab689696900ffd33c6dd7849d7c2 ("sched/core: simpler function for sched_exec migration")
>>
>> in testcase: trinity
>> with following parameters:
>>
>> runtime: 300s
>>
>>
>>
>>
>> on test machine: qemu-system-i386 -enable-kvm -smp 2 -m 320M
>>
>> caused below changes:
>>
>>
>> +------------------------------------------------------------------+----------+------------+
>> | | v4.8-rc5 | 3d26b7622f |
>> +------------------------------------------------------------------+----------+------------+
>> | boot_successes | 2271 | 473 |
>> | boot_failures | 248 | 654 |
>> | genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev_bus) | 248 | 654 |
>> | calltrace:SyS_open | 248 | 654 |
>> | invoked_oom-killer:gfp_mask=0x | 33 | 32 |
>> | Mem-Info | 33 | 32 |
>> | BUG:kernel_reboot-without-warning_in_test_stage | 210 | 8 |
>> | genirq:Flags_mismatch_irq | 1 | |
>> | genirq:Flags_mismatch_irq##(ser | 1 | |
>> | genirq:Flags_mismatch_irq##(serial)vs | 1 | |
>> | genirq:Flags_mismatch_irq##(serial)vs.#(goldfi | 1 | |
>> | genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev_bu | 1 | |
>> | warn_alloc_failed+0x | 1 | |
>> | Out_of_memory:Kill_process | 1 | 4 |
>> | BUG:unable_to_handle_kernel | 0 | 533 |
>> | Oops | 0 | 533 |
>> | calltrace:smpboot_thread_fn | 0 | 593 |
>> | kernel_BUG_at_mm/slub.c | 0 | 531 |
>> | invalid_opcode:#[##]SMP | 0 | 536 |
>> | EIP_is_at_kfree | 0 | 531 |
>> | calltrace:SyS_execve | 0 | 533 |
>> | Kernel_panic-not_syncing:Fatal_exception | 0 | 613 |
>> | WARNING:at_arch/x86/kernel/traps.c:#do_debug | 0 | 86 |
>> | general_protection_fault:#[##]SMP | 0 | 20 |
>> | EIP_is_at.brk.pagetables | 0 | 1 |
>> | EIP_is_at_do_execveat_common | 0 | 1 |
>> | EIP_is_at_copy_strings | 0 | 1 |
>> | bounds:#[##]SMP | 0 | 2 |
>> | PANIC:double_fault | 0 | 2 |
>> | EIP_is_at_elf_format | 0 | 1 |
>> | general_protection_fault:#d34[##]SMP | 0 | 1 |
>> | EIP_is_at__lock_acquire | 0 | 3 |
>> | Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0 | 1 |
>> | WARNING:at_kernel/sched/core.c:#__might_sleep | 0 | 2 |
>> | EIP_is_at_unlink_anon_vmas | 0 | 2 |
>> | BUG:Bad_rss-counter_state_mm:#idx:#val | 0 | 1 |
>> | BUG:non-zero_nr_ptes_on_freeing_mm | 0 | 1 |
>> | EIP_is_at_cpu_stopper_thread | 0 | 2 |
>> | genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev | 0 | 1 |
>> +------------------------------------------------------------------+----------+------------+
>>
>> [ 22.622360] BUG: unable to handle kernel NULL pointer dereference at 00000001
>> [ 22.623553] IP: [<5128c004>] 0x5128c004
>> [ 22.624210] *pde = 00000000
>> [ 22.624698] Oops: 0000 [#1] SMP
>> [ 22.625223] Modules linked in:
>> [ 22.625638] CPU: 1 PID: 15 Comm: migration/1 Not tainted 4.8.0-rc5-00001-g3d26b76 #1
>> [ 22.625638] task: 530910c0 task.stack: 5309a000
>> [ 22.625638] EIP: 0060:[<5128c004>] EFLAGS: 00010246 CPU: 1
>> [ 22.625638] EIP is at 0x5128c004
>> [ 22.625638] EAX: 00000001 EBX: 53790280 ECX: 00000000 EDX: 00000001
>> [ 22.625638] ESI: 5128c000 EDI: 41765d34 EBP: 5309bf04 ESP: 5309bee4
>> [ 22.625638] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
>> [ 22.625638] CR0: 80050033 CR2: 00000001 CR3: 01950000 CR4: 00000690
>> [ 22.625638] Stack:
>> [ 22.625638] 53790280 410c5173 00000001 537902b0 53790284 530910c0 530023c0 41761ca0
>> [ 22.625638] 5309bf1c 410543d3 00000000 5301da60 530023c0 410542e0 5309bfa4 410513e0
>> [ 22.625638] 00000001 00000001 530023c0 00000000 00000000 dead4ead ffffffff ffffffff
>> [ 22.625638] Call Trace:
>> [ 22.625638] [<410c5173>] ? cpu_stopper_thread+0x73/0xf0
>> [ 22.625638] [<410543d3>] smpboot_thread_fn+0xf3/0x1e0
>> [ 22.625638] [<410542e0>] ? sort_range+0x20/0x20
>> [ 22.625638] [<410513e0>] kthread+0xa0/0xc0
>> [ 22.625638] [<41543e46>] ? wait_for_common+0xa6/0x150
>> [ 22.625638] [<415483e2>] ret_from_kernel_thread+0xe/0x24
>> [ 22.625638] [<41051340>] ? kthread_create_on_node+0x160/0x160
>> [ 22.625638] Code: 00 00 00 95 a1 7c 37 73 00 00 00 46 02 00 00 6c f5 bd 3f 7b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 80 e4 1a 53 <02> 00 00 00 00 00 00 00 00 00 00 00 9d 6e ac 57 6c 6b 70 2f 6c
>> [ 22.625638] EIP: [<5128c004>] 0x5128c004 SS:ESP 0068:5309bee4
>> [ 22.625638] CR2: 0000000000000001
>> [ 22.625638] ---[ end trace d07782e5cdd90364 ]---
>> [ 22.623351] ------------[ cut here ]------------
>> [ 22.623351] kernel BUG at mm/slub.c:3851!
>> [ 22.623351] invalid opcode: 0000 [#2] SMP
>> [ 22.623351] Modules linked in:
>> [ 22.623351] CPU: 0 PID: 267 Comm: sh Tainted: G D 4.8.0-rc5-00001-g3d26b76 #1
>> [ 22.623351] task: 531ae480 task.stack: 5128c000
>> [ 22.623351] EIP: 0060:[<411268d3>] EFLAGS: 00010246 CPU: 0
>> [ 22.623351] EIP is at kfree+0x193/0x1a0
>> [ 22.623351] EAX: 00000000 EBX: 539cc1a0 ECX: 00000000 EDX: 00000000
>> [ 22.623351] ESI: 37740000 EDI: 5128df08 EBP: 5128dec4 ESP: 5128deb0
>> [ 22.623351] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
>> [ 22.623351] CR0: 80050033 CR2: 377d358c CR3: 125e6000 CR4: 00000690
>> [ 22.623351] Stack:
>> [ 22.623351] 377d4000 00002000 530aee00 37740000 525e0480 5128df24 41184169 530aee00
>> [ 22.623351] 00000000 00000005 00000000 00000000 00000000 000930a0 00000000 00000000
>> [ 22.623351] ffffffff 377d5608 00000001 377a61bf 5140f300 520ca8c0 5128df08 5128df08
>> [ 22.623351] Call Trace:
>> [ 22.623351] [<41184169>] load_elf_binary+0xb69/0xbc0
>> [ 22.623351] [<4113ddf2>] search_binary_handler+0x62/0x1a0
>> [ 22.623351] [<4113e4f3>] do_execveat_common+0x5c3/0x760
>> [ 22.623351] [<4113e8ff>] SyS_execve+0x1f/0x30
>> [ 22.623351] [<410012a5>] do_int80_syscall_32+0x45/0x110
>> [ 22.623351] [<415484d0>] entry_INT80_32+0x2c/0x2c
>> [ 22.623351] Code: ff 40 18 eb b6 8d 76 00 6a 01 57 89 da 89 f0 89 f9 e8 12 fb ff ff 58 5a eb a1 8d b6 00 00 00 00 8b 43 14 a8 01 0f 85 7c ff ff ff <0f> 0b 8d 74 26 00 8d bc 27 00 00 00 00 55 89 e5 57 56 53 89 d7
>> [ 22.623351] EIP: [<411268d3>] kfree+0x193/0x1a0 SS:ESP 0068:5128deb0
>> [ 22.651614] ---[ end trace d07782e5cdd90365 ]---
>> [ 22.651616] Kernel panic - not syncing: Fatal exception
>> [ 22.654918] Shutting down cpus with NMI
>> [ 22.654918] Kernel Offset: disabled
>>
>>
>>
>> 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
>>
5 years, 8 months
[lkp] [sched/core] 3d26b7622f: BUG: unable to handle kernel NULL pointer dereference at 00000001
by kernel test robot
FYI, we noticed the following commit:
https://github.com/0day-ci/linux cheng-chao/sched-core-simpler-function-for-sched_exec-migration/20160905-142452
commit 3d26b7622f3bab689696900ffd33c6dd7849d7c2 ("sched/core: simpler function for sched_exec migration")
in testcase: trinity
with following parameters:
runtime: 300s
on test machine: qemu-system-i386 -enable-kvm -smp 2 -m 320M
caused below changes:
+------------------------------------------------------------------+----------+------------+
| | v4.8-rc5 | 3d26b7622f |
+------------------------------------------------------------------+----------+------------+
| boot_successes | 2271 | 473 |
| boot_failures | 248 | 654 |
| genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev_bus) | 248 | 654 |
| calltrace:SyS_open | 248 | 654 |
| invoked_oom-killer:gfp_mask=0x | 33 | 32 |
| Mem-Info | 33 | 32 |
| BUG:kernel_reboot-without-warning_in_test_stage | 210 | 8 |
| genirq:Flags_mismatch_irq | 1 | |
| genirq:Flags_mismatch_irq##(ser | 1 | |
| genirq:Flags_mismatch_irq##(serial)vs | 1 | |
| genirq:Flags_mismatch_irq##(serial)vs.#(goldfi | 1 | |
| genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev_bu | 1 | |
| warn_alloc_failed+0x | 1 | |
| Out_of_memory:Kill_process | 1 | 4 |
| BUG:unable_to_handle_kernel | 0 | 533 |
| Oops | 0 | 533 |
| calltrace:smpboot_thread_fn | 0 | 593 |
| kernel_BUG_at_mm/slub.c | 0 | 531 |
| invalid_opcode:#[##]SMP | 0 | 536 |
| EIP_is_at_kfree | 0 | 531 |
| calltrace:SyS_execve | 0 | 533 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 613 |
| WARNING:at_arch/x86/kernel/traps.c:#do_debug | 0 | 86 |
| general_protection_fault:#[##]SMP | 0 | 20 |
| EIP_is_at.brk.pagetables | 0 | 1 |
| EIP_is_at_do_execveat_common | 0 | 1 |
| EIP_is_at_copy_strings | 0 | 1 |
| bounds:#[##]SMP | 0 | 2 |
| PANIC:double_fault | 0 | 2 |
| EIP_is_at_elf_format | 0 | 1 |
| general_protection_fault:#d34[##]SMP | 0 | 1 |
| EIP_is_at__lock_acquire | 0 | 3 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 0 | 1 |
| WARNING:at_kernel/sched/core.c:#__might_sleep | 0 | 2 |
| EIP_is_at_unlink_anon_vmas | 0 | 2 |
| BUG:Bad_rss-counter_state_mm:#idx:#val | 0 | 1 |
| BUG:non-zero_nr_ptes_on_freeing_mm | 0 | 1 |
| EIP_is_at_cpu_stopper_thread | 0 | 2 |
| genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev | 0 | 1 |
+------------------------------------------------------------------+----------+------------+
[ 22.622360] BUG: unable to handle kernel NULL pointer dereference at 00000001
[ 22.623553] IP: [<5128c004>] 0x5128c004
[ 22.624210] *pde = 00000000
[ 22.624698] Oops: 0000 [#1] SMP
[ 22.625223] Modules linked in:
[ 22.625638] CPU: 1 PID: 15 Comm: migration/1 Not tainted 4.8.0-rc5-00001-g3d26b76 #1
[ 22.625638] task: 530910c0 task.stack: 5309a000
[ 22.625638] EIP: 0060:[<5128c004>] EFLAGS: 00010246 CPU: 1
[ 22.625638] EIP is at 0x5128c004
[ 22.625638] EAX: 00000001 EBX: 53790280 ECX: 00000000 EDX: 00000001
[ 22.625638] ESI: 5128c000 EDI: 41765d34 EBP: 5309bf04 ESP: 5309bee4
[ 22.625638] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 22.625638] CR0: 80050033 CR2: 00000001 CR3: 01950000 CR4: 00000690
[ 22.625638] Stack:
[ 22.625638] 53790280 410c5173 00000001 537902b0 53790284 530910c0 530023c0 41761ca0
[ 22.625638] 5309bf1c 410543d3 00000000 5301da60 530023c0 410542e0 5309bfa4 410513e0
[ 22.625638] 00000001 00000001 530023c0 00000000 00000000 dead4ead ffffffff ffffffff
[ 22.625638] Call Trace:
[ 22.625638] [<410c5173>] ? cpu_stopper_thread+0x73/0xf0
[ 22.625638] [<410543d3>] smpboot_thread_fn+0xf3/0x1e0
[ 22.625638] [<410542e0>] ? sort_range+0x20/0x20
[ 22.625638] [<410513e0>] kthread+0xa0/0xc0
[ 22.625638] [<41543e46>] ? wait_for_common+0xa6/0x150
[ 22.625638] [<415483e2>] ret_from_kernel_thread+0xe/0x24
[ 22.625638] [<41051340>] ? kthread_create_on_node+0x160/0x160
[ 22.625638] Code: 00 00 00 95 a1 7c 37 73 00 00 00 46 02 00 00 6c f5 bd 3f 7b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 80 e4 1a 53 <02> 00 00 00 00 00 00 00 00 00 00 00 9d 6e ac 57 6c 6b 70 2f 6c
[ 22.625638] EIP: [<5128c004>] 0x5128c004 SS:ESP 0068:5309bee4
[ 22.625638] CR2: 0000000000000001
[ 22.625638] ---[ end trace d07782e5cdd90364 ]---
[ 22.623351] ------------[ cut here ]------------
[ 22.623351] kernel BUG at mm/slub.c:3851!
[ 22.623351] invalid opcode: 0000 [#2] SMP
[ 22.623351] Modules linked in:
[ 22.623351] CPU: 0 PID: 267 Comm: sh Tainted: G D 4.8.0-rc5-00001-g3d26b76 #1
[ 22.623351] task: 531ae480 task.stack: 5128c000
[ 22.623351] EIP: 0060:[<411268d3>] EFLAGS: 00010246 CPU: 0
[ 22.623351] EIP is at kfree+0x193/0x1a0
[ 22.623351] EAX: 00000000 EBX: 539cc1a0 ECX: 00000000 EDX: 00000000
[ 22.623351] ESI: 37740000 EDI: 5128df08 EBP: 5128dec4 ESP: 5128deb0
[ 22.623351] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 22.623351] CR0: 80050033 CR2: 377d358c CR3: 125e6000 CR4: 00000690
[ 22.623351] Stack:
[ 22.623351] 377d4000 00002000 530aee00 37740000 525e0480 5128df24 41184169 530aee00
[ 22.623351] 00000000 00000005 00000000 00000000 00000000 000930a0 00000000 00000000
[ 22.623351] ffffffff 377d5608 00000001 377a61bf 5140f300 520ca8c0 5128df08 5128df08
[ 22.623351] Call Trace:
[ 22.623351] [<41184169>] load_elf_binary+0xb69/0xbc0
[ 22.623351] [<4113ddf2>] search_binary_handler+0x62/0x1a0
[ 22.623351] [<4113e4f3>] do_execveat_common+0x5c3/0x760
[ 22.623351] [<4113e8ff>] SyS_execve+0x1f/0x30
[ 22.623351] [<410012a5>] do_int80_syscall_32+0x45/0x110
[ 22.623351] [<415484d0>] entry_INT80_32+0x2c/0x2c
[ 22.623351] Code: ff 40 18 eb b6 8d 76 00 6a 01 57 89 da 89 f0 89 f9 e8 12 fb ff ff 58 5a eb a1 8d b6 00 00 00 00 8b 43 14 a8 01 0f 85 7c ff ff ff <0f> 0b 8d 74 26 00 8d bc 27 00 00 00 00 55 89 e5 57 56 53 89 d7
[ 22.623351] EIP: [<411268d3>] kfree+0x193/0x1a0 SS:ESP 0068:5128deb0
[ 22.651614] ---[ end trace d07782e5cdd90365 ]---
[ 22.651616] Kernel panic - not syncing: Fatal exception
[ 22.654918] Shutting down cpus with NMI
[ 22.654918] Kernel Offset: disabled
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
5 years, 8 months
[writeback] 8bc4ad9498: INFO: suspicious RCU usage. ]
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/axboe/linux-block.git wb-buf-throttle
commit 8bc4ad9498f81d6689da42457615db0989e720d3 ("writeback: throttle buffered writeback")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu Westmere -m 512M
caused below changes:
+--------------------------------------+------------+------------+
| | b2e4e4e34d | 8bc4ad9498 |
+--------------------------------------+------------+------------+
| boot_successes | 6 | 0 |
| boot_failures | 0 | 8 |
| INFO:suspicious_RCU_usage | 0 | 8 |
| message:INFO:suspicious_RCU_usage | 0 | 0.0 |
| pattern:INFO:suspicious_RCU_usage | 0 | 0 |
| calltrace:disk_events_workfn | 0 | 8 |
| message:calltrace:disk_events_workfn | 0 | 0.0 |
| pattern:calltrace:disk_events_workfn | 0 | 0 |
+--------------------------------------+------------+------------+
[ 7.323356] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 7.334239]
[ 7.337256] ===============================
[ 7.340532] [ INFO: suspicious RCU usage. ]
[ 7.342419] 4.8.0-rc4-00008-g8bc4ad9 #1 Not tainted
[ 7.347065] -------------------------------
[ 7.350132] include/linux/cgroup.h:435 suspicious rcu_dereference_check() usage!
[ 7.355928]
[ 7.355928] other info that might help us debug this:
[ 7.355928]
[ 7.360548]
[ 7.360548] rcu_scheduler_active = 1, debug_locks = 0
[ 7.366185] 3 locks held by kworker/0:1/19:
[ 7.369225] #0: ("events_freezable_power_efficient"){.+.+..}, at: [<ffffffff810fc9d9>] process_one_work+0x151/0x4ee
[ 7.377225] #1: ((&(&ev->dwork)->work)){+.+...}, at: [<ffffffff810fc9d9>] process_one_work+0x151/0x4ee
[ 7.384175] #2: (&(&q->__queue_lock)->rlock){-.....}, at: [<ffffffff81789b1d>] cfq_set_request+0x5e/0x2a3
[ 7.389264]
[ 7.389264] stack backtrace:
[ 7.391300] CPU: 0 PID: 19 Comm: kworker/0:1 Not tainted 4.8.0-rc4-00008-g8bc4ad9 #1
[ 7.395279] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 7.398012] Workqueue: events_freezable_power_ disk_events_workfn
[ 7.399933] 0000000000000000 ffff88003db1f8e8 ffffffff8178ed3b ffff88003db184c0
[ 7.404348] 0000000000000001 ffff88003db1f918 ffffffff81123472 ffff88003db184c0
[ 7.407158] ffffffff84091180 0000000000000003 ffff880039d4c400 ffff88003db1f938
[ 7.410074] Call Trace:
[ 7.411328] [<ffffffff8178ed3b>] dump_stack+0x82/0xb8
[ 7.413982] [<ffffffff81123472>] lockdep_rcu_suspicious+0xf7/0x100
[ 7.415828] [<ffffffff817873f4>] bio_blkcg+0x89/0x93
[ 7.417336] [<ffffffff817891f0>] check_blkcg_changed+0x58/0x1b8
[ 7.419027] [<ffffffff81125fa7>] ? lock_acquire+0x125/0x1be
[ 7.421418] [<ffffffff81125fa7>] ? lock_acquire+0x125/0x1be
[ 7.424049] [<ffffffff811238d3>] ? lock_acquired+0xfc/0x2f2
[ 7.426893] [<ffffffff81789b1d>] ? cfq_set_request+0x5e/0x2a3
[ 7.428722] [<ffffffff81789b90>] cfq_set_request+0xd1/0x2a3
[ 7.430315] [<ffffffff82efb493>] ? _raw_spin_unlock_irq+0x2c/0x3b
[ 7.432134] [<ffffffff81124a52>] ? trace_hardirqs_on_caller+0x17c/0x198
[ 7.434083] [<ffffffff81124a7b>] ? trace_hardirqs_on+0xd/0xf
[ 7.435703] [<ffffffff82efb498>] ? _raw_spin_unlock_irq+0x31/0x3b
[ 7.437472] [<ffffffff8176dd78>] ? ioc_create_icq+0x15e/0x175
[ 7.439690] [<ffffffff81763fe3>] elv_set_request+0x1f/0x24
[ 7.442157] [<ffffffff8176856d>] get_request+0x38f/0x77f
[ 7.444694] [<ffffffff8111b59c>] ? add_wait_queue_exclusive+0x44/0x44
[ 7.447449] [<ffffffff817689c2>] blk_get_request+0x65/0xa8
[ 7.449868] [<ffffffff81c692df>] ide_cd_queue_pc+0x76/0x19d
[ 7.451986] [<ffffffff8112539e>] ? __lock_acquire+0x679/0xed1
[ 7.453757] [<ffffffff81c695ee>] cdrom_check_status+0x51/0x53
[ 7.455372] [<ffffffff81c6a27e>] ide_cdrom_check_events_real+0x20/0x3f
[ 7.457294] [<ffffffff82579f94>] cdrom_update_events+0x18/0x21
[ 7.458987] [<ffffffff82579faf>] cdrom_check_events+0x12/0x1f
[ 7.460713] [<ffffffff81c68317>] idecd_check_events+0x1c/0x1e
[ 7.462393] [<ffffffff81778d12>] disk_check_events+0x47/0x103
[ 7.464129] [<ffffffff81778dea>] disk_events_workfn+0x1c/0x1e
[ 7.465844] [<ffffffff810fcafa>] process_one_work+0x272/0x4ee
[ 7.467462] [<ffffffff810fd247>] worker_thread+0x1eb/0x2c9
[ 7.469137] [<ffffffff810fd05c>] ? rescuer_thread+0x2b3/0x2b3
[ 7.471338] [<ffffffff81101dd4>] kthread+0xc5/0xcd
[ 7.472978] [<ffffffff82efbf2f>] ret_from_fork+0x1f/0x40
[ 7.474501] [<ffffffff81101d0f>] ? kthread_create_on_node+0x1b0/0x1b0
[ 7.477061] Loading iSCSI transport class v2.0-870.
[ 7.479285] rdac: device handler registered
Thanks,
Kernel Test Robot
5 years, 8 months
[sched/wait] 02ae2b22bd: WARNING: CPU: 0 PID: 958 at kernel/sched/core.c:7610 __might_sleep+0x6e/0x90
by kernel test robot
FYI, we noticed the following commit:
https://github.com/0day-ci/linux Oleg-Nesterov/sched-wait-fix-and-then-kill-abort_exclusive_wait/20160907-145024
commit 02ae2b22bd2a42c4e4054563b139a541ba67a43e ("sched/wait: avoid abort_exclusive_wait() in ___wait_event()")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 320M
caused below changes:
+-----------------------------------------------+------------+------------+
| | 9272868493 | 02ae2b22bd |
+-----------------------------------------------+------------+------------+
| boot_successes | 16 | 1 |
| boot_failures | 0 | 15 |
| WARNING:at_kernel/sched/core.c:#__might_sleep | 0 | 15 |
+-----------------------------------------------+------------+------------+
[ 30.775242] kill 958 cat /proc/kmsg
[ 30.775242] wait for background monitors: 959 962 oom-killer nfs-hang
[ 32.078512] ------------[ cut here ]------------
[ 32.080657] WARNING: CPU: 0 PID: 958 at kernel/sched/core.c:7610 __might_sleep+0x6e/0x90
[ 32.084787] do not call blocking ops when !TASK_RUNNING; state=1 set at [<ffffffff81107e0d>] prepare_to_wait_event+0xad/0x120
[ 32.091287] Modules linked in:
[ 32.094001] CPU: 0 PID: 958 Comm: cat Not tainted 4.8.0-rc5-00035-g02ae2b2 #1
[ 32.094017] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 32.094017] 0000000000000000 ffff880009497be8 ffffffff813c9947 ffffffff810e993e
[ 32.094017] ffff880009497c48 ffff880009497c48 0000000000000000 ffff880009497c38
[ 32.094017] ffffffff810c18e7 ffff880009497c08 00001dba8110ea1d ffff880009497c28
[ 32.094017] Call Trace:
[ 32.094017] [<ffffffff813c9947>] dump_stack+0xc4/0x12d
[ 32.094017] [<ffffffff810e993e>] ? __might_sleep+0x6e/0x90
[ 32.094017] [<ffffffff810c18e7>] __warn+0xe7/0x110
[ 32.094017] [<ffffffff810c19b4>] warn_slowpath_fmt+0x44/0x50
[ 32.094017] [<ffffffff81107e0d>] ? prepare_to_wait_event+0xad/0x120
[ 32.094017] [<ffffffff81107e0d>] ? prepare_to_wait_event+0xad/0x120
[ 32.094017] [<ffffffff810e993e>] __might_sleep+0x6e/0x90
[ 32.094017] [<ffffffff810d3613>] get_signal+0x63/0x3c0
[ 32.094017] [<ffffffff81498de6>] ? tty_write+0x2c6/0x2f0
[ 32.094017] [<ffffffff8126d565>] ? kmsg_read+0x55/0x70
[ 32.094017] [<ffffffff81016d60>] do_signal+0x20/0x100
[ 32.094017] [<ffffffff8135e1c9>] ? security_file_permission+0x99/0xb0
[ 32.094017] [<ffffffff81002344>] ? exit_to_usermode_loop+0x44/0xd0
[ 32.094017] [<ffffffff8110e9b3>] ? trace_hardirqs_on_caller+0x193/0x1f0
[ 32.094017] [<ffffffff81002372>] exit_to_usermode_loop+0x72/0xd0
[ 32.094017] [<ffffffff81002dca>] do_int80_syscall_32+0x1fa/0x230
[ 32.094017] [<ffffffff8177d9bd>] ? entry_INT80_compat+0x3d/0x50
[ 32.094017] [<ffffffff8177d9b8>] entry_INT80_compat+0x38/0x50
[ 32.225846] ---[ end trace 8f84425f8176b010 ]---
[ 32.225846] ---[ end trace 8f84425f8176b010 ]---
Thanks,
Kernel Test Robot
5 years, 8 months