Bluetooth: hci7 command 0x0419 tx timeout Bluetooth: hci8 command 0x0409 tx timeout Bluetooth: hci8 command 0x041b tx timeout Bluetooth: hci8 command 0x040f tx timeout Bluetooth: hci8 command 0x0419 tx timeout BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 245s! INFO: task kworker/u4:1:22 blocked for more than 140 seconds. Showing busy workqueues and worker pools: workqueue events: flags=0x0 Not tainted 4.14.307-syzkaller #0 pwq 0: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:1 D26880 22 2 0x80000000 Workqueue: events_unbound fsnotify_connector_destroy_workfn Call Trace: cpus=0 node=0 flags=0x0 nice=0 active=20/256 refcnt=21 in-flight: 17632:rtc_timer_do_work pending: nfc_urelease_event_work, vmstat_shepherd, cache_reap, defense_work_handler context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 , defense_work_handler , wait_rcu_exp_gp , macvlan_process_broadcast schedule+0x8d/0x1b0 kernel/sched/core.c:3430 , macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, free_obj_work schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 , hci_cmd_timeout, l2cap_info_timeout , macvlan_process_broadcast , macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast , macvlan_process_broadcast workqueue events_long: flags=0x0 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 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=4/256 refcnt=5 pending: gc_worker, br_fdb_cleanup, br_fdb_cleanup , br_fdb_cleanup workqueue events_unbound: flags=0x2 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 29033:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 22: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=5/256 refcnt=6 __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 pending: process_srcu, fb_flashcursor, neigh_periodic_work, neigh_periodic_work , do_cache_clean pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=246s workers=3 idle: 17699 3 fsnotify_connector_destroy_workfn+0x49/0xa0 fs/notify/mark.c:156 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=5 idle: 8478 17032 34 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 kworker/u4:0:29033 blocked for more than 140 seconds. Not tainted 4.14.307-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:0 D27112 29033 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.1:15309 blocked for more than 140 seconds. Not tainted 4.14.307-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D28912 15309 8011 0x00000004 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 packet_release+0x740/0xa80 net/packet/af_packet.c:3116 __sock_release+0xcd/0x2b0 net/socket.c:602 sock_close+0x15/0x20 net/socket.c:1139 __fput+0x25f/0x7a0 fs/file_table.c:210 task_work_run+0x11f/0x190 kernel/task_work.c:113 tracehook_notify_resume include/linux/tracehook.h:191 [inline] exit_to_usermode_loop+0x1ad/0x200 arch/x86/entry/common.c:164 prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline] syscall_return_slowpath arch/x86/entry/common.c:270 [inline] do_syscall_64+0x4a3/0x640 arch/x86/entry/common.c:297 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f18d6e37fab RSP: 002b:00007fff06452190 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000005 RCX: 00007f18d6e37fab RDX: 0000000000000000 RSI: 0000001b2ed20a28 RDI: 0000000000000004 RBP: 00007f18d6fa7980 R08: 0000000000000000 R09: 00000000a2d9c70c R10: 0000000000000000 R11: 0000000000000293 R12: 00000000000dc647 R13: 00007fff06452290 R14: 00007f18d6fa6050 R15: 0000000000000032 INFO: task syz-executor.1:15428 blocked for more than 140 seconds. Not tainted 4.14.307-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D27528 15428 1 0x00000004 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_preempt_disabled+0xf/0x20 kernel/sched/core.c:3488 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 exp_funnel_lock kernel/rcu/tree_exp.h:305 [inline] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 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 unshare_nsproxy_namespaces+0xa1/0x1d0 kernel/nsproxy.c:206 SYSC_unshare kernel/fork.c:2413 [inline] SyS_unshare+0x308/0x7f0 kernel/fork.c:2363 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7fb374f086b7 RSP: 002b:00007ffe188608a8 EFLAGS: 00000206 ORIG_RAX: 0000000000000110 RAX: ffffffffffffffda RBX: 00007ffe18860ee8 RCX: 00007fb374f086b7 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000040000000 RBP: 00000000ffffffff R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000006 R13: 00007ffe18860980 R14: 00007fb3750274f8 R15: 0000000000000006 Showing all locks held in the system: 2 locks held by kworker/u4:1/22: #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:0/29033: #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.1/15309: #0: (&sb->s_type->i_mutex_key#13){+.+.}, at: [] inode_lock include/linux/fs.h:719 [inline] #0: (&sb->s_type->i_mutex_key#13){+.+.}, at: [] __sock_release+0x86/0x2b0 net/socket.c:601 #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 2 locks held by syz-executor.1/15428: #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:305 [inline] #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 1 lock held by syz-executor.1/15454: #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.307-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/16/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: 4623 Comm: systemd-journal Not tainted 4.14.307-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/16/2023 task: ffff8880a1134100 task.stack: ffff8880a1138000 RIP: 0010:__save_stack_trace+0x87/0x160 arch/x86/kernel/stacktrace.c:44 RSP: 0018:ffff8880a113f868 EFLAGS: 00000087 RAX: 0000000000000000 RBX: ffffffff817f1393 RCX: 0000000000000001 RDX: ffff8880a113f910 RSI: ffff8880a113f6d0 RDI: ffffffff817f1393 RBP: ffff8880a113f8e8 R08: 0000000000000001 R09: 0000000000000001 R10: ffff8880a113f8f8 R11: 0000000000000001 R12: ffff8880a113f8f8 R13: 0000000000000000 R14: 00000000fffffffe R15: ffff8880a113fe88 FS: 00007f3e53da98c0(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f3e51259000 CR3: 00000000a1509000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: save_stack mm/kasan/kasan.c:447 [inline] set_track mm/kasan/kasan.c:459 [inline] kasan_slab_free+0xc3/0x1a0 mm/kasan/kasan.c:524 __cache_free mm/slab.c:3496 [inline] kfree+0xc9/0x250 mm/slab.c:3815 aa_free_file_ctx security/apparmor/include/file.h:76 [inline] apparmor_file_free_security+0x7e/0xb0 security/apparmor/lsm.c:441 security_file_free+0x42/0x80 security/security.c:879 put_filp+0x23/0x90 fs/file_table.c:312 path_openat+0x6ce/0x2970 fs/namei.c:3583 do_filp_open+0x179/0x3c0 fs/namei.c:3605 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f3e53338840 RSP: 002b:00007ffda7fa1ee8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffda7fa21f0 RCX: 00007f3e53338840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 000055fde0f2e5c0 RBP: 000000000000000d R08: 0000000000000000 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 000055fde0f20040 R14: 00007ffda7fa21b0 R15: 000055fde0f2dd50 Code: 7d 90 e8 6d d8 05 00 8b 55 90 85 d2 75 2d eb 62 41 8b 04 24 41 3b 44 24 04 73 76 49 8b 54 24 08 8d 48 01 41 89 0c 24 48 89 1c c2 <48> 8d 7d 90 e8 70 c0 05 00 8b 45 90 85 c0 74 37 48 8d 7d 90 e8 ---------------- Code disassembly (best guess): 0: 7d 90 jge 0xffffff92 2: e8 6d d8 05 00 callq 0x5d874 7: 8b 55 90 mov -0x70(%rbp),%edx a: 85 d2 test %edx,%edx c: 75 2d jne 0x3b e: eb 62 jmp 0x72 10: 41 8b 04 24 mov (%r12),%eax 14: 41 3b 44 24 04 cmp 0x4(%r12),%eax 19: 73 76 jae 0x91 1b: 49 8b 54 24 08 mov 0x8(%r12),%rdx 20: 8d 48 01 lea 0x1(%rax),%ecx 23: 41 89 0c 24 mov %ecx,(%r12) 27: 48 89 1c c2 mov %rbx,(%rdx,%rax,8) * 2b: 48 8d 7d 90 lea -0x70(%rbp),%rdi <-- trapping instruction 2f: e8 70 c0 05 00 callq 0x5c0a4 34: 8b 45 90 mov -0x70(%rbp),%eax 37: 85 c0 test %eax,%eax 39: 74 37 je 0x72 3b: 48 8d 7d 90 lea -0x70(%rbp),%rdi 3f: e8 .byte 0xe8