INFO: task hung in register_netdevice_notifier

19 views
Skip to first unread message

syzbot

unread,
Apr 27, 2019, 12:10:06 AM4/27/19
to syzkaller...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: c98875d9 Linux 4.19.36
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=1681f2cca00000
kernel config: https://syzkaller.appspot.com/x/.config?x=5e40ac5fbcc6366d
dashboard link: https://syzkaller.appspot.com/bug?extid=fcb6acc1c3d7ada0a944
compiler: gcc (GCC) 9.0.0 20181231 (experimental)

Unfortunately, I don't have any reproducer for this crash yet.

IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+fcb6ac...@syzkaller.appspotmail.com

INFO: task syz-executor.2:29331 blocked for more than 140 seconds.
Not tainted 4.19.36 #4
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D28728 29331 7912 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2826 [inline]
__schedule+0x813/0x1d00 kernel/sched/core.c:3474
schedule+0x92/0x1c0 kernel/sched/core.c:3518
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:573 [inline]
rwsem_down_write_failed+0x774/0xcc0 kernel/locking/rwsem-xadd.c:602
call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117
__down_write arch/x86/include/asm/rwsem.h:142 [inline]
down_write+0x53/0x90 kernel/locking/rwsem.c:72
register_netdevice_notifier+0x7e/0x630 net/core/dev.c:1628
raw_init+0x299/0x340 net/can/raw.c:343
can_create+0x28a/0x4b0 net/can/af_can.c:183
__sock_create+0x3e6/0x750 net/socket.c:1276
sock_create net/socket.c:1316 [inline]
__sys_socket+0x103/0x220 net/socket.c:1346
__do_sys_socket net/socket.c:1355 [inline]
__se_sys_socket net/socket.c:1353 [inline]
__x64_sys_socket+0x73/0xb0 net/socket.c:1353
do_syscall_64+0x103/0x610 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x458da9
Code: Bad RIP value.
RSP: 002b:00007f94da7cdc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000458da9
RDX: 0000000000000001 RSI: 0000000000000003 RDI: 000000000000001d
RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f94da7ce6d4
R13: 00000000004c73ad R14: 00000000004dd088 R15: 00000000ffffffff
INFO: task syz-executor.2:29820 blocked for more than 140 seconds.
Not tainted 4.19.36 #4
kobject: 'batman_adv' (000000005a128af6): kobject_uevent_env
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D
kobject: 'batman_adv' (000000005a128af6): kobject_uevent_env: filter
function caused the event to drop!
28728 29820 7912 0x00000004
kobject: 'batman_adv' (000000005a128af6): kobject_cleanup, parent
(null)
Call Trace:
context_switch kernel/sched/core.c:2826 [inline]
__schedule+0x813/0x1d00 kernel/sched/core.c:3474
schedule+0x92/0x1c0 kernel/sched/core.c:3518
kobject: 'batman_adv' (000000005a128af6): calling ktype release
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:573 [inline]
rwsem_down_write_failed+0x774/0xcc0 kernel/locking/rwsem-xadd.c:602
kobject: (000000005a128af6): dynamic_kobj_release
kobject: 'batman_adv': free name
kobject: 'rx-0' (00000000b1a29f42): kobject_cleanup, parent 00000000f2ab14a4
kobject: 'rx-0' (00000000b1a29f42): auto cleanup 'remove' event
call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117
__down_write arch/x86/include/asm/rwsem.h:142 [inline]
down_write+0x53/0x90 kernel/locking/rwsem.c:72
register_netdevice_notifier+0x7e/0x630 net/core/dev.c:1628
raw_init+0x299/0x340 net/can/raw.c:343
can_create+0x28a/0x4b0 net/can/af_can.c:183
__sock_create+0x3e6/0x750 net/socket.c:1276
kobject: 'rx-0' (00000000b1a29f42): kobject_uevent_env
sock_create net/socket.c:1316 [inline]
__sys_socket+0x103/0x220 net/socket.c:1346
kobject: 'rx-0' (00000000b1a29f42): kobject_uevent_env: uevent_suppress
caused the event to drop!
kobject: 'rx-0' (00000000b1a29f42): auto cleanup kobject_del
kobject: 'rx-0' (00000000b1a29f42): calling ktype release
__do_sys_socket net/socket.c:1355 [inline]
__se_sys_socket net/socket.c:1353 [inline]
__x64_sys_socket+0x73/0xb0 net/socket.c:1353
do_syscall_64+0x103/0x610 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x458da9
Code: Bad RIP value.
kobject: 'rx-0': free name
RSP: 002b:00007f94da78bc78 EFLAGS: 00000246
kobject: 'tx-3' (000000003d258747): kobject_cleanup, parent 00000000f2ab14a4
ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000458da9
RDX: 0000000000000001 RSI: 0000000000000003 RDI: 000000000000001d
RBP: 000000000073c040 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f94da78c6d4
R13: 00000000004c73ad R14: 00000000004dd088 R15: 00000000ffffffff

Showing all locks held in the system:
kobject: 'tx-3' (000000003d258747): auto cleanup 'remove' event
7 locks held by kworker/u4:2/81:
kobject: 'tx-3' (000000003d258747): kobject_uevent_env
1 lock held by khungtaskd/1032:
kobject: 'tx-3' (000000003d258747): kobject_uevent_env: uevent_suppress
caused the event to drop!
#0:
kobject: 'tx-3' (000000003d258747): auto cleanup kobject_del
00000000e5280321 (
kobject: 'tx-3' (000000003d258747): calling ktype release
rcu_read_lock
kobject: 'tx-3': free name
){....}, at: debug_show_all_locks+0x5f/0x27e kernel/locking/lockdep.c:4438
2 locks held by getty/7869:
kobject: 'tx-2' (000000009743bf7e): kobject_cleanup, parent 00000000f2ab14a4
#0:
kobject: 'tx-2' (000000009743bf7e): auto cleanup 'remove' event
0000000053d794ab
kobject: 'tx-2' (000000009743bf7e): kobject_uevent_env
(
kobject: 'tx-2' (000000009743bf7e): kobject_uevent_env: uevent_suppress
caused the event to drop!
&tty->ldisc_sem
kobject: 'tx-2' (000000009743bf7e): auto cleanup kobject_del
){++++}
kobject: 'tx-2' (000000009743bf7e): calling ktype release
, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
kobject: 'tx-2': free name
#1:
kobject: 'tx-1' (000000007d9476ac): kobject_cleanup, parent 00000000f2ab14a4
000000009dd45a27
kobject: 'tx-1' (000000007d9476ac): auto cleanup 'remove' event
(&ldata->atomic_read_lock
kobject: 'tx-1' (000000007d9476ac): kobject_uevent_env
){+.+.}
kobject: 'tx-1' (000000007d9476ac): kobject_uevent_env: uevent_suppress
caused the event to drop!
, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
kobject: 'tx-1' (000000007d9476ac): auto cleanup kobject_del
2 locks held by getty/7870:
kobject: 'tx-1' (000000007d9476ac): calling ktype release
#0:
kobject: 'tx-1': free name
00000000580b4c2d
kobject: 'tx-0' (0000000021e80d36): kobject_cleanup, parent 00000000f2ab14a4
(&tty->ldisc_sem
kobject: 'tx-0' (0000000021e80d36): auto cleanup 'remove' event
){++++}
kobject: 'tx-0' (0000000021e80d36): kobject_uevent_env
, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
kobject: 'tx-0' (0000000021e80d36): kobject_uevent_env: uevent_suppress
caused the event to drop!
#1:
kobject: 'tx-0' (0000000021e80d36): auto cleanup kobject_del
00000000c80b646e
kobject: 'tx-0' (0000000021e80d36): calling ktype release
(
kobject: 'tx-0': free name
&ldata->atomic_read_lock
kobject: 'queues' (00000000f2ab14a4): kobject_cleanup, parent
(null)
){+.+.}
kobject: 'queues' (00000000f2ab14a4): calling ktype release
, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
kobject: 'queues' (00000000f2ab14a4): kset_release
2 locks held by getty/7871:
kobject: 'queues': free name
#0:
kobject: 'wlan2524' (00000000f21a57ab): kobject_uevent_env
0000000086bc9114
kobject: 'wlan2524' (00000000f21a57ab): kobject_uevent_env: uevent_suppress
caused the event to drop!
(
kobject: 'net' (0000000010323782): kobject_cleanup, parent (null)
&tty->ldisc_sem
kobject: 'net' (0000000010323782): calling ktype release
){++++}
kobject: 'net': free name
, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
#1: 00000000010e0d36 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7872:
#0: 00000000e0c51cce (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
#1: 00000000ebd69b76 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7873:
#0: 00000000959aa51a (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
#1: 00000000cf9109cd (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7874:
#0: 000000004963985c (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
#1: 00000000635eae50 (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7875:
#0: 00000000c4966a07 (&tty->ldisc_sem){++++}, at:
ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:363
#1: 000000002c3cf19c (&ldata->atomic_read_lock){+.+.}, at:
n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by kworker/1:5/5370:
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at:
__write_once_size include/linux/compiler.h:220 [inline]
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at:
arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at: atomic64_set
include/asm-generic/atomic-instrumented.h:40 [inline]
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at: atomic_long_set
include/asm-generic/atomic-long.h:59 [inline]
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at: set_work_data
kernel/workqueue.c:617 [inline]
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at:
set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
#0: 00000000cfd6265a ((wq_completion)"rcu_gp"){+.+.}, at:
process_one_work+0x87e/0x1760 kernel/workqueue.c:2124
#1: 000000008a530401 ((work_completion)(&rew.rew_work)){+.+.}, at:
process_one_work+0x8b4/0x1760 kernel/workqueue.c:2128
1 lock held by syz-executor.2/29331:
#0: 000000003e100e4f (pernet_ops_rwsem){++++}, at:
register_netdevice_notifier+0x7e/0x630 net/core/dev.c:1628
1 lock held by syz-executor.2/29820:
#0: 000000003e100e4f (pernet_ops_rwsem){++++}, at:
register_netdevice_notifier+0x7e/0x630 net/core/dev.c:1628

=============================================

NMI backtrace for cpu 0
CPU: 0 PID: 1032 Comm: khungtaskd Not tainted 4.19.36 #4
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x172/0x1f0 lib/dump_stack.c:113
nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1b0/0x1f8 lib/nmi_backtrace.c:62
arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
watchdog+0x9df/0xee0 kernel/hung_task.c:287
kthread+0x357/0x430 kernel/kthread.c:246
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:413
Sending NMI from CPU 0 to CPUs 1:
kobject: 'wlan2524' (00000000f21a57ab): kobject_cleanup, parent
(null)
NMI backtrace for cpu 1
CPU: 1 PID: 81 Comm: kworker/u4:2 Not tainted 4.19.36 #4
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
Workqueue: netns cleanup_net
RIP: 0010:io_serial_in+0x6b/0x90 drivers/tty/serial/8250/8250_port.c:450
Code: e1 00 00 00 49 8d 7c 24 38 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48
c1 ea 03 d3 e3 80 3c 02 00 75 17 41 03 5c 24 38 89 da ec <5b> 0f b6 c0 41
5c 5d c3 e8 f8 f0 34 fe eb c2 e8 51 f1 34 fe eb e2
RSP: 0018:ffff8880a99e74c0 EFLAGS: 00000002
RAX: dffffc0000000060 RBX: 00000000000003fd RCX: 0000000000000000
RDX: 00000000000003fd RSI: ffffffff836cb4c1 RDI: ffffffff8ad6ce78
RBP: ffff8880a99e74d0 R08: ffff8880a99ce600 R09: fffffbfff15ad9c9
R10: fffffbfff15ad9c8 R11: ffffffff8ad6ce43 R12: ffffffff8ad6ce40
R13: 0000000000000020 R14: ffffffff8ad6ce40 R15: fffffbfff15ad9d1
FS: 0000000000000000(0000) GS:ffff8880ae900000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000c423a038c8 CR3: 00000000a4f4c000 CR4: 00000000001406e0
Call Trace:
serial_in drivers/tty/serial/8250/8250.h:111 [inline]
wait_for_xmitr+0xa7/0x240 drivers/tty/serial/8250/8250_port.c:2028
serial8250_console_putchar+0x20/0x60
drivers/tty/serial/8250/8250_port.c:3199
uart_console_write+0x57/0x120 drivers/tty/serial/serial_core.c:1878
serial8250_console_write+0x651/0xa10
drivers/tty/serial/8250/8250_port.c:3265
univ8250_console_write+0x5f/0x70 drivers/tty/serial/8250/8250_core.c:590
call_console_drivers kernel/printk/printk.c:1729 [inline]
console_unlock+0xbe4/0x10c0 kernel/printk/printk.c:2410
vprintk_emit+0x238/0x690 kernel/printk/printk.c:1927
vprintk_default+0x28/0x30 kernel/printk/printk.c:1968
vprintk_func+0x7e/0x189 kernel/printk/printk_safe.c:398
printk+0xba/0xed kernel/printk/printk.c:2001
kobject_cleanup lib/kobject.c:638 [inline]
kobject_release lib/kobject.c:691 [inline]
kref_put include/linux/kref.h:70 [inline]
kobject_put.cold+0x88/0x2ec lib/kobject.c:708
netdev_run_todo+0x5cc/0x7d0 net/core/dev.c:8788
rtnl_unlock+0xe/0x10 net/core/rtnetlink.c:117
ieee80211_unregister_hw+0x51/0x280 net/mac80211/main.c:1236
mac80211_hwsim_del_radio+0x280/0x3b0
drivers/net/wireless/mac80211_hwsim.c:2995
hwsim_exit_net+0x553/0xc50 drivers/net/wireless/mac80211_hwsim.c:3664
ops_exit_list.isra.0+0xb0/0x160 net/core/net_namespace.c:153
cleanup_net+0x3fb/0x960 net/core/net_namespace.c:552
process_one_work+0x98e/0x1760 kernel/workqueue.c:2153
worker_thread+0x98/0xe40 kernel/workqueue.c:2296
kthread+0x357/0x430 kernel/kthread.c:246
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:413


---
This bug is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this bug report. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

syzbot

unread,
Sep 21, 2021, 3:27:23 AM9/21/21
to syzkaller...@googlegroups.com
Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.
Reply all
Reply to author
Forward
0 new messages