================================ WARNING: inconsistent lock state 4.14.207-syzkaller #0 Not tainted -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. syz-executor.3/8033 [HC0[0]:SC1[1]:HE1:SE0] takes: (slock-AF_BLUETOOTH-BTPROTO_SCO){+.?.}, at: [] spin_lock include/linux/spinlock.h:317 [inline] (slock-AF_BLUETOOTH-BTPROTO_SCO){+.?.}, at: [] sco_sock_timeout+0x29/0x1c0 net/bluetooth/sco.c:82 {SOFTIRQ-ON-W} state was registered at: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:152 spin_lock include/linux/spinlock.h:317 [inline] sco_conn_del+0xbf/0x290 net/bluetooth/sco.c:175 sco_disconn_cfm+0x65/0xa0 net/bluetooth/sco.c:1134 hci_disconn_cfm include/net/bluetooth/hci_core.h:1226 [inline] hci_conn_hash_flush+0x127/0x260 net/bluetooth/hci_conn.c:1393 hci_dev_do_close+0x535/0xca0 net/bluetooth/hci_core.c:1620 hci_unregister_dev+0x17f/0x8c0 net/bluetooth/hci_core.c:3191 vhci_release+0x70/0xe0 drivers/bluetooth/hci_vhci.c:354 __fput+0x25f/0x7a0 fs/file_table.c:210 task_work_run+0x11f/0x190 kernel/task_work.c:113 exit_task_work include/linux/task_work.h:22 [inline] do_exit+0xa44/0x2850 kernel/exit.c:868 do_group_exit+0x100/0x2e0 kernel/exit.c:965 SYSC_exit_group kernel/exit.c:976 [inline] SyS_exit_group+0x19/0x20 kernel/exit.c:974 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb irq event stamp: 8718214 hardirqs last enabled at (8718214): [] __raw_spin_unlock_irq include/linux/spinlock_api_smp.h:168 [inline] hardirqs last enabled at (8718214): [] _raw_spin_unlock_irq+0x24/0x80 kernel/locking/spinlock.c:200 hardirqs last disabled at (8718213): [] __raw_spin_lock_irq include/linux/spinlock_api_smp.h:126 [inline] hardirqs last disabled at (8718213): [] _raw_spin_lock_irq+0x35/0x80 kernel/locking/spinlock.c:168 softirqs last enabled at (8718168): [] tcp_close+0x7e6/0xed0 net/ipv4/tcp.c:2305 softirqs last disabled at (8718209): [] invoke_softirq kernel/softirq.c:368 [inline] softirqs last disabled at (8718209): [] irq_exit+0x193/0x240 kernel/softirq.c:409 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(slock-AF_BLUETOOTH-BTPROTO_SCO); lock(slock-AF_BLUETOOTH-BTPROTO_SCO); *** DEADLOCK *** 1 lock held by syz-executor.3/8033: #0: (((&sk->sk_timer))#2){+.-.}, at: [] lockdep_copy_map include/linux/lockdep.h:174 [inline] #0: (((&sk->sk_timer))#2){+.-.}, at: [] call_timer_fn+0xb8/0x650 kernel/time/timer.c:1270 stack backtrace: CPU: 0 PID: 8033 Comm: syz-executor.3 Not tainted 4.14.207-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x283 lib/dump_stack.c:58 print_usage_bug.cold+0x42e/0x570 kernel/locking/lockdep.c:2589 valid_state kernel/locking/lockdep.c:2602 [inline] mark_lock_irq kernel/locking/lockdep.c:2796 [inline] mark_lock+0xb4d/0x1050 kernel/locking/lockdep.c:3194 mark_irqflags kernel/locking/lockdep.c:3072 [inline] __lock_acquire+0xc81/0x3f20 kernel/locking/lockdep.c:3448 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:152 spin_lock include/linux/spinlock.h:317 [inline] sco_sock_timeout+0x29/0x1c0 net/bluetooth/sco.c:82 call_timer_fn+0x14a/0x650 kernel/time/timer.c:1280 expire_timers+0x232/0x4d0 kernel/time/timer.c:1319 __run_timers kernel/time/timer.c:1637 [inline] run_timer_softirq+0x1d5/0x5a0 kernel/time/timer.c:1650 __do_softirq+0x254/0xa1d kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x193/0x240 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:648 [inline] smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1102 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:793 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline] RIP: 0010:lock_is_held_type+0x17a/0x210 kernel/locking/lockdep.c:4038 RSP: 0018:ffff888064457d18 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10 RAX: 1ffffffff11e1231 RBX: 0000000000000282 RCX: 0000000000000001 RDX: dffffc0000000000 RSI: 00000000ffffffff RDI: 0000000000000282 RBP: ffff88806444a100 R08: ffffffff8b98e0c8 R09: 0000000000000000 R10: 0000000000000000 R11: ffff88806444a100 R12: 0000000000000000 R13: 00000000014000c0 R14: 0000000000000080 R15: 0000000000000000 lock_is_held include/linux/lockdep.h:437 [inline] ___might_sleep+0x1ea/0x2b0 kernel/sched/core.c:6007 slab_pre_alloc_hook mm/slab.h:419 [inline] slab_alloc mm/slab.c:3376 [inline] kmem_cache_alloc_trace+0x290/0x3d0 mm/slab.c:3616 kmalloc include/linux/slab.h:488 [inline] sock_alloc_inode+0x5f/0x250 net/socket.c:254 alloc_inode+0x5d/0x170 fs/inode.c:210 new_inode_pseudo+0x14/0xe0 fs/inode.c:899 sock_alloc+0x3c/0x270 net/socket.c:569 __sock_create+0x8a/0x620 net/socket.c:1239 sock_create net/socket.c:1315 [inline] SYSC_socket net/socket.c:1345 [inline] SyS_socket+0xd1/0x1b0 net/socket.c:1325 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x460a07 RSP: 002b:00007ffe2d166b68 EFLAGS: 00000246 ORIG_RAX: 0000000000000029 RAX: ffffffffffffffda RBX: 000000000074bca0 RCX: 0000000000460a07 RDX: 0000000000000006 RSI: 0000000000000001 RDI: 0000000000000002 RBP: 0000000000000300 R08: 0000000000006000 R09: 0000000000004000 R10: 00007ffe2d166c10 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffe2d167290 R14: 000000000003716d R15: 0000000000000000 net_ratelimit: 27 callbacks suppressed ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table IPv6 header not found ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table print_req_error: I/O error, dev loop2, sector 0 print_req_error: I/O error, dev loop2, sector 0 ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table print_req_error: I/O error, dev loop3, sector 0 kauditd_printk_skb: 17 callbacks suppressed audit: type=1804 audit(1605985776.579:236): pid=17665 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/219/file1/bus" dev="loop2" ino=215 res=1 print_req_error: I/O error, dev loop3, sector 0 audit: type=1804 audit(1605985776.619:237): pid=17667 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir175470889/syzkaller.mYr3bf/287/bus" dev="sda1" ino=16413 res=1 print_req_error: I/O error, dev loop3, sector 0 audit: type=1804 audit(1605985776.629:238): pid=17665 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/219/file1/bus" dev="loop2" ino=215 res=1 autofs4:pid:17716:autofs4_fill_super: called with bogus options autofs4:pid:17719:autofs4_fill_super: called with bogus options print_req_error: I/O error, dev loop3, sector 0 audit: type=1804 audit(1605985776.769:239): pid=17692 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir175470889/syzkaller.mYr3bf/288/bus" dev="sda1" ino=16412 res=1 print_req_error: I/O error, dev loop3, sector 0 audit: type=1804 audit(1605985776.789:240): pid=17703 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/220/file1/bus" dev="loop2" ino=216 res=1 audit: type=1804 audit(1605985776.899:241): pid=17714 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir175470889/syzkaller.mYr3bf/289/bus" dev="sda1" ino=15905 res=1 audit: type=1804 audit(1605985776.989:242): pid=17733 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir175470889/syzkaller.mYr3bf/290/bus" dev="sda1" ino=16415 res=1 audit: type=1804 audit(1605985776.989:243): pid=17728 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/221/file1/bus" dev="sda1" ino=16414 res=1 autofs4:pid:17758:autofs4_fill_super: called with bogus options audit: type=1804 audit(1605985777.439:244): pid=17756 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir175470889/syzkaller.mYr3bf/291/bus" dev="sda1" ino=16418 res=1 print_req_error: I/O error, dev loop2, sector 0 audit: type=1804 audit(1605985777.489:245): pid=17757 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/222/file1/bus" dev="sda1" ino=15988 res=1 autofs4:pid:17778:autofs4_fill_super: called with bogus options autofs4:pid:17781:autofs4_fill_super: called with bogus options print_req_error: I/O error, dev loop3, sector 0 print_req_error: I/O error, dev loop3, sector 0 autofs4:pid:17855:autofs4_fill_super: called with bogus options autofs4:pid:17862:autofs4_fill_super: called with bogus options FAT-fs (loop2): Unrecognized mount option "00000000000000000004" or missing value FAT-fs (loop2): Unrecognized mount option "00000000000000000004" or missing value IPVS: ftp: loaded support on port[0] = 21 autofs4:pid:17975:autofs4_fill_super: called with bogus options FAT-fs (loop2): Unrecognized mount option "0x0000000000000000" or missing value FAT-fs (loop2): Unrecognized mount option "0x0000000000000000" or missing value autofs4:pid:17975:autofs4_fill_super: called with bogus options autofs4:pid:18068:autofs4_fill_super: called with bogus options autofs4:pid:18073:autofs4_fill_super: called with bogus options autofs4:pid:18084:autofs4_fill_super: called with bogus options autofs4:pid:18130:autofs4_fill_super: called with bogus options autofs4:pid:18140:autofs4_fill_super: called with bogus options net_ratelimit: 33 callbacks suppressed ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table FAT-fs (loop2): Directory bread(block 1035) failed FAT-fs (loop2): Directory bread(block 1036) failed autofs4:pid:18159:autofs4_fill_super: called with bogus options autofs4:pid:18161:autofs4_fill_super: called with bogus options FAT-fs (loop2): Directory bread(block 1037) failed FAT-fs (loop2): Directory bread(block 1038) failed FAT-fs (loop2): Directory bread(block 1039) failed FAT-fs (loop2): Directory bread(block 1040) failed ip6_tables: ip6tables: counters copy to user failed while replacing table FAT-fs (loop2): Directory bread(block 1041) failed autofs4:pid:18172:autofs4_fill_super: called with bogus options FAT-fs (loop2): Directory bread(block 1042) failed autofs4:pid:18175:autofs4_fill_super: called with bogus options FAT-fs (loop2): Directory bread(block 1043) failed FAT-fs (loop2): Directory bread(block 1044) failed kauditd_printk_skb: 23 callbacks suppressed audit: type=1804 audit(1605985781.590:269): pid=18195 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/234/file1/bus" dev="loop2" ino=223 res=1 autofs4:pid:18192:autofs4_fill_super: called with bogus options ip6_tables: ip6tables: counters copy to user failed while replacing table autofs4:pid:18212:autofs4_fill_super: called with bogus options audit: type=1804 audit(1605985781.600:270): pid=18203 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/234/file1/bus" dev="loop2" ino=223 res=1 ip6_tables: ip6tables: counters copy to user failed while replacing table ip6_tables: ip6tables: counters copy to user failed while replacing table FAT-fs (loop2): Unrecognized mount option "" or missing value print_req_error: 6 callbacks suppressed print_req_error: I/O error, dev loop2, sector 0 audit: type=1804 audit(1605985781.860:271): pid=18219 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/235/file1/bus" dev="sda1" ino=16454 res=1 ip6_tables: ip6tables: counters copy to user failed while replacing table print_req_error: I/O error, dev loop2, sector 0 FAT-fs (loop2): Unrecognized mount option "" or missing value autofs4:pid:18238:autofs4_fill_super: called with bogus options ip6_tables: ip6tables: counters copy to user failed while replacing table autofs4:pid:18240:autofs4_fill_super: called with bogus options audit: type=1804 audit(1605985781.970:272): pid=18219 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/235/file1/file1/bus" dev="sda1" ino=16456 res=1 ip6_tables: ip6tables: counters copy to user failed while replacing table audit: type=1804 audit(1605985782.030:273): pid=18249 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/236/file1/bus" dev="sda1" ino=16463 res=1 ip6_tables: ip6tables: counters copy to user failed while replacing table print_req_error: I/O error, dev loop2, sector 0 audit: type=1804 audit(1605985782.150:274): pid=18249 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/236/file1/file1/bus" dev="sda1" ino=16465 res=1 FAT-fs (loop2): bogus number of reserved sectors autofs4:pid:18271:autofs4_fill_super: called with bogus options FAT-fs (loop2): Can't find a valid FAT filesystem audit: type=1804 audit(1605985782.470:275): pid=18268 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/237/file1/bus" dev="sda1" ino=16459 res=1 FAT-fs (loop2): bogus number of reserved sectors FAT-fs (loop2): Can't find a valid FAT filesystem autofs4:pid:18286:autofs4_fill_super: called with bogus options audit: type=1804 audit(1605985782.540:276): pid=18268 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/237/file1/file1/bus" dev="sda1" ino=16472 res=1 autofs4:pid:18300:autofs4_fill_super: called with bogus options FAT-fs (loop2): Unrecognized mount option "]&/s3:b6~" or missing value audit: type=1804 audit(1605985782.690:277): pid=18304 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/238/file1/bus" dev="sda1" ino=16465 res=1 FAT-fs (loop2): Unrecognized mount option "]&/s3:b6~" or missing value audit: type=1804 audit(1605985782.760:278): pid=18318 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir189380645/syzkaller.X7rh7e/238/file1/file1/bus" dev="sda1" ino=16460 res=1 autofs4:pid:18336:autofs4_fill_super: called with bogus options FAT-fs (loop2): invalid media value (0x00) FAT-fs (loop2): Can't find a valid FAT filesystem autofs4:pid:18370:autofs4_fill_super: called with bogus options FAT-fs (loop2): invalid media value (0x00) FAT-fs (loop2): Can't find a valid FAT filesystem autofs4:pid:18389:autofs4_fill_super: called with bogus options autofs4:pid:18395:autofs4_fill_super: called with bogus options autofs4:pid:18418:autofs4_fill_super: called with bogus options autofs4:pid:18420:autofs4_fill_super: called with bogus options print_req_error: I/O error, dev loop2, sector 0 autofs4:pid:18515:autofs4_fill_super: called with bogus options