[ OK ] Reached target Login Prompts. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Debian GNU/Linux 9 syzkaller ttyS0 Warning: Permanently added '10.128.0.18' (ECDSA) to the list of known hosts. 2020/12/28 09:21:39 parsed 1 programs 2020/12/28 09:21:40 executed programs: 0 syzkaller login: [ 1584.566405] IPVS: ftp: loaded support on port[0] = 21 [ 1584.677681] chnl_net:caif_netlink_parms(): no params data found [ 1584.772571] bridge0: port 1(bridge_slave_0) entered blocking state [ 1584.779198] bridge0: port 1(bridge_slave_0) entered disabled state [ 1584.786920] device bridge_slave_0 entered promiscuous mode [ 1584.794515] bridge0: port 2(bridge_slave_1) entered blocking state [ 1584.800989] bridge0: port 2(bridge_slave_1) entered disabled state [ 1584.807937] device bridge_slave_1 entered promiscuous mode [ 1584.825273] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 1584.834119] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 1584.853579] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 1584.861012] team0: Port device team_slave_0 added [ 1584.866399] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 1584.874870] team0: Port device team_slave_1 added [ 1584.889811] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 1584.896713] 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. [ 1584.921928] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 1584.933591] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 1584.939816] 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. [ 1584.965144] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 1584.975857] IPv6: ADDRCONF(NETDEV_UP): bridge_slave_0: link is not ready [ 1584.983436] IPv6: ADDRCONF(NETDEV_UP): bridge_slave_1: link is not ready [ 1585.003220] device hsr_slave_0 entered promiscuous mode [ 1585.009305] device hsr_slave_1 entered promiscuous mode [ 1585.015698] IPv6: ADDRCONF(NETDEV_UP): hsr_slave_0: link is not ready [ 1585.022905] IPv6: ADDRCONF(NETDEV_UP): hsr_slave_1: link is not ready [ 1585.087847] bridge0: port 2(bridge_slave_1) entered blocking state [ 1585.094295] bridge0: port 2(bridge_slave_1) entered forwarding state [ 1585.101216] bridge0: port 1(bridge_slave_0) entered blocking state [ 1585.107568] bridge0: port 1(bridge_slave_0) entered forwarding state [ 1585.139118] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 1585.146384] 8021q: adding VLAN 0 to HW filter on device bond0 [ 1585.154947] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 1585.164263] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 1585.172525] bridge0: port 1(bridge_slave_0) entered disabled state [ 1585.179483] bridge0: port 2(bridge_slave_1) entered disabled state [ 1585.186935] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 1585.197029] IPv6: ADDRCONF(NETDEV_UP): team0: link is not ready [ 1585.204155] 8021q: adding VLAN 0 to HW filter on device team0 [ 1585.213246] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 1585.222107] bridge0: port 1(bridge_slave_0) entered blocking state [ 1585.228433] bridge0: port 1(bridge_slave_0) entered forwarding state [ 1585.238421] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 1585.246251] bridge0: port 2(bridge_slave_1) entered blocking state [ 1585.252634] bridge0: port 2(bridge_slave_1) entered forwarding state [ 1585.268043] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 1585.275713] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 1585.285820] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 1585.296510] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 1585.306438] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 1585.315274] IPv6: ADDRCONF(NETDEV_UP): hsr0: link is not ready [ 1585.321453] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 1585.334713] IPv6: ADDRCONF(NETDEV_UP): vxcan0: link is not ready [ 1585.342061] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 1585.348682] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 1585.360839] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 1585.372547] IPv6: ADDRCONF(NETDEV_UP): veth0_virt_wifi: link is not ready [ 1585.382816] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 1585.416447] IPv6: ADDRCONF(NETDEV_UP): veth0_vlan: link is not ready [ 1585.424281] IPv6: ADDRCONF(NETDEV_UP): vlan0: link is not ready [ 1585.432092] IPv6: ADDRCONF(NETDEV_UP): vlan1: link is not ready [ 1585.442232] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 1585.449738] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 1585.457605] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 1585.466880] device veth0_vlan entered promiscuous mode [ 1585.475983] device veth1_vlan entered promiscuous mode [ 1585.483132] IPv6: ADDRCONF(NETDEV_UP): macvlan0: link is not ready [ 1585.492094] IPv6: ADDRCONF(NETDEV_UP): macvlan1: link is not ready [ 1585.503967] IPv6: ADDRCONF(NETDEV_UP): veth0_macvtap: link is not ready [ 1585.512953] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 1585.521465] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 1585.528856] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 1585.538748] device veth0_macvtap entered promiscuous mode [ 1585.545467] IPv6: ADDRCONF(NETDEV_UP): macvtap0: link is not ready [ 1585.553810] device veth1_macvtap entered promiscuous mode [ 1585.563279] IPv6: ADDRCONF(NETDEV_UP): veth0_to_batadv: link is not ready [ 1585.572263] IPv6: ADDRCONF(NETDEV_UP): veth1_to_batadv: link is not ready [ 1585.582567] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 1585.589242] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 1585.598503] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 1585.609050] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 1585.616116] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 1585.727429] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 1585.735102] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 1585.749069] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 1585.758902] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 1585.770439] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready [ 1585.777250] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 1585.784434] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 1585.792219] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 1586.621024] Bluetooth: hci0: command 0x0409 tx timeout 2020/12/28 09:21:45 executed programs: 3 [ 1588.690125] Bluetooth: hci0: command 0x041b tx timeout [ 1590.780170] Bluetooth: hci0: command 0x040f tx timeout [ 1592.859848] Bluetooth: hci0: command 0x0419 tx timeout 2020/12/28 09:21:50 executed programs: 9 2020/12/28 09:21:55 executed programs: 15 2020/12/28 09:22:01 executed programs: 21 2020/12/28 09:22:06 executed programs: 27 2020/12/28 09:22:11 executed programs: 33 2020/12/28 09:22:17 executed programs: 39 2020/12/28 09:22:22 executed programs: 45 2020/12/28 09:22:28 executed programs: 51 2020/12/28 09:22:33 executed programs: 57 [ 1707.722426] Bluetooth: hci0: command 0x0406 tx timeout [ 1802.916071] INFO: task syz-executor.0:8855 blocked for more than 140 seconds. [ 1802.923531] Not tainted 4.19.163-syzkaller #0 [ 1802.929615] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1802.937789] syz-executor.0 D28064 8855 8159 0x00000004 [ 1802.943420] Call Trace: [ 1802.946064] __schedule+0x887/0x2040 [ 1802.949806] ? io_schedule_timeout+0x140/0x140 [ 1802.954383] ? mark_held_locks+0xf0/0xf0 [ 1802.958531] schedule+0x8d/0x1b0 [ 1802.961898] schedule_timeout+0x92d/0xfe0 [ 1802.966092] ? lock_acquire+0x170/0x3c0 [ 1802.970240] ? finish_task_switch+0x118/0x780 [ 1802.974732] ? usleep_range+0x170/0x170 [ 1802.978794] ? wait_for_common+0x294/0x470 [ 1802.983090] ? lock_downgrade+0x720/0x720 [ 1802.987346] ? lock_acquire+0x170/0x3c0 [ 1802.991317] ? wait_for_common+0x9e/0x470 [ 1802.995714] ? _raw_spin_unlock_irq+0x24/0x80 [ 1803.000407] wait_for_common+0x29c/0x470 [ 1803.004468] ? __flush_work+0x37e/0x8b0 [ 1803.008486] ? bit_wait_io_timeout+0x100/0x100 [ 1803.013066] ? ___preempt_schedule+0x16/0x18 [ 1803.017547] ? wake_up_q+0xe0/0xe0 [ 1803.021082] ? ___preempt_schedule+0x16/0x18 [ 1803.025493] __flush_work+0x4bb/0x8b0 [ 1803.029962] ? alloc_unbound_pwq+0xc10/0xc10 [ 1803.034399] ? flush_workqueue_prep_pwqs+0x570/0x570 [ 1803.039593] ? _raw_spin_unlock_irq+0x5a/0x80 [ 1803.044082] ? __flush_work+0x4cf/0x8b0 [ 1803.048135] ? trace_hardirqs_off+0x64/0x200 [ 1803.052555] ? __cancel_work_timer+0x3ba/0x590 [ 1803.057221] __cancel_work_timer+0x412/0x590 [ 1803.061661] ? try_to_grab_pending+0x6f0/0x6f0 [ 1803.066319] ? lock_downgrade+0x720/0x720 [ 1803.070642] ? lock_acquire+0x170/0x3c0 [ 1803.074613] ? p9_fd_close+0x172/0x520 [ 1803.078595] ? _raw_spin_unlock_irqrestore+0x79/0xe0 [ 1803.083703] p9_fd_close+0x305/0x520 [ 1803.087468] p9_client_create+0x901/0x12e0 [ 1803.091706] ? setup_fault_attr+0x200/0x200 [ 1803.096118] ? p9_client_flush+0x490/0x490 [ 1803.100352] ? rcu_read_lock_sched_held+0x16c/0x1d0 [ 1803.105360] ? __lockdep_init_map+0x100/0x5a0 [ 1803.109900] ? __raw_spin_lock_init+0x28/0x100 [ 1803.114482] v9fs_session_init+0x1dd/0x1770 [ 1803.118892] ? gfp_pfmemalloc_allowed+0x150/0x150 [ 1803.123845] ? v9fs_show_options+0x760/0x760 [ 1803.128301] ? setup_fault_attr+0x200/0x200 [ 1803.132620] ? lock_acquire+0x170/0x3c0 [ 1803.136676] ? check_preemption_disabled+0x41/0x280 [ 1803.141717] ? v9fs_mount+0x54/0x910 [ 1803.145414] ? rcu_read_lock_sched_held+0x16c/0x1d0 [ 1803.150479] ? kmem_cache_alloc_trace+0x323/0x380 [ 1803.155425] v9fs_mount+0x73/0x910 [ 1803.160092] ? alloc_pages_current+0x19b/0x2a0 [ 1803.164683] ? __lockdep_init_map+0x100/0x5a0 [ 1803.169233] mount_fs+0xa3/0x30c [ 1803.172816] vfs_kern_mount.part.0+0x68/0x470 [ 1803.177412] do_mount+0x113c/0x2f10 [ 1803.181060] ? do_raw_spin_unlock+0x171/0x230 [ 1803.185537] ? check_preemption_disabled+0x41/0x280 [ 1803.190600] ? copy_mount_string+0x40/0x40 [ 1803.194837] ? copy_mount_options+0x59/0x380 [ 1803.199327] ? rcu_read_lock_sched_held+0x16c/0x1d0 [ 1803.204352] ? kmem_cache_alloc_trace+0x323/0x380 [ 1803.209260] ? copy_mount_options+0x26f/0x380 [ 1803.213782] ksys_mount+0xcf/0x130 [ 1803.217412] __x64_sys_mount+0xba/0x150 [ 1803.221385] ? lockdep_hardirqs_on+0x3a8/0x5c0 [ 1803.226027] do_syscall_64+0xf9/0x620 [ 1803.229828] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 1803.235015] RIP: 0033:0x45e229 [ 1803.238306] Code: Bad RIP value. [ 1803.241681] RSP: 002b:00007fe6acb64c68 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5 [ 1803.249433] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 000000000045e229 [ 1803.256809] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000000000000 [ 1803.264079] RBP: 000000000119c1c8 R08: 0000000020000580 R09: 0000000000000000 [ 1803.271390] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000119c184 [ 1803.278923] R13: 00007fffbfec25df R14: 00007fe6acb659c0 R15: 000000000119c184 [ 1803.286923] [ 1803.286923] Showing all locks held in the system: [ 1803.293261] 1 lock held by khungtaskd/1566: [ 1803.297692] #0: 000000001c84d01c (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 [ 1803.306400] 2 locks held by kworker/0:2/3634: [ 1803.310877] #0: 00000000efcebc64 ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 [ 1803.320245] #1: 00000000b5c2ea31 ((work_completion)(&m->wq)){+.+.}, at: process_one_work+0x79c/0x1570 [ 1803.329922] 1 lock held by in:imklog/7775: [ 1803.334143] #0: 00000000c8341eeb (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 [ 1803.342204] [ 1803.343824] ============================================= [ 1803.343824] [ 1803.350903] NMI backtrace for cpu 1 [ 1803.354522] CPU: 1 PID: 1566 Comm: khungtaskd Not tainted 4.19.163-syzkaller #0 [ 1803.361949] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 1803.371282] Call Trace: [ 1803.373855] dump_stack+0x1fc/0x2fe [ 1803.377468] nmi_cpu_backtrace.cold+0x63/0xa2 [ 1803.381955] ? lapic_can_unplug_cpu.cold+0x39/0x39 [ 1803.386875] nmi_trigger_cpumask_backtrace+0x1a6/0x1eb [ 1803.392235] watchdog+0x991/0xe60 [ 1803.395681] ? reset_hung_task_detector+0x30/0x30 [ 1803.400502] kthread+0x33f/0x460 [ 1803.403848] ? kthread_park+0x180/0x180 [ 1803.407889] ret_from_fork+0x24/0x30 [ 1803.411867] Sending NMI from CPU 1 to CPUs 0: [ 1803.416922] NMI backtrace for cpu 0 [ 1803.416927] CPU: 0 PID: 23 Comm: kworker/u4:1 Not tainted 4.19.163-syzkaller #0 [ 1803.416933] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 1803.416936] Workqueue: bat_events batadv_nc_worker [ 1803.416941] RIP: 0010:lock_acquire+0xc/0x3c0 [ 1803.416956] Code: ff ff 48 c7 c7 40 55 36 8d e8 80 5b 4e 00 e9 e3 fc ff ff 90 66 2e 0f 1f 84 00 00 00 00 00 48 b8 00 00 00 00 00 fc ff df 41 57 <45> 89 c7 41 56 41 89 ce 41 55 41 89 d5 41 54 41 89 f4 55 48 89 fd [ 1803.416959] RSP: 0018:ffff8880b504fc18 EFLAGS: 00000246 [ 1803.416966] RAX: dffffc0000000000 RBX: ffff88809527d5b0 RCX: 0000000000000000 [ 1803.416970] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff88809527d5c8 [ 1803.416975] RBP: ffff88809527d5b0 R08: 0000000000000001 R09: 0000000000000000 [ 1803.416979] R10: 0000000000000004 R11: 0000000000000000 R12: dffffc0000000000 [ 1803.416983] R13: ffffffff87f9be50 R14: 0000000000000002 R15: ffff88809ab9de40 [ 1803.416988] FS: 0000000000000000(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000 [ 1803.416992] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1803.416996] CR2: 00007fc22c426248 CR3: 00000000a1265000 CR4: 00000000001406f0 [ 1803.417001] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 1803.417005] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 1803.417007] Call Trace: [ 1803.417010] _raw_spin_lock_bh+0x2f/0x40 [ 1803.417014] ? batadv_nc_purge_paths+0xdc/0x310 [ 1803.417017] batadv_nc_purge_paths+0xdc/0x310 [ 1803.417020] batadv_nc_worker+0x731/0xd50 [ 1803.417023] process_one_work+0x864/0x1570 [ 1803.417026] ? pwq_dec_nr_in_flight+0x2d0/0x2d0 [ 1803.417029] worker_thread+0x64c/0x1130 [ 1803.417033] ? process_one_work+0x1570/0x1570 [ 1803.417035] kthread+0x33f/0x460 [ 1803.417038] ? kthread_park+0x180/0x180 [ 1803.417041] ret_from_fork+0x24/0x30 [ 1803.418098] Kernel panic - not syncing: hung_task: blocked tasks [ 1803.597994] CPU: 1 PID: 1566 Comm: khungtaskd Not tainted 4.19.163-syzkaller #0 [ 1803.605417] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 1803.614785] Call Trace: [ 1803.617360] dump_stack+0x1fc/0x2fe [ 1803.620967] panic+0x26a/0x50e [ 1803.624138] ? __warn_printk+0xf3/0xf3 [ 1803.628008] ? lapic_can_unplug_cpu.cold+0x39/0x39 [ 1803.632914] ? ___preempt_schedule+0x16/0x18 [ 1803.637317] ? watchdog+0x991/0xe60 [ 1803.640961] ? nmi_trigger_cpumask_backtrace+0x15e/0x1eb [ 1803.646393] watchdog+0x9a2/0xe60 [ 1803.649827] ? reset_hung_task_detector+0x30/0x30 [ 1803.654647] kthread+0x33f/0x460 [ 1803.657990] ? kthread_park+0x180/0x180 [ 1803.661942] ret_from_fork+0x24/0x30 [ 1803.666263] Kernel Offset: disabled [ 1803.669881] Rebooting in 86400 seconds..