INFO: task hung in dev_ioctl (2)

[syzbot]

Hello,

syzbot found the following crash on:

HEAD commit: 714ada7c ANDROID: removed CONFIG_PM_WAKELOCKS
git tree: android-4.14
console output: https://syzkaller.appspot.com/x/log.txt?x=10fee57ae00000
kernel config: https://syzkaller.appspot.com/x/.config?x=f93318c01623545f
dashboard link: https://syzkaller.appspot.com/bug?extid=888b74dce30cac170e16
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+888b74...@syzkaller.appspotmail.com

ip6_tunnel: � xmit: Local address not yet configured!
INFO: task syz-executor.4:25926 blocked for more than 140 seconds.
Not tainted 4.14.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4 D29024 25926 31632 0x00000004
Call Trace:
schedule+0x92/0x1c0 kernel/sched/core.c:3499
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3557
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x595/0x13e0 kernel/locking/mutex.c:893
dev_ioctl+0x531/0xdf0 net/core/dev_ioctl.c:421
sock_do_ioctl+0x92/0xb0 net/socket.c:981
sock_ioctl+0x253/0x440 net/socket.c:1071
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0xabe/0x1040 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007f0e7fbb0c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000000400200 RSI: 0000000000008912 RDI: 0000000000000005
RBP: 000000000075bf20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f0e7fbb16d4
R13: 00000000004c439a R14: 00000000004d8e38 R15: 00000000ffffffff
INFO: task syz-executor.4:25948 blocked for more than 140 seconds.
Not tainted 4.14.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4 D29792 25948 31632 0x00000004
Call Trace:
schedule+0x92/0x1c0 kernel/sched/core.c:3499
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3557
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x595/0x13e0 kernel/locking/mutex.c:893
dev_ioctl+0x531/0xdf0 net/core/dev_ioctl.c:421
sock_do_ioctl+0x92/0xb0 net/socket.c:981
sock_ioctl+0x253/0x440 net/socket.c:1071
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0xabe/0x1040 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007f0e7fb6ec78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000000400200 RSI: 0000000000008912 RDI: 0000000000000005
RBP: 000000000075c070 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f0e7fb6f6d4
R13: 00000000004c439a R14: 00000000004d8e38 R15: 00000000ffffffff
INFO: task syz-executor.3:25925 blocked for more than 140 seconds.
Not tainted 4.14.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D29952 25925 16401 0x00000004
Call Trace:
schedule+0x92/0x1c0 kernel/sched/core.c:3499
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3557
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x595/0x13e0 kernel/locking/mutex.c:893
dev_ioctl+0x531/0xdf0 net/core/dev_ioctl.c:421
sock_do_ioctl+0x92/0xb0 net/socket.c:981
sock_ioctl+0x253/0x440 net/socket.c:1071
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0xabe/0x1040 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007f4bfa53ec78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000000400200 RSI: 0000000000008912 RDI: 0000000000000004
RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f4bfa53f6d4
R13: 00000000004c439a R14: 00000000004d8e38 R15: 00000000ffffffff
INFO: task syz-executor.5:25923 blocked for more than 140 seconds.
Not tainted 4.14.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5 D27584 25923 24293 0x00000004
Call Trace:
schedule+0x92/0x1c0 kernel/sched/core.c:3499
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3557
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x595/0x13e0 kernel/locking/mutex.c:893
dev_ioctl+0x531/0xdf0 net/core/dev_ioctl.c:421
sock_do_ioctl+0x92/0xb0 net/socket.c:981
sock_ioctl+0x253/0x440 net/socket.c:1071
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0xabe/0x1040 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007fd39d566c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000020000080 RSI: 0000001000008912 RDI: 0000000000000004
RBP: 000000000075bf20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fd39d5676d4
R13: 00000000004c1dab R14: 00000000004d6150 R15: 00000000ffffffff
INFO: task syz-executor.0:25930 blocked for more than 140 seconds.
Not tainted 4.14.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0 D29216 25930 15364 0x00000004
Call Trace:
schedule+0x92/0x1c0 kernel/sched/core.c:3499
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3557
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x595/0x13e0 kernel/locking/mutex.c:893
dev_ioctl+0x531/0xdf0 net/core/dev_ioctl.c:421
sock_do_ioctl+0x92/0xb0 net/socket.c:981
sock_ioctl+0x253/0x440 net/socket.c:1071
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0xabe/0x1040 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007fd9872d0c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000000400200 RSI: 0000000000008912 RDI: 0000000000000004
RBP: 000000000075bf20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fd9872d16d4
R13: 00000000004c439a R14: 00000000004d8e38 R15: 00000000ffffffff
INFO: task syz-executor.0:25936 blocked for more than 140 seconds.
Not tainted 4.14.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0 D29552 25936 15364 0x00000004
Call Trace:
schedule+0x92/0x1c0 kernel/sched/core.c:3499
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3557
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x595/0x13e0 kernel/locking/mutex.c:893
dev_ioctl+0x531/0xdf0 net/core/dev_ioctl.c:421
sock_do_ioctl+0x92/0xb0 net/socket.c:981
sock_ioctl+0x253/0x440 net/socket.c:1071
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:500 [inline]
do_vfs_ioctl+0xabe/0x1040 fs/ioctl.c:684
SYSC_ioctl fs/ioctl.c:701 [inline]
SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007fd9872afc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000000400200 RSI: 0000000000008912 RDI: 0000000000000004
RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fd9872b06d4
R13: 00000000004c439a R14: 00000000004d8e38 R15: 00000000ffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/23:
#0: (tasklist_lock){.+.+}, at: [<00000000472d85ad>]
debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4544
2 locks held by getty/1759:
#0: (&tty->ldisc_sem){++++}, at: [<000000006180e710>]
tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
#1: (&ldata->atomic_read_lock){+.+.}, at: [<000000004dbfaa0e>]
n_tty_read+0x1f7/0x1700 drivers/tty/n_tty.c:2156
3 locks held by kworker/0:5/24935:
#0: ("%s"("ipv6_addrconf")){+.+.}, at: [<0000000093086561>]
process_one_work+0x6ff/0x1580 kernel/workqueue.c:2105
#1: ((addr_chk_work).work){+.+.}, at: [<000000006791464b>]
process_one_work+0x735/0x1580 kernel/workqueue.c:2109
#2: (rtnl_mutex){+.+.}, at: [<00000000c2c1d2b9>]
addrconf_verify_work+0xa/0x20 net/ipv6/addrconf.c:4431
2 locks held by syz-executor.3/9785:
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
inode_lock include/linux/fs.h:724 [inline]
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
__sock_release+0x86/0x2c0 net/socket.c:601
#1: (rtnl_mutex){+.+.}, at: [<00000000799f466b>] raw_close+0xe/0x30
net/ipv4/raw.c:699
2 locks held by syz-executor.5/19069:
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
inode_lock include/linux/fs.h:724 [inline]
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
__sock_release+0x86/0x2c0 net/socket.c:601
#1: (rtnl_mutex){+.+.}, at: [<00000000799f466b>] raw_close+0xe/0x30
net/ipv4/raw.c:699
2 locks held by syz-executor.3/20120:
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
inode_lock include/linux/fs.h:724 [inline]
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
__sock_release+0x86/0x2c0 net/socket.c:601
#1: (rtnl_mutex){+.+.}, at: [<00000000799f466b>] raw_close+0xe/0x30
net/ipv4/raw.c:699
2 locks held by syz-executor.0/23783:
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
inode_lock include/linux/fs.h:724 [inline]
#0: (&sb->s_type->i_mutex_key#8){+.+.}, at: [<00000000dd1e9cff>]
__sock_release+0x86/0x2c0 net/socket.c:601
#1: (rtnl_mutex){+.+.}, at: [<00000000799f466b>] raw_close+0xe/0x30
net/ipv4/raw.c:699
1 lock held by syz-executor.4/25926:
#0: (rtnl_mutex){+.+.}, at: [<00000000d15769df>] dev_ioctl+0x531/0xdf0
net/core/dev_ioctl.c:421
1 lock held by syz-executor.4/25948:
#0: (rtnl_mutex){+.+.}, at: [<00000000d15769df>] dev_ioctl+0x531/0xdf0
net/core/dev_ioctl.c:421
1 lock held by syz-executor.3/25925:
#0: (rtnl_mutex){+.+.}, at: [<00000000d15769df>] dev_ioctl+0x531/0xdf0
net/core/dev_ioctl.c:421
1 lock held by syz-executor.5/25923:
#0: (rtnl_mutex){+.+.}, at: [<00000000d15769df>] dev_ioctl+0x531/0xdf0
net/core/dev_ioctl.c:421
1 lock held by syz-executor.0/25930:
#0: (rtnl_mutex){+.+.}, at: [<00000000d15769df>] dev_ioctl+0x531/0xdf0
net/core/dev_ioctl.c:421
1 lock held by syz-executor.0/25936:
#0: (rtnl_mutex){+.+.}, at: [<00000000d15769df>] dev_ioctl+0x531/0xdf0
net/core/dev_ioctl.c:421

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

NMI backtrace for cpu 1
CPU: 1 PID: 23 Comm: khungtaskd Not tainted 4.14.156-syzkaller #0
Call Trace:
__dump_stack lib/dump_stack.c:17 [inline]
dump_stack+0xe5/0x154 lib/dump_stack.c:58
nmi_cpu_backtrace.cold+0x47/0x86 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x119/0x147 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline]
watchdog+0x629/0xbe0 kernel/hung_task.c:274
kthread+0x31f/0x430 kernel/kthread.c:232
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:404
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 25920 Comm: syz-executor.2 Not tainted 4.14.156-syzkaller #0
task: 00000000882a13c3 task.stack: 00000000e4d14b83
RIP: 0010:fib6_walk_continue+0x229/0x5f0 net/ipv6/ip6_fib.c:1680
RSP: 0018:ffff88816c20ea48 EFLAGS: 00000246
RAX: 1ffff11030526891 RBX: ffff88816c20eae8 RCX: 0000000000040000
RDX: ffffffff8274701f RSI: ffffc9000494b000 RDI: ffff888182934488
RBP: ffff888182934480 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: dffffc0000000000
R13: 0000000000000000 R14: ffffed102d841d62 R15: ffff88816c20eb14
FS: 00007fe8df95c700(0000) GS:ffff8881dba00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000ef4000 CR3: 00000001cfab8003 CR4: 00000000001606b0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
fib6_walk+0x8d/0xe0 net/ipv6/ip6_fib.c:1748
fib6_clean_tree+0xd4/0x110 net/ipv6/ip6_fib.c:1822
__fib6_clean_all+0xf5/0x230 net/ipv6/ip6_fib.c:1838
rt6_ifdown+0x90/0x5b0 net/ipv6/route.c:2868
addrconf_ifdown+0xc5/0x13b0 net/ipv6/addrconf.c:3602
addrconf_notify+0x159/0x1b80 net/ipv6/addrconf.c:3526
notifier_call_chain+0x10c/0x1a0 kernel/notifier.c:93
call_netdevice_notifiers net/core/dev.c:1687 [inline]
__dev_notify_flags+0x175/0x240 net/core/dev.c:6836
dev_change_flags+0xe6/0x130 net/core/dev.c:6867
do_setlink+0x928/0x2b50 net/core/rtnetlink.c:2071
rtnl_group_changelink net/core/rtnetlink.c:2482 [inline]
rtnl_newlink+0xbef/0x16b0 net/core/rtnetlink.c:2636
rtnetlink_rcv_msg+0x3d3/0xb40 net/core/rtnetlink.c:4285
netlink_rcv_skb+0x137/0x3a0 net/netlink/af_netlink.c:2432
netlink_unicast_kernel net/netlink/af_netlink.c:1286 [inline]
netlink_unicast+0x478/0x650 net/netlink/af_netlink.c:1312
netlink_sendmsg+0x66a/0xbe0 net/netlink/af_netlink.c:1877
sock_sendmsg_nosec net/socket.c:646 [inline]
sock_sendmsg+0xb7/0x100 net/socket.c:656
___sys_sendmsg+0x752/0x890 net/socket.c:2062
__sys_sendmsg+0xb6/0x150 net/socket.c:2096
SYSC_sendmsg net/socket.c:2107 [inline]
SyS_sendmsg+0x27/0x40 net/socket.c:2103
do_syscall_64+0x19b/0x520 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45a679
RSP: 002b:00007fe8df95bc78 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a679
RDX: 0000000000000000 RSI: 0000000020000140 RDI: 0000000000000008
RBP: 000000000075bf20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fe8df95c6d4
R13: 00000000004c8e09 R14: 00000000004dfcd8 R15: 00000000ffffffff
Code: 41 83 fd 01 74 61 0f 87 0d ff ff ff e8 81 b7 bc fe 48 8d 7d 08 48 89
f8 48 c1 e8 03 42 80 3c 20 00 0f 85 30 03 00 00 4c 8b 6d 08 <4d> 85 ed 74
2b e8 5d b7 bc fe 48 8b 04 24 48 c1 e8 03 42 80 3c


---
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]

Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.