[....] Starting enhanced syslogd: rsyslogd[?25l[?1c7[ ok 8[?25h[?0c. [ 68.449639][ T28] audit: type=1800 audit(1577363492.206:25): pid=9325 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="cron" dev="sda1" ino=2414 res=0 [ 68.469462][ T28] audit: type=1800 audit(1577363492.206:26): pid=9325 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="mcstrans" dev="sda1" ino=2457 res=0 [ 68.533895][ T28] audit: type=1800 audit(1577363492.216:27): pid=9325 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="restorecond" dev="sda1" ino=2436 res=0 [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.1.28' (ECDSA) to the list of known hosts. syzkaller login: [ 823.814824][ T9476] IPVS: ftp: loaded support on port[0] = 21 [ 823.888508][ T9476] chnl_net:caif_netlink_parms(): no params data found [ 823.921434][ T9476] bridge0: port 1(bridge_slave_0) entered blocking state [ 823.930451][ T9476] bridge0: port 1(bridge_slave_0) entered disabled state [ 823.939429][ T9476] device bridge_slave_0 entered promiscuous mode [ 823.948051][ T9476] bridge0: port 2(bridge_slave_1) entered blocking state [ 823.955308][ T9476] bridge0: port 2(bridge_slave_1) entered disabled state [ 823.963003][ T9476] device bridge_slave_1 entered promiscuous mode [ 823.981933][ T9476] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 823.993578][ T9476] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 824.014360][ T9476] team0: Port device team_slave_0 added [ 824.022734][ T9476] team0: Port device team_slave_1 added [ 824.097831][ T9476] device hsr_slave_0 entered promiscuous mode [ 824.135479][ T9476] device hsr_slave_1 entered promiscuous mode [ 824.231363][ T9476] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 824.288253][ T9476] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 824.367750][ T9476] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 824.437279][ T9476] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 824.496036][ T9476] bridge0: port 2(bridge_slave_1) entered blocking state [ 824.503280][ T9476] bridge0: port 2(bridge_slave_1) entered forwarding state [ 824.511505][ T9476] bridge0: port 1(bridge_slave_0) entered blocking state [ 824.518646][ T9476] bridge0: port 1(bridge_slave_0) entered forwarding state [ 824.561471][ T9476] 8021q: adding VLAN 0 to HW filter on device bond0 [ 824.574460][ T9478] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 824.596649][ T9478] bridge0: port 1(bridge_slave_0) entered disabled state [ 824.616423][ T9478] bridge0: port 2(bridge_slave_1) entered disabled state [ 824.626280][ T9478] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 824.639741][ T9476] 8021q: adding VLAN 0 to HW filter on device team0 [ 824.651561][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 824.661100][ T3264] bridge0: port 1(bridge_slave_0) entered blocking state [ 824.668345][ T3264] bridge0: port 1(bridge_slave_0) entered forwarding state [ 824.687169][ T9478] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 824.696435][ T9478] bridge0: port 2(bridge_slave_1) entered blocking state [ 824.703490][ T9478] bridge0: port 2(bridge_slave_1) entered forwarding state [ 824.724703][ T9476] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 824.735975][ T9476] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 824.748723][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 824.758007][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 824.766628][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 824.775784][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 824.784070][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 824.791922][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 824.815599][ T9476] 8021q: adding VLAN 0 to HW filter on device batadv0 executing program [ 824.823164][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 824.832921][ T3264] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 985.175212][ T1116] INFO: task syz-executor107:9476 blocked for more than 143 seconds. [ 985.184688][ T1116] Not tainted 5.5.0-rc2-next-20191220-syzkaller #0 [ 985.195537][ T1116] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 985.204213][ T1116] syz-executor107 D24176 9476 9475 0x80004002 [ 985.210675][ T1116] Call Trace: [ 985.214055][ T1116] __schedule+0x934/0x1f90 [ 985.218541][ T1116] ? firmware_map_remove+0x1a0/0x1a0 [ 985.223900][ T1116] ? _raw_spin_unlock_irq+0x23/0x80 [ 985.229198][ T1116] ? wait_on_page_bit+0x23f/0xa60 [ 985.234253][ T1116] ? _raw_spin_unlock_irq+0x23/0x80 [ 985.240256][ T1116] schedule+0xdc/0x2b0 [ 985.244322][ T1116] io_schedule+0x1c/0x70 [ 985.248614][ T1116] wait_on_page_bit+0x27c/0xa60 [ 985.253469][ T1116] ? __lock_page_killable+0xb30/0xb30 [ 985.259030][ T1116] ? rcu_lockdep_current_cpu_online+0xe3/0x130 [ 985.265441][ T1116] ? page_cache_prev_miss+0x340/0x340 [ 985.271124][ T1116] ? rcu_read_lock_sched_held+0x9c/0xd0 [ 985.276791][ T1116] ? rcu_read_lock_any_held.part.0+0x50/0x50 [ 985.282860][ T1116] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 985.289413][ T1116] ? page_mapping+0x301/0x5c0 [ 985.294104][ T1116] wait_on_page_writeback+0x1b2/0x4f0 [ 985.299575][ T1116] __filemap_fdatawait_range+0x145/0x340 [ 985.305242][ T1116] ? add_page_wait_queue+0x260/0x260 [ 985.310667][ T1116] ? find_held_lock+0x35/0x130 [ 985.315542][ T1116] filemap_fdatawait_keep_errors+0x22/0x30 [ 985.321483][ T1116] sync_inodes_sb+0x6e4/0xb50 [ 985.326224][ T1116] ? try_to_writeback_inodes_sb+0x70/0x70 [ 985.332016][ T1116] ? filemap_flush+0x24/0x30 [ 985.336847][ T1116] sync_filesystem+0x168/0x260 [ 985.341715][ T1116] generic_shutdown_super+0x75/0x370 [ 985.347110][ T1116] kill_block_super+0xa0/0x100 [ 985.351887][ T1116] deactivate_locked_super+0x95/0x100 [ 985.357421][ T1116] deactivate_super+0x1bd/0x1e0 [ 985.362276][ T1116] ? iterate_supers_type+0x2b0/0x2b0 [ 985.368273][ T1116] ? dput+0x38/0xe10 [ 985.372271][ T1116] ? dput+0x1ed/0xe10 [ 985.376383][ T1116] ? mnt_get_writers.isra.0+0xf9/0x150 [ 985.381922][ T1116] cleanup_mnt+0x351/0x4c0 [ 985.386504][ T1116] ? trace_hardirqs_on+0x67/0x240 [ 985.391556][ T1116] __cleanup_mnt+0x16/0x20 [ 985.396114][ T1116] task_work_run+0x145/0x1c0 [ 985.400780][ T1116] do_exit+0x909/0x2f20 [ 985.405031][ T1116] ? mm_update_next_owner+0x7c0/0x7c0 [ 985.410454][ T1116] do_group_exit+0x135/0x360 [ 985.415189][ T1116] get_signal+0x47c/0x24f0 [ 985.419677][ T1116] do_signal+0x87/0x1700 [ 985.423904][ T1116] ? trace_hardirqs_on+0x67/0x240 [ 985.428982][ T1116] ? setup_sigcontext+0x7d0/0x7d0 [ 985.434095][ T1116] ? force_sig_info_to_task+0x2a2/0x340 [ 985.439785][ T1116] ? prepare_exit_to_usermode+0x321/0x3a0 [ 985.445671][ T1116] ? exit_to_usermode_loop+0x43/0x380 [ 985.451028][ T1116] ? lockdep_hardirqs_on+0x421/0x5e0 [ 985.456397][ T1116] ? trace_hardirqs_on+0x67/0x240 [ 985.461446][ T1116] exit_to_usermode_loop+0x286/0x380 [ 985.466793][ T1116] prepare_exit_to_usermode+0x321/0x3a0 [ 985.472369][ T1116] ? general_protection+0x8/0x40 [ 985.477401][ T1116] ret_from_intr+0x26/0x36 [ 985.481834][ T1116] RIP: 0033:0x411810 [ 985.486139][ T1116] Code: Bad RIP value. [ 985.490208][ T1116] RSP: 002b:00007ffc7a9ef2f0 EFLAGS: 00010202 [ 985.496965][ T1116] RAX: 0000000000000000 RBX: 000000000000000b RCX: 0000000000402ed0 [ 985.504994][ T1116] RDX: 0000000000000001 RSI: 00000000006d50e0 RDI: 000000000000000b [ 985.513583][ T1116] RBP: 00000000006d50e0 R08: 0000000000000003 R09: 0000000000000003 [ 985.521644][ T1116] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000001 [ 985.529673][ T1116] R13: 0f1414ac00000002 R14: 0000000000000000 R15: 0000000000000000 [ 985.537725][ T1116] [ 985.537725][ T1116] Showing all locks held in the system: [ 985.545642][ T1116] 1 lock held by khungtaskd/1116: [ 985.550647][ T1116] #0: ffffffff899af980 (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x279 [ 985.560043][ T1116] 1 lock held by rsyslogd/9363: [ 985.564900][ T1116] #0: ffff88809acb5120 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110 [ 985.573578][ T1116] 2 locks held by getty/9453: [ 985.578345][ T1116] #0: ffff888094a4d090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.587330][ T1116] #1: ffffc9000178b2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.597068][ T1116] 2 locks held by getty/9454: [ 985.601923][ T1116] #0: ffff888094932090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.611032][ T1116] #1: ffffc900017ab2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.620722][ T1116] 2 locks held by getty/9455: [ 985.626469][ T1116] #0: ffff88809f1ee090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.635662][ T1116] #1: ffffc9000173b2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.645444][ T1116] 2 locks held by getty/9456: [ 985.650103][ T1116] #0: ffff8880985c3090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.659272][ T1116] #1: ffffc9000179b2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.668926][ T1116] 2 locks held by getty/9457: [ 985.673637][ T1116] #0: ffff8880a22e1090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.682745][ T1116] #1: ffffc900017bb2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.692362][ T1116] 2 locks held by getty/9458: [ 985.697087][ T1116] #0: ffff888095bff090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.706080][ T1116] #1: ffffc900017cb2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.715715][ T1116] 2 locks held by getty/9459: [ 985.720387][ T1116] #0: ffff8880a32e1090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 [ 985.729373][ T1116] #1: ffffc900011442e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 [ 985.739593][ T1116] 2 locks held by syz-executor107/9476: [ 985.745196][ T1116] #0: ffff88809799c0d8 (&type->s_umount_key#45){+.+.}, at: deactivate_super+0x1b5/0x1e0 [ 985.755616][ T1116] #1: ffff88809799c8b8 (&s->s_sync_lock){+.+.}, at: sync_inodes_sb+0x246/0xb50 [ 985.764668][ T1116] [ 985.767043][ T1116] ============================================= [ 985.767043][ T1116] [ 985.775619][ T1116] NMI backtrace for cpu 1 [ 985.779953][ T1116] CPU: 1 PID: 1116 Comm: khungtaskd Not tainted 5.5.0-rc2-next-20191220-syzkaller #0 [ 985.789407][ T1116] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 985.799446][ T1116] Call Trace: [ 985.802794][ T1116] dump_stack+0x197/0x210 [ 985.808962][ T1116] nmi_cpu_backtrace.cold+0x70/0xb2 [ 985.814287][ T1116] ? vprintk_func+0x86/0x189 [ 985.819010][ T1116] ? lapic_can_unplug_cpu.cold+0x3a/0x3a [ 985.824761][ T1116] nmi_trigger_cpumask_backtrace+0x23b/0x28b [ 985.830730][ T1116] arch_trigger_cpumask_backtrace+0x14/0x20 [ 985.836617][ T1116] watchdog+0xdf1/0x14e0 [ 985.840888][ T1116] kthread+0x361/0x430 [ 985.844947][ T1116] ? reset_hung_task_detector+0x30/0x30 [ 985.850488][ T1116] ? kthread_mod_delayed_work+0x1f0/0x1f0 [ 985.856364][ T1116] ret_from_fork+0x24/0x30 [ 985.860858][ T1116] Sending NMI from CPU 1 to CPUs 0: [ 985.866178][ C0] NMI backtrace for cpu 0 skipped: idling at native_safe_halt+0xe/0x10 [ 985.867142][ T1116] Kernel panic - not syncing: hung_task: blocked tasks [ 985.881267][ T1116] CPU: 1 PID: 1116 Comm: khungtaskd Not tainted 5.5.0-rc2-next-20191220-syzkaller #0 [ 985.890715][ T1116] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 985.900764][ T1116] Call Trace: [ 985.904042][ T1116] dump_stack+0x197/0x210 [ 985.908354][ T1116] panic+0x2e3/0x75c [ 985.912234][ T1116] ? add_taint.cold+0x16/0x16 [ 985.916902][ T1116] ? lapic_can_unplug_cpu.cold+0x3a/0x3a [ 985.922653][ T1116] ? ___preempt_schedule+0x16/0x18 [ 985.927748][ T1116] ? nmi_trigger_cpumask_backtrace+0x21b/0x28b [ 985.933905][ T1116] ? nmi_trigger_cpumask_backtrace+0x24c/0x28b [ 985.940040][ T1116] ? nmi_trigger_cpumask_backtrace+0x256/0x28b [ 985.946195][ T1116] ? nmi_trigger_cpumask_backtrace+0x21b/0x28b [ 985.952334][ T1116] watchdog+0xe02/0x14e0 [ 985.956588][ T1116] kthread+0x361/0x430 [ 985.960658][ T1116] ? reset_hung_task_detector+0x30/0x30 [ 985.967347][ T1116] ? kthread_mod_delayed_work+0x1f0/0x1f0 [ 985.973132][ T1116] ret_from_fork+0x24/0x30 [ 985.979992][ T1116] Kernel Offset: disabled [ 985.984329][ T1116] Rebooting in 86400 seconds..