Bluetooth: hci5 command 0x0406 tx timeout Bluetooth: hci7 command 0x0409 tx timeout Bluetooth: hci7 command 0x041b tx timeout Bluetooth: hci7 command 0x040f tx timeout Bluetooth: hci7 command 0x0419 tx timeout BUG: workqueue lockup - pool INFO: task kworker/u4:0:5 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:0 D28776 5 2 0x80000000 cpus=0 node=0 flags=0x0 nice=0 Workqueue: events_unbound fsnotify_connector_destroy_workfn stuck for 162s! Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 0: cpus=0 Call Trace: node=0 context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 flags=0x0 nice=0 active=26/256 refcnt=27 in-flight: 8632:rtc_timer_do_work pending: rht_deferred_worker, defense_work_handler, macvlan_process_broadcast, defense_work_handler, vmstat_shepherd , key_garbage_collector, cache_reap, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, nfc_urelease_event_work , nfc_urelease_event_work schedule+0x8d/0x1b0 kernel/sched/core.c:3430 , nfc_urelease_event_work schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 , macvlan_process_broadcast, macvlan_process_broadcast, nfc_urelease_event_work, wait_rcu_exp_gp, free_obj_work, defense_work_handler, release_one_tty , release_one_tty , rfkill_any_led_trigger_worker workqueue events_unbound: flags=0x2 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 9306:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 5:fsnotify_connector_destroy_workfn fsnotify_connector_destroy_workfn workqueue events_power_efficient: flags=0x80 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=3/256 refcnt=4 pending: fb_flashcursor, process_srcu, do_cache_clean do_wait_for_common kernel/sched/completion.c:91 [inline] __wait_for_common kernel/sched/completion.c:112 [inline] wait_for_common+0x272/0x430 kernel/sched/completion.c:123 workqueue mm_percpu_wq: flags=0x8 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: vmstat_update workqueue ipv6_addrconf: flags=0x40008 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/1 refcnt=13 pending: addrconf_dad_work delayed: addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 , addrconf_dad_work , addrconf_dad_work , addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work pool 0: cpus=0 node=0 fsnotify_connector_destroy_workfn+0x49/0xa0 fs/notify/mark.c:156 flags=0x0 nice=0 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 hung=162s workers=5 idle: 9118 3 3436 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 24 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=7 idle: 8817 179 3555 69 22 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 INFO: task kworker/u4:6:9306 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:6 D29136 9306 2 0x80000000 Workqueue: events_unbound fsnotify_mark_destroy_workfn Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 do_wait_for_common kernel/sched/completion.c:91 [inline] __wait_for_common kernel/sched/completion.c:112 [inline] wait_for_common+0x272/0x430 kernel/sched/completion.c:123 __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 fsnotify_mark_destroy_workfn+0xed/0x2e0 fs/notify/mark.c:757 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 INFO: task syz-executor.5:9634 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D28424 9634 8011 0x80000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 _synchronize_rcu_expedited+0x522/0x770 kernel/rcu/tree_exp.h:615 synchronize_rcu+0x98/0x130 kernel/rcu/tree_plugin.h:762 synchronize_net+0x3a/0x40 net/core/dev.c:8250 nf_register_net_hook+0x6a5/0xa40 net/netfilter/core.c:295 nf_register_net_hooks+0x47/0xa0 net/netfilter/core.c:383 ebt_register_table+0x912/0xbb0 net/bridge/netfilter/ebtables.c:1227 ops_init+0xaa/0x3e0 net/core/net_namespace.c:118 setup_net+0x22f/0x530 net/core/net_namespace.c:298 copy_net_ns+0x19b/0x440 net/core/net_namespace.c:422 create_new_namespaces+0x375/0x720 kernel/nsproxy.c:107 copy_namespaces+0x27b/0x310 kernel/nsproxy.c:165 copy_process.part.0+0x25f8/0x71c0 kernel/fork.c:1794 copy_process kernel/fork.c:1606 [inline] _do_fork+0x184/0xc80 kernel/fork.c:2092 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f55900d30f9 RSP: 002b:00007f558e645118 EFLAGS: 00000206 ORIG_RAX: 0000000000000038 RAX: ffffffffffffffda RBX: 00007f55901f2f80 RCX: 00007f55900d30f9 RDX: 0000000020009c40 RSI: 0000000020009c30 RDI: 0000000060000000 RBP: 00007f559012eae9 R08: 0000000020009cc0 R09: 0000000020009cc0 R10: 0000000020009c80 R11: 0000000000000206 R12: 0000000000000000 R13: 00007ffd54dd8aef R14: 00007f558e645300 R15: 0000000000022000 Showing all locks held in the system: 2 locks held by kworker/u4:0/5: #0: ("events_unbound"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: (connector_reaper_work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 1 lock held by khungtaskd/1533: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 2 locks held by kworker/u4:6/9306: #0: ("events_unbound"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((reaper_work).work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 2 locks held by syz-executor.5/9634: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:272 [inline] #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x2c2/0x770 kernel/rcu/tree_exp.h:596 1 lock held by syz-executor.5/9711: #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:305 [inline] #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 1 lock held by syz-executor.5/9723: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1533 Comm: khungtaskd Not tainted 4.14.305-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x180 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+0x5b9/0xb40 kernel/hung_task.c:274 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 8632 Comm: kworker/0:3 Not tainted 4.14.305-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Workqueue: events rtc_timer_do_work task: ffff8880b0758300 task.stack: ffff888094068000 RIP: 0010:hash_64_generic include/linux/hash.h:81 [inline] RIP: 0010:lookup_chain_cache kernel/locking/lockdep.c:2354 [inline] RIP: 0010:lookup_chain_cache_add kernel/locking/lockdep.c:2381 [inline] RIP: 0010:validate_chain kernel/locking/lockdep.c:2435 [inline] RIP: 0010:__lock_acquire+0xa32/0x3f20 kernel/locking/lockdep.c:3491 RSP: 0018:ffff88809406f970 EFLAGS: 00000006 RAX: 0000000000040000 RBX: 72a3cfb04f8413be RCX: e378f8063e794ee7 RDX: 1ffff110160eb184 RSI: 000000009447ad1e RDI: ffff8880b0758c21 RBP: 0000000000000000 R08: 0000000000000001 R09: 00000000000c0674 R10: ffff8880b0758c00 R11: ffff8880b0758300 R12: ffff8880b0758c18 R13: 0000000000000000 R14: e378f8063e794ee7 R15: ffffffff8bed1e40 FS: 0000000000000000(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f185eb4c010 CR3: 00000000abafa000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x8c/0xc0 kernel/locking/spinlock.c:160 rtc_handle_legacy_irq+0x82/0x180 drivers/rtc/interface.c:518 rtc_timer_do_work+0x220/0x590 drivers/rtc/interface.c:881 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 Code: 08 84 d2 0f 85 3f 2d 00 00 44 8b 2d 09 e2 b9 0a 45 85 ed 0f 85 5e f7 ff ff e9 69 6b c1 05 66 41 f7 42 20 ff 1f 0f 84 01 07 00 00 <48> b9 eb 83 b5 80 46 86 c8 61 48 b8 00 00 00 00 00 fc ff df 49 ---------------- Code disassembly (best guess): 0: 08 84 d2 0f 85 3f 2d or %al,0x2d3f850f(%rdx,%rdx,8) 7: 00 00 add %al,(%rax) 9: 44 8b 2d 09 e2 b9 0a mov 0xab9e209(%rip),%r13d # 0xab9e219 10: 45 85 ed test %r13d,%r13d 13: 0f 85 5e f7 ff ff jne 0xfffff777 19: e9 69 6b c1 05 jmpq 0x5c16b87 1e: 66 41 f7 42 20 ff 1f testw $0x1fff,0x20(%r10) 25: 0f 84 01 07 00 00 je 0x72c * 2b: 48 b9 eb 83 b5 80 46 movabs $0x61c8864680b583eb,%rcx <-- trapping instruction 32: 86 c8 61 35: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 3c: fc ff df 3f: 49 rex.WB