[ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Starting Load/Save RF Kill Switch Status... [ OK ] Started Load/Save RF Kill Switch Status. Debian GNU/Linux 9 syzkaller ttyS0 Warning: Permanently added '10.128.0.243' (ECDSA) to the list of known hosts. 2020/11/28 18:08:43 parsed 1 programs 2020/11/28 18:08:44 executed programs: 0 syzkaller login: [ 1579.971870] IPVS: ftp: loaded support on port[0] = 21 [ 1580.078533] chnl_net:caif_netlink_parms(): no params data found [ 1580.156930] bridge0: port 1(bridge_slave_0) entered blocking state [ 1580.163799] bridge0: port 1(bridge_slave_0) entered disabled state [ 1580.170944] device bridge_slave_0 entered promiscuous mode [ 1580.178842] bridge0: port 2(bridge_slave_1) entered blocking state [ 1580.185971] bridge0: port 2(bridge_slave_1) entered disabled state [ 1580.192892] device bridge_slave_1 entered promiscuous mode [ 1580.210683] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 1580.219446] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 1580.238830] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 1580.246300] team0: Port device team_slave_0 added [ 1580.251694] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 1580.259342] team0: Port device team_slave_1 added [ 1580.274664] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 1580.281050] 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. [ 1580.306299] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 1580.317751] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 1580.324071] 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. [ 1580.349362] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 1580.360851] IPv6: ADDRCONF(NETDEV_UP): bridge_slave_0: link is not ready [ 1580.368513] IPv6: ADDRCONF(NETDEV_UP): bridge_slave_1: link is not ready [ 1580.387695] device hsr_slave_0 entered promiscuous mode [ 1580.393507] device hsr_slave_1 entered promiscuous mode [ 1580.399466] IPv6: ADDRCONF(NETDEV_UP): hsr_slave_0: link is not ready [ 1580.407732] IPv6: ADDRCONF(NETDEV_UP): hsr_slave_1: link is not ready [ 1580.471862] bridge0: port 2(bridge_slave_1) entered blocking state [ 1580.478326] bridge0: port 2(bridge_slave_1) entered forwarding state [ 1580.485189] bridge0: port 1(bridge_slave_0) entered blocking state [ 1580.491547] bridge0: port 1(bridge_slave_0) entered forwarding state [ 1580.525346] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 1580.531424] 8021q: adding VLAN 0 to HW filter on device bond0 [ 1580.540089] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 1580.549121] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 1580.558246] bridge0: port 1(bridge_slave_0) entered disabled state [ 1580.566204] bridge0: port 2(bridge_slave_1) entered disabled state [ 1580.574716] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 1580.586065] IPv6: ADDRCONF(NETDEV_UP): team0: link is not ready [ 1580.592146] 8021q: adding VLAN 0 to HW filter on device team0 [ 1580.601022] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 1580.609095] bridge0: port 1(bridge_slave_0) entered blocking state [ 1580.615508] bridge0: port 1(bridge_slave_0) entered forwarding state [ 1580.633662] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 1580.641252] bridge0: port 2(bridge_slave_1) entered blocking state [ 1580.647635] bridge0: port 2(bridge_slave_1) entered forwarding state [ 1580.655583] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 1580.663980] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 1580.672100] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 1580.681390] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 1580.692638] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 1580.703472] IPv6: ADDRCONF(NETDEV_UP): hsr0: link is not ready [ 1580.709492] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 1580.718748] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 1580.732240] IPv6: ADDRCONF(NETDEV_UP): vxcan0: link is not ready [ 1580.740013] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 1580.747018] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 1580.757353] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 1580.770844] IPv6: ADDRCONF(NETDEV_UP): veth0_virt_wifi: link is not ready [ 1580.780423] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 1580.811305] IPv6: ADDRCONF(NETDEV_UP): veth0_vlan: link is not ready [ 1580.818603] IPv6: ADDRCONF(NETDEV_UP): vlan0: link is not ready [ 1580.826488] IPv6: ADDRCONF(NETDEV_UP): vlan1: link is not ready [ 1580.836253] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 1580.844087] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 1580.850925] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 1580.859959] device veth0_vlan entered promiscuous mode [ 1580.868935] device veth1_vlan entered promiscuous mode [ 1580.875036] IPv6: ADDRCONF(NETDEV_UP): macvlan0: link is not ready [ 1580.883605] IPv6: ADDRCONF(NETDEV_UP): macvlan1: link is not ready [ 1580.895173] IPv6: ADDRCONF(NETDEV_UP): veth0_macvtap: link is not ready [ 1580.905498] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 1580.912693] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 1580.920262] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 1580.929498] device veth0_macvtap entered promiscuous mode [ 1580.936181] IPv6: ADDRCONF(NETDEV_UP): macvtap0: link is not ready [ 1580.944620] device veth1_macvtap entered promiscuous mode [ 1580.952804] IPv6: ADDRCONF(NETDEV_UP): veth0_to_batadv: link is not ready [ 1580.962744] IPv6: ADDRCONF(NETDEV_UP): veth1_to_batadv: link is not ready [ 1580.973270] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 1580.980035] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 1580.988974] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 1580.999353] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 1581.006691] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 1581.112633] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 1581.121197] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 1581.138615] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready [ 1581.142487] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 1581.149932] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 1581.155260] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 1581.169112] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 1581.176635] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 1582.014229] Bluetooth: hci0: command 0x0409 tx timeout [ 1584.103068] Bluetooth: hci0: command 0x041b tx timeout 2020/11/28 18:08:49 executed programs: 4 [ 1586.173519] Bluetooth: hci0: command 0x040f tx timeout [ 1588.253010] Bluetooth: hci0: command 0x0419 tx timeout 2020/11/28 18:08:55 executed programs: 10 2020/11/28 18:09:00 executed programs: 16 2020/11/28 18:09:05 executed programs: 22 2020/11/28 18:09:10 executed programs: 28 2020/11/28 18:09:15 executed programs: 34 2020/11/28 18:09:20 executed programs: 40 2020/11/28 18:09:25 executed programs: 46 2020/11/28 18:09:31 executed programs: 52 2020/11/28 18:09:36 executed programs: 58 2020/11/28 18:09:41 executed programs: 64 2020/11/28 18:09:46 executed programs: 70 2020/11/28 18:09:51 executed programs: 76 2020/11/28 18:09:56 executed programs: 82 2020/11/28 18:10:01 executed programs: 88 [ 1702.644814] Bluetooth: hci0: command 0x0406 tx timeout [ 1823.437278] INFO: task syz-executor.0:9027 blocked for more than 140 seconds. [ 1823.444668] Not tainted 4.19.160-syzkaller #0 [ 1823.451489] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1823.460109] syz-executor.0 D27992 9027 8127 0x00000004 [ 1823.465735] Call Trace: [ 1823.468374] __schedule+0x887/0x2040 [ 1823.472096] ? io_schedule_timeout+0x140/0x140 [ 1823.476788] ? mark_held_locks+0xf0/0xf0 [ 1823.481674] schedule+0x8d/0x1b0 [ 1823.485050] schedule_timeout+0x92d/0xfe0 [ 1823.489235] ? lock_acquire+0x170/0x3c0 [ 1823.493209] ? finish_task_switch+0x118/0x780 [ 1823.498427] ? usleep_range+0x170/0x170 [ 1823.502443] ? wait_for_common+0x294/0x470 [ 1823.506678] ? lock_downgrade+0x720/0x720 [ 1823.511737] ? lock_acquire+0x170/0x3c0 [ 1823.515726] ? wait_for_common+0x9e/0x470 [ 1823.520583] ? _raw_spin_unlock_irq+0x24/0x80 [ 1823.525187] wait_for_common+0x29c/0x470 [ 1823.529917] ? __flush_work+0x37e/0x8b0 [ 1823.533886] ? bit_wait_io_timeout+0x100/0x100 [ 1823.539074] ? ___preempt_schedule+0x16/0x18 [ 1823.543476] ? wake_up_q+0xe0/0xe0 [ 1823.547807] ? ___preempt_schedule+0x16/0x18 [ 1823.552235] __flush_work+0x4bb/0x8b0 [ 1823.556045] ? alloc_unbound_pwq+0xc10/0xc10 [ 1823.561439] ? flush_workqueue_prep_pwqs+0x570/0x570 [ 1823.566552] ? _raw_spin_unlock_irq+0x5a/0x80 [ 1823.571700] ? __flush_work+0x4cf/0x8b0 [ 1823.575690] ? trace_hardirqs_off+0x64/0x200 [ 1823.580766] ? __cancel_work_timer+0x3ba/0x590 [ 1823.585342] __cancel_work_timer+0x412/0x590 [ 1823.591003] ? try_to_grab_pending+0x6f0/0x6f0 [ 1823.595577] ? lock_downgrade+0x720/0x720 [ 1823.600361] ? lock_acquire+0x170/0x3c0 [ 1823.604337] ? p9_fd_close+0x172/0x520 [ 1823.608825] ? _raw_spin_unlock_irqrestore+0x79/0xe0 [ 1823.613920] p9_fd_close+0x305/0x520 [ 1823.618252] p9_client_create+0x901/0x12e0 [ 1823.622484] ? setup_fault_attr+0x200/0x200 [ 1823.626787] ? p9_client_flush+0x490/0x490 [ 1823.631877] ? rcu_read_lock_sched_held+0x16c/0x1d0 [ 1823.637288] ? __lockdep_init_map+0x100/0x5a0 [ 1823.641873] ? __raw_spin_lock_init+0x28/0x100 [ 1823.646539] v9fs_session_init+0x1dd/0x1770 [ 1823.651725] ? gfp_pfmemalloc_allowed+0x150/0x150 [ 1823.656780] ? v9fs_show_options+0x760/0x760 [ 1823.661835] ? setup_fault_attr+0x200/0x200 [ 1823.666202] ? lock_acquire+0x170/0x3c0 [ 1823.670942] ? check_preemption_disabled+0x41/0x280 [ 1823.675981] ? v9fs_mount+0x54/0x910 [ 1823.680341] ? rcu_read_lock_sched_held+0x16c/0x1d0 [ 1823.685362] ? kmem_cache_alloc_trace+0x323/0x380 [ 1823.690863] v9fs_mount+0x73/0x910 [ 1823.694403] ? alloc_pages_current+0x19b/0x2a0 [ 1823.699686] ? __lockdep_init_map+0x100/0x5a0 [ 1823.704890] mount_fs+0xa3/0x30c [ 1823.708961] vfs_kern_mount.part.0+0x68/0x470 [ 1823.713718] do_mount+0x113c/0x2f10 [ 1823.718136] ? do_raw_spin_unlock+0x171/0x230 [ 1823.722763] ? check_preemption_disabled+0x41/0x280 [ 1823.728459] ? copy_mount_string+0x40/0x40 [ 1823.732809] ? copy_mount_options+0x59/0x380 [ 1823.737898] ? rcu_read_lock_sched_held+0x16c/0x1d0 [ 1823.743062] ? kmem_cache_alloc_trace+0x323/0x380 [ 1823.748578] ? copy_mount_options+0x26f/0x380 [ 1823.753075] ksys_mount+0xcf/0x130 [ 1823.758825] __x64_sys_mount+0xba/0x150 [ 1823.762807] ? lockdep_hardirqs_on+0x3a8/0x5c0 [ 1823.768115] do_syscall_64+0xf9/0x620 [ 1823.771910] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 1823.778506] RIP: 0033:0x45deb9 [ 1823.781702] Code: Bad RIP value. [ 1823.785062] RSP: 002b:00007fa52176ec78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5 [ 1823.793588] RAX: ffffffffffffffda RBX: 0000000000021840 RCX: 000000000045deb9 [ 1823.801296] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000000000000 [ 1823.809063] RBP: 000000000118c168 R08: 0000000020000580 R09: 0000000000000000 [ 1823.816344] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000118c124 [ 1823.824251] R13: 00007fffd9e2367f R14: 00007fa52176f9c0 R15: 000000000118c124 [ 1823.831999] [ 1823.831999] Showing all locks held in the system: [ 1823.839034] 1 lock held by khungtaskd/1566: [ 1823.843343] #0: 000000009a8be0a8 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 [ 1823.852633] 1 lock held by in:imklog/7766: [ 1823.857413] #0: 00000000e69e536c (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 [ 1823.865607] 2 locks held by kworker/0:3/8336: [ 1823.870736] #0: 00000000a0f0bbcb ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 [ 1823.880438] #1: 000000006aa3f29b ((work_completion)(&m->wq)){+.+.}, at: process_one_work+0x79c/0x1570 [ 1823.890294] [ 1823.891915] ============================================= [ 1823.891915] [ 1823.899724] NMI backtrace for cpu 0 [ 1823.903510] CPU: 0 PID: 1566 Comm: khungtaskd Not tainted 4.19.160-syzkaller #0 [ 1823.910934] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 1823.920265] Call Trace: [ 1823.922841] dump_stack+0x1fc/0x2fe [ 1823.926624] nmi_cpu_backtrace.cold+0x63/0xa2 [ 1823.931119] ? lapic_can_unplug_cpu.cold+0x39/0x39 [ 1823.936027] nmi_trigger_cpumask_backtrace+0x1a6/0x1eb [ 1823.941286] watchdog+0x991/0xe60 [ 1823.945155] ? reset_hung_task_detector+0x30/0x30 [ 1823.949992] kthread+0x33f/0x460 [ 1823.953350] ? kthread_park+0x180/0x180 [ 1823.957303] ret_from_fork+0x24/0x30 [ 1823.961179] Sending NMI from CPU 0 to CPUs 1: [ 1823.966026] NMI backtrace for cpu 1 [ 1823.966032] CPU: 1 PID: 4687 Comm: systemd-journal Not tainted 4.19.160-syzkaller #0 [ 1823.966038] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 1823.966041] RIP: 0010:write_comp_data+0x16/0x70 [ 1823.966052] Code: 39 d0 76 07 48 89 34 d1 48 89 11 c3 0f 1f 84 00 00 00 00 00 49 89 f1 49 89 fa 65 48 8b 34 25 80 df 01 00 65 8b 05 ba 0e 9d 7e 00 01 1f 00 75 4f 8b 86 d8 12 00 00 83 f8 03 75 44 48 8b 86 e0 [ 1823.966056] RSP: 0018:ffff8880a0c47b90 EFLAGS: 00000246 [ 1823.966063] RAX: 0000000080000000 RBX: ffff8880a13bb840 RCX: ffffffff834d4e0b [ 1823.966068] RDX: 0000000000000000 RSI: ffff8880a0c3e4c0 RDI: 0000000000000001 [ 1823.966073] RBP: ffff8880a13bcf00 R08: 0000000000000000 R09: 0000000000000000 [ 1823.966077] R10: 0000000000000001 R11: 0000000000000000 R12: ffff88813be814e0 [ 1823.966082] R13: ffff88813be814e0 R14: 0000000000000000 R15: ffff8880a13bb840 [ 1823.966088] FS: 00007f4fbb8c48c0(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000 [ 1823.966092] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1823.966097] CR2: 00007f4fb8cac018 CR3: 00000000a1672000 CR4: 00000000001406e0 [ 1823.966101] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 1823.966106] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 1823.966109] Call Trace: [ 1823.966112] aa_file_perm+0x19b/0xd20 [ 1823.966115] ? seccomp_run_filters+0x18c/0x590 [ 1823.966118] ? aa_path_link+0x410/0x410 [ 1823.966122] ? __put_seccomp_filter+0xa0/0xa0 [ 1823.966125] ? mark_held_locks+0xf0/0xf0 [ 1823.966129] ? __sanitizer_cov_trace_switch+0x4b/0x80 [ 1823.966132] common_file_perm+0x445/0x850 [ 1823.966136] ? apparmor_getprocattr+0x11d0/0x11d0 [ 1823.966139] ? mark_held_locks+0xf0/0xf0 [ 1823.966142] security_file_permission+0x54/0x220 [ 1823.966146] rw_verify_area+0x104/0x330 [ 1823.966149] vfs_read+0x145/0x3c0 [ 1823.966152] ksys_read+0x12b/0x2a0 [ 1823.966155] ? kernel_write+0x110/0x110 [ 1823.966158] ? trace_hardirqs_off_caller+0x6e/0x210 [ 1823.966162] ? do_syscall_64+0x21/0x620 [ 1823.966165] do_syscall_64+0xf9/0x620 [ 1823.966168] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 1823.966171] RIP: 0033:0x7f4fbae54210 [ 1823.966182] Code: 73 01 c3 48 8b 0d 98 7d 20 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 83 3d b9 c1 20 00 00 75 10 b8 00 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 4e fc ff ff 48 89 04 24 [ 1823.966186] RSP: 002b:00007fff0f651db8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000 [ 1823.966194] RAX: ffffffffffffffda RBX: 00007fff0f654830 RCX: 00007f4fbae54210 [ 1823.966199] RDX: 0000000000002000 RSI: 00007fff0f652630 RDI: 0000000000000009 [ 1823.966203] RBP: 0000000000000000 R08: 0000000000000008 R09: 000001e941d5f73a [ 1823.966208] R10: 000320fbd3c3ea58 R11: 0000000000000246 R12: 00007fff0f652630 [ 1823.966213] R13: 00007fff0f654788 R14: 000055beebc51958 R15: 0005b52eb9882f79 [ 1823.968337] Kernel panic - not syncing: hung_task: blocked tasks [ 1824.247647] CPU: 0 PID: 1566 Comm: khungtaskd Not tainted 4.19.160-syzkaller #0 [ 1824.255071] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 1824.264401] Call Trace: [ 1824.266978] dump_stack+0x1fc/0x2fe [ 1824.270587] panic+0x26a/0x50e [ 1824.273763] ? __warn_printk+0xf3/0xf3 [ 1824.277638] ? lapic_can_unplug_cpu.cold+0x39/0x39 [ 1824.282547] ? ___preempt_schedule+0x16/0x18 [ 1824.286963] ? watchdog+0x991/0xe60 [ 1824.290598] ? nmi_trigger_cpumask_backtrace+0x15e/0x1eb [ 1824.296028] watchdog+0x9a2/0xe60 [ 1824.299465] ? reset_hung_task_detector+0x30/0x30 [ 1824.304289] kthread+0x33f/0x460 [ 1824.307650] ? kthread_park+0x180/0x180 [ 1824.311605] ret_from_fork+0x24/0x30 [ 1824.316105] Kernel Offset: disabled [ 1824.319751] Rebooting in 86400 seconds..