audit: type=1804 audit(1613510830.616:97): pid=16619 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/57/bus" dev="sda1" ino=15873 res=1 ================================ WARNING: inconsistent lock state 4.14.218-syzkaller #0 Not tainted -------------------------------- inconsistent {IN-SOFTIRQ-W} -> {SOFTIRQ-ON-W} usage. syz-executor.4/15941 [HC0[0]:SC0[0]:HE1:SE1] takes: (slock-AF_BLUETOOTH-BTPROTO_SCO){+.?.}, at: [] spin_lock syzkaller/managers/linux-4-14/kernel/./include/linux/spinlock.h:317 [inline] (slock-AF_BLUETOOTH-BTPROTO_SCO){+.?.}, at: [] sco_conn_del+0xbf/0x290 syzkaller/managers/linux-4-14/kernel/net/bluetooth/sco.c:175 {IN-SOFTIRQ-W} state was registered at: lock_acquire+0x170/0x3f0 syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:3998 __raw_spin_lock syzkaller/managers/linux-4-14/kernel/./include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 syzkaller/managers/linux-4-14/kernel/kernel/locking/spinlock.c:152 spin_lock syzkaller/managers/linux-4-14/kernel/./include/linux/spinlock.h:317 [inline] sco_sock_timeout+0x29/0x1c0 syzkaller/managers/linux-4-14/kernel/net/bluetooth/sco.c:82 call_timer_fn+0x14a/0x650 syzkaller/managers/linux-4-14/kernel/kernel/time/timer.c:1280 expire_timers+0x232/0x4d0 syzkaller/managers/linux-4-14/kernel/kernel/time/timer.c:1319 __run_timers syzkaller/managers/linux-4-14/kernel/kernel/time/timer.c:1637 [inline] run_timer_softirq+0x1d5/0x5a0 syzkaller/managers/linux-4-14/kernel/kernel/time/timer.c:1650 __do_softirq+0x24d/0x9ff syzkaller/managers/linux-4-14/kernel/kernel/softirq.c:288 invoke_softirq syzkaller/managers/linux-4-14/kernel/kernel/softirq.c:368 [inline] irq_exit+0x193/0x240 syzkaller/managers/linux-4-14/kernel/kernel/softirq.c:409 exiting_irq syzkaller/managers/linux-4-14/kernel/./arch/x86/include/asm/apic.h:648 [inline] smp_apic_timer_interrupt+0x141/0x5e0 syzkaller/managers/linux-4-14/kernel/arch/x86/kernel/apic/apic.c:1102 apic_timer_interrupt+0x93/0xa0 syzkaller/managers/linux-4-14/kernel/arch/x86/entry/entry_64.S:793 arch_local_save_flags syzkaller/managers/linux-4-14/kernel/./arch/x86/include/asm/paravirt.h:774 [inline] arch_local_irq_save syzkaller/managers/linux-4-14/kernel/./arch/x86/include/asm/paravirt.h:796 [inline] seqcount_lockdep_reader_access syzkaller/managers/linux-4-14/kernel/./include/linux/seqlock.h:80 [inline] read_seqcount_begin syzkaller/managers/linux-4-14/kernel/./include/linux/seqlock.h:164 [inline] get_counters+0x176/0x5c0 syzkaller/managers/linux-4-14/kernel/net/ipv4/netfilter/ip_tables.c:778 irq event stamp: 4489 hardirqs last enabled at (4489): [] kfree+0x14a/0x250 syzkaller/managers/linux-4-14/kernel/mm/slab.c:3816 hardirqs last disabled at (4488): [] kfree+0x6f/0x250 syzkaller/managers/linux-4-14/kernel/mm/slab.c:3809 softirqs last enabled at (3208): [] __do_softirq+0x68b/0x9ff syzkaller/managers/linux-4-14/kernel/kernel/softirq.c:314 softirqs last disabled at (2511): [] invoke_softirq syzkaller/managers/linux-4-14/kernel/kernel/softirq.c:368 [inline] softirqs last disabled at (2511): [] irq_exit+0x193/0x240 syzkaller/managers/linux-4-14/kernel/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 *** 3 locks held by syz-executor.4/15941: #0: (&hdev->req_lock){+.+.}, at: [] hci_dev_do_close+0x109/0xca0 syzkaller/managers/linux-4-14/kernel/net/bluetooth/hci_core.c:1576 #1: (&hdev->lock){+.+.}, at: [] hci_dev_do_close+0x21c/0xca0 syzkaller/managers/linux-4-14/kernel/net/bluetooth/hci_core.c:1607 #2: (hci_cb_list_lock){+.+.}, at: [] hci_disconn_cfm syzkaller/managers/linux-4-14/kernel/./include/net/bluetooth/hci_core.h:1223 [inline] #2: (hci_cb_list_lock){+.+.}, at: [] hci_conn_hash_flush+0xda/0x260 syzkaller/managers/linux-4-14/kernel/net/bluetooth/hci_conn.c:1393 stack backtrace: CPU: 0 PID: 15941 Comm: syz-executor.4 Not tainted 4.14.218-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack syzkaller/managers/linux-4-14/kernel/lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 syzkaller/managers/linux-4-14/kernel/lib/dump_stack.c:58 print_usage_bug.cold+0x42e/0x570 syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:2589 valid_state syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:2602 [inline] mark_lock_irq syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:2796 [inline] mark_lock+0xb4d/0x1050 syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:3194 mark_irqflags syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:3090 [inline] __lock_acquire+0xd5c/0x3f20 syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:3448 lock_acquire+0x170/0x3f0 syzkaller/managers/linux-4-14/kernel/kernel/locking/lockdep.c:3998 __raw_spin_lock syzkaller/managers/linux-4-14/kernel/./include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 syzkaller/managers/linux-4-14/kernel/kernel/locking/spinlock.c:152 spin_lock syzkaller/managers/linux-4-14/kernel/./include/linux/spinlock.h:317 [inline] sco_conn_del+0xbf/0x290 syzkaller/managers/linux-4-14/kernel/net/bluetooth/sco.c:175 sco_disconn_cfm+0x65/0xa0 syzkaller/managers/linux-4-14/kernel/net/bluetooth/sco.c:1134 hci_disconn_cfm syzkaller/managers/linux-4-14/kernel/./include/net/bluetooth/hci_core.h:1226 [inline] hci_conn_hash_flush+0x127/0x260 syzkaller/managers/linux-4-14/kernel/net/bluetooth/hci_conn.c:1393 hci_dev_do_close+0x535/0xca0 syzkaller/managers/linux-4-14/kernel/net/bluetooth/hci_core.c:1620 hci_unregister_dev+0x17f/0x8c0 syzkaller/managers/linux-4-14/kernel/net/bluetooth/hci_core.c:3191 vhci_release+0x70/0xe0 syzkaller/managers/linux-4-14/kernel/drivers/bluetooth/hci_vhci.c:354 __fput+0x25f/0x7a0 syzkaller/managers/linux-4-14/kernel/fs/file_table.c:210 task_work_run+0x11f/0x190 syzkaller/managers/linux-4-14/kernel/kernel/task_work.c:113 exit_task_work syzkaller/managers/linux-4-14/kernel/./include/linux/task_work.h:22 [inline] do_exit+0xa44/0x2850 syzkaller/managers/linux-4-14/kernel/kernel/exit.c:868 do_group_exit+0x100/0x2e0 syzkaller/managers/linux-4-14/kernel/kernel/exit.c:965 get_signal+0x38d/0x1ca0 syzkaller/managers/linux-4-14/kernel/kernel/signal.c:2423 do_signal+0x7c/0x1550 syzkaller/managers/linux-4-14/kernel/arch/x86/kernel/signal.c:814 exit_to_usermode_loop+0x160/0x200 syzkaller/managers/linux-4-14/kernel/arch/x86/entry/common.c:160 prepare_exit_to_usermode syzkaller/managers/linux-4-14/kernel/arch/x86/entry/common.c:199 [inline] syscall_return_slowpath syzkaller/managers/linux-4-14/kernel/arch/x86/entry/common.c:270 [inline] do_syscall_64+0x4a3/0x640 syzkaller/managers/linux-4-14/kernel/arch/x86/entry/common.c:297 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x465d99 RSP: 002b:00007fe25ec57218 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca RAX: fffffffffffffe00 RBX: 000000000056bf68 RCX: 0000000000465d99 RDX: 0000000000000000 RSI: 0000000000000080 RDI: 000000000056bf68 RBP: 000000000056bf60 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf6c R13: 00007ffedf14da8f R14: 00007fe25ec57300 R15: 0000000000022000 audit: type=1804 audit(1613510831.546:98): pid=16667 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/58/bus" dev="sda1" ino=16406 res=1 IPVS: ftp: loaded support on port[0] = 21 audit: type=1804 audit(1613510832.536:99): pid=16791 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/59/bus" dev="sda1" ino=16406 res=1 audit: type=1804 audit(1613510832.716:100): pid=16801 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir657038107/syzkaller.QHsehG/217/bus" dev="sda1" ino=15841 res=1 audit: type=1804 audit(1613510833.576:101): pid=16842 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/60/bus" dev="sda1" ino=15970 res=1 batman_adv: batadv0: Interface deactivated: batadv_slave_0 batman_adv: batadv0: Removing interface: batadv_slave_0 batman_adv: batadv0: Interface deactivated: batadv_slave_1 batman_adv: batadv0: Removing interface: batadv_slave_1 device bridge_slave_1 left promiscuous mode bridge0: port 2(bridge_slave_1) entered disabled state device bridge_slave_0 left promiscuous mode bridge0: port 1(bridge_slave_0) entered disabled state device veth1_macvtap left promiscuous mode device veth0_macvtap left promiscuous mode device veth1_vlan left promiscuous mode device veth0_vlan left promiscuous mode device hsr_slave_1 left promiscuous mode device hsr_slave_0 left promiscuous mode team0 (unregistering): Port device team_slave_1 removed team0 (unregistering): Port device team_slave_0 removed bond0 (unregistering): Releasing backup interface bond_slave_1 bond0 (unregistering): Releasing backup interface bond_slave_0 bond0 (unregistering): Released all slaves IPVS: ftp: loaded support on port[0] = 21 chnl_net:caif_netlink_parms(): no params data found bridge0: port 1(bridge_slave_0) entered blocking state bridge0: port 1(bridge_slave_0) entered disabled state device bridge_slave_0 entered promiscuous mode bridge0: port 2(bridge_slave_1) entered blocking state bridge0: port 2(bridge_slave_1) entered disabled state device bridge_slave_1 entered promiscuous mode bond0: Enslaving bond_slave_0 as an active interface with an up link bond0: Enslaving bond_slave_1 as an active interface with an up link IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready team0: Port device team_slave_0 added IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready team0: Port device team_slave_1 added batman_adv: batadv0: Adding interface: batadv_slave_0 batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active batman_adv: batadv0: Adding interface: batadv_slave_1 batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active IPv6: ADDRCONF(NETDEV_UP): bridge_slave_0: link is not ready IPv6: ADDRCONF(NETDEV_UP): bridge_slave_1: link is not ready device hsr_slave_0 entered promiscuous mode device hsr_slave_1 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): hsr_slave_0: link is not ready IPv6: ADDRCONF(NETDEV_UP): hsr_slave_1: link is not ready bridge0: port 2(bridge_slave_1) entered blocking state bridge0: port 2(bridge_slave_1) entered forwarding state bridge0: port 1(bridge_slave_0) entered blocking state bridge0: port 1(bridge_slave_0) entered forwarding state IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready 8021q: adding VLAN 0 to HW filter on device bond0 IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready bridge0: port 1(bridge_slave_0) entered disabled state bridge0: port 2(bridge_slave_1) entered disabled state IPv6: ADDRCONF(NETDEV_UP): team0: link is not ready 8021q: adding VLAN 0 to HW filter on device team0 IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready bridge0: port 1(bridge_slave_0) entered blocking state bridge0: port 1(bridge_slave_0) entered forwarding state IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready bridge0: port 2(bridge_slave_1) entered blocking state bridge0: port 2(bridge_slave_1) entered forwarding state IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network IPv6: ADDRCONF(NETDEV_UP): hsr0: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready IPv6: ADDRCONF(NETDEV_UP): vxcan0: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready 8021q: adding VLAN 0 to HW filter on device batadv0 IPv6: ADDRCONF(NETDEV_UP): veth0_virt_wifi: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready IPv6: ADDRCONF(NETDEV_UP): veth0_vlan: link is not ready IPv6: ADDRCONF(NETDEV_UP): vlan0: link is not ready IPv6: ADDRCONF(NETDEV_UP): vlan1: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready device veth0_vlan entered promiscuous mode device veth1_vlan entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): macvlan0: link is not ready IPv6: ADDRCONF(NETDEV_UP): macvlan1: link is not ready IPv6: ADDRCONF(NETDEV_UP): veth0_macvtap: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready device veth0_macvtap entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): macvtap0: link is not ready device veth1_macvtap entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): veth0_to_batadv: link is not ready IPv6: ADDRCONF(NETDEV_UP): veth1_to_batadv: link is not ready batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: batadv0: Interface activated: batadv_slave_0 IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! batman_adv: batadv0: Interface activated: batadv_slave_1 IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready audit: type=1804 audit(1613510838.176:102): pid=17142 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir657038107/syzkaller.QHsehG/218/bus" dev="sda1" ino=16436 res=1 audit: type=1804 audit(1613510838.276:103): pid=17150 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/61/bus" dev="sda1" ino=16431 res=1 Bluetooth: hci2 command 0x0409 tx timeout IPVS: ftp: loaded support on port[0] = 21 UDF-fs: bad mount option "" or missing value IPVS: ftp: loaded support on port[0] = 21 audit: type=1804 audit(1613510839.206:104): pid=17248 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir657038107/syzkaller.QHsehG/219/bus" dev="sda1" ino=16447 res=1 audit: type=1804 audit(1613510839.256:105): pid=17251 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/62/bus" dev="sda1" ino=15847 res=1 IPv6: NLM_F_CREATE should be specified when creating new route netlink: 152 bytes leftover after parsing attributes in process `syz-executor.4'. audit: type=1804 audit(1613510840.286:106): pid=17312 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir989000197/syzkaller.s2EooD/63/bus" dev="sda1" ino=16448 res=1 audit: type=1804 audit(1613510840.346:107): pid=17314 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir657038107/syzkaller.QHsehG/220/bus" dev="sda1" ino=16449 res=1