[ 81.793679][ T27] audit: type=1800 audit(1581070612.848:27): pid=9767 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="restorecond" dev="sda1" ino=2436 res=0 [ 81.929257][ T27] audit: type=1800 audit(1581070613.028:28): pid=9767 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="ssh" dev="sda1" ino=2417 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. [ 82.743818][ T27] audit: type=1800 audit(1581070613.838:29): pid=9767 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="rc.local" dev="sda1" ino=2432 res=0 [ 82.772022][ T27] audit: type=1800 audit(1581070613.848:30): pid=9767 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="rmnologin" dev="sda1" ino=2423 res=0 Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.0.232' (ECDSA) to the list of known hosts. 2020/02/07 10:17:31 parsed 1 programs 2020/02/07 10:17:33 executed programs: 0 syzkaller login: [ 122.227293][ T9941] IPVS: ftp: loaded support on port[0] = 21 [ 122.285347][ T9941] chnl_net:caif_netlink_parms(): no params data found [ 122.319928][ T9941] bridge0: port 1(bridge_slave_0) entered blocking state [ 122.327948][ T9941] bridge0: port 1(bridge_slave_0) entered disabled state [ 122.335907][ T9941] device bridge_slave_0 entered promiscuous mode [ 122.344822][ T9941] bridge0: port 2(bridge_slave_1) entered blocking state [ 122.352157][ T9941] bridge0: port 2(bridge_slave_1) entered disabled state [ 122.360145][ T9941] device bridge_slave_1 entered promiscuous mode [ 122.377608][ T9941] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 122.388827][ T9941] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 122.408074][ T9941] team0: Port device team_slave_0 added [ 122.415357][ T9941] team0: Port device team_slave_1 added [ 122.429876][ T9941] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 122.436897][ T9941] 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. [ 122.463119][ T9941] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 122.475768][ T9941] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 122.482718][ T9941] 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. [ 122.509286][ T9941] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 122.565965][ T9941] device hsr_slave_0 entered promiscuous mode [ 122.603933][ T9941] device hsr_slave_1 entered promiscuous mode [ 122.724751][ T9941] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 122.766594][ T9941] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 122.816400][ T9941] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 122.875823][ T9941] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 122.926169][ T9941] bridge0: port 2(bridge_slave_1) entered blocking state [ 122.933347][ T9941] bridge0: port 2(bridge_slave_1) entered forwarding state [ 122.941303][ T9941] bridge0: port 1(bridge_slave_0) entered blocking state [ 122.948388][ T9941] bridge0: port 1(bridge_slave_0) entered forwarding state [ 122.991649][ T9941] 8021q: adding VLAN 0 to HW filter on device bond0 [ 123.005990][ T12] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 123.017023][ T12] bridge0: port 1(bridge_slave_0) entered disabled state [ 123.025253][ T12] bridge0: port 2(bridge_slave_1) entered disabled state [ 123.033269][ T12] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 123.048079][ T9941] 8021q: adding VLAN 0 to HW filter on device team0 [ 123.058980][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 123.068275][ T3126] bridge0: port 1(bridge_slave_0) entered blocking state [ 123.075397][ T3126] bridge0: port 1(bridge_slave_0) entered forwarding state [ 123.093965][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 123.102628][ T5] bridge0: port 2(bridge_slave_1) entered blocking state [ 123.109782][ T5] bridge0: port 2(bridge_slave_1) entered forwarding state [ 123.120989][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 123.131150][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 123.143274][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 123.161494][ T9941] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 123.174790][ T9941] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 123.188212][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 123.197832][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 123.206591][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 123.226054][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 123.234774][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 123.248850][ T9941] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 123.267180][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 123.289600][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 123.298779][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 123.308997][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 123.319476][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 123.329876][ T9941] device veth0_vlan entered promiscuous mode [ 123.341885][ T9941] device veth1_vlan entered promiscuous mode [ 123.369937][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 123.379023][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 123.390399][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 123.399818][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 123.411791][ T9941] device veth0_macvtap entered promiscuous mode [ 123.422939][ T9941] device veth1_macvtap entered promiscuous mode [ 123.440385][ T9941] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 123.448724][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 123.457254][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 123.466028][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 123.478882][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 123.492106][ T9941] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 123.501456][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 123.511754][ T3126] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 123.773580][ C0] [ 123.775960][ C0] ================================ [ 123.781543][ C0] WARNING: inconsistent lock state [ 123.786805][ C0] 5.5.0-syzkaller #0 Not tainted [ 123.791944][ C0] -------------------------------- [ 123.797287][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 123.804230][ C0] syz-execprog/9932 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 123.810719][ C0] ffff8880a8c919c8 (&(&local->client_conns_lock)->rlock){+.?.}, at: rxrpc_put_client_conn+0x6ed/0xc90 [ 123.822076][ C0] {SOFTIRQ-ON-W} state was registered at: [ 123.829397][ C0] lock_acquire+0x190/0x410 [ 123.834429][ C0] _raw_spin_lock+0x2f/0x40 [ 123.839222][ C0] rxrpc_connect_call+0x358/0x4e30 [ 123.844538][ C0] rxrpc_new_client_call+0x9c0/0x1ad0 [ 123.850082][ C0] rxrpc_do_sendmsg+0xffa/0x1d5f [ 123.855605][ C0] rxrpc_sendmsg+0x4d6/0x5f0 [ 123.860901][ C0] sock_sendmsg+0xd7/0x130 [ 123.865411][ C0] ____sys_sendmsg+0x358/0x880 [ 123.870326][ C0] ___sys_sendmsg+0x100/0x170 [ 123.875079][ C0] __sys_sendmmsg+0x1bf/0x4d0 [ 123.880438][ C0] __x64_sys_sendmmsg+0x9d/0x100 [ 123.886521][ C0] do_syscall_64+0xfa/0x790 [ 123.891294][ C0] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 123.897463][ C0] irq event stamp: 16112 [ 123.902048][ C0] hardirqs last enabled at (16112): [] _raw_spin_unlock_irqrestore+0x66/0xe0 [ 123.912723][ C0] hardirqs last disabled at (16111): [] _raw_spin_lock_irqsave+0x6f/0xcd [ 123.922972][ C0] softirqs last enabled at (12486): [] __do_softirq+0x6cd/0x98c [ 123.932683][ C0] softirqs last disabled at (16083): [] irq_exit+0x19b/0x1e0 [ 123.942070][ C0] [ 123.942070][ C0] other info that might help us debug this: [ 123.951425][ C0] Possible unsafe locking scenario: [ 123.951425][ C0] [ 123.958927][ C0] CPU0 [ 123.962275][ C0] ---- [ 123.965549][ C0] lock(&(&local->client_conns_lock)->rlock); [ 123.972218][ C0] [ 123.975864][ C0] lock(&(&local->client_conns_lock)->rlock); [ 123.982627][ C0] [ 123.982627][ C0] *** DEADLOCK *** [ 123.982627][ C0] [ 123.991494][ C0] 3 locks held by syz-execprog/9932: [ 123.996818][ C0] #0: ffff88809ee65120 (&(ptlock_ptr(page))->rlock#2){+.+.}, at: unmap_page_range+0x885/0x28d0 [ 124.007671][ C0] #1: ffffffff89bac200 (rcu_read_lock){....}, at: lock_page_memcg+0x0/0x240 [ 124.016692][ C0] #2: ffffffff89bac140 (rcu_callback){....}, at: rcu_core+0x562/0x1390 [ 124.025182][ C0] [ 124.025182][ C0] stack backtrace: [ 124.031151][ C0] CPU: 0 PID: 9932 Comm: syz-execprog Not tainted 5.5.0-syzkaller #0 [ 124.039471][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 124.049814][ C0] Call Trace: [ 124.053093][ C0] [ 124.056354][ C0] dump_stack+0x197/0x210 [ 124.060755][ C0] print_usage_bug.cold+0x327/0x378 [ 124.066149][ C0] mark_lock+0xbb4/0x1220 [ 124.070570][ C0] ? rcu_core_si+0x9/0x10 [ 124.075384][ C0] ? check_usage_backwards+0x330/0x330 [ 124.081620][ C0] ? irq_exit+0x19b/0x1e0 [ 124.086478][ C0] __lock_acquire+0x1e8e/0x4a00 [ 124.091369][ C0] ? __kasan_check_read+0x11/0x20 [ 124.096487][ C0] ? mark_lock+0xc2/0x1220 [ 124.101104][ C0] ? mark_held_locks+0xf0/0xf0 [ 124.106140][ C0] ? _raw_spin_unlock_irqrestore+0x66/0xe0 [ 124.112043][ C0] lock_acquire+0x190/0x410 [ 124.116594][ C0] ? rxrpc_put_client_conn+0x6ed/0xc90 [ 124.122053][ C0] _raw_spin_lock+0x2f/0x40 [ 124.127610][ C0] ? rxrpc_put_client_conn+0x6ed/0xc90 [ 124.133535][ C0] rxrpc_put_client_conn+0x6ed/0xc90 [ 124.138834][ C0] ? rxrpc_rcu_destroy_call+0xbd/0x200 [ 124.144333][ C0] rxrpc_rcu_destroy_call+0xbd/0x200 [ 124.150439][ C0] rcu_core+0x5e1/0x1390 [ 124.154677][ C0] ? __rcu_read_unlock+0x700/0x700 [ 124.159808][ C0] ? rcu_read_lock_sched_held+0x9c/0xd0 [ 124.165345][ C0] ? rcu_read_lock_any_held.part.0+0x50/0x50 [ 124.171318][ C0] rcu_core_si+0x9/0x10 [ 124.175458][ C0] __do_softirq+0x262/0x98c [ 124.179948][ C0] ? sched_clock_cpu+0x1b/0x1b0 [ 124.184790][ C0] irq_exit+0x19b/0x1e0 [ 124.188951][ C0] smp_apic_timer_interrupt+0x1a3/0x610 [ 124.194485][ C0] apic_timer_interrupt+0xf/0x20 [ 124.199400][ C0] [ 124.202319][ C0] RIP: 0010:__rcu_read_unlock+0x173/0x700 [ 124.208015][ C0] Code: 00 85 c0 75 7c 65 48 8b 1c 25 c0 1e 02 00 48 8d bb 78 03 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 0f b6 04 02 <84> c0 74 04 3c 03 7e 34 c7 83 78 03 00 00 00 00 00 00 e9 01 ff ff [ 124.227609][ C0] RSP: 0018:ffffc9000682f8d0 EFLAGS: 00000a06 ORIG_RAX: ffffffffffffff13 [ 124.235994][ C0] RAX: 0000000000000000 RBX: ffff8880993c8540 RCX: 0000000000000000 [ 124.243941][ C0] RDX: 1ffff11013279117 RSI: 0000000000000004 RDI: ffff8880993c88b8 [ 124.251887][ C0] RBP: ffffc9000682f908 R08: 1ffff11015d0707b R09: ffffed1015d0707c [ 124.259843][ C0] R10: ffffed1015d0707b R11: ffff8880ae8383db R12: ffffea0002435240 [ 124.267789][ C0] R13: 0000000000000000 R14: 0000000000000001 R15: ffff8880993c8540 [ 124.275760][ C0] ? rcu_dynticks_curr_cpu_in_eqs+0x54/0xb0 [ 124.281641][ C0] __unlock_page_memcg+0x58/0x100 [ 124.286640][ C0] unlock_page_memcg+0x2c/0x40 [ 124.291415][ C0] page_remove_rmap+0x60c/0x11d0 [ 124.296346][ C0] ? __sanitizer_cov_trace_const_cmp1+0x1a/0x20 [ 124.302597][ C0] unmap_page_range+0xde0/0x28d0 [ 124.307526][ C0] ? vm_normal_page_pmd+0x470/0x470 [ 124.312771][ C0] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 124.319033][ C0] ? uprobe_munmap+0xad/0x320 [ 124.323689][ C0] unmap_single_vma+0x19d/0x300 [ 124.328516][ C0] unmap_vmas+0x184/0x2f0 [ 124.332844][ C0] ? zap_vma_ptes+0x110/0x110 [ 124.337500][ C0] ? __khugepaged_exit+0x34c/0x410 [ 124.342722][ C0] ? __khugepaged_exit+0x34c/0x410 [ 124.347851][ C0] ? __kasan_check_write+0x14/0x20 [ 124.352970][ C0] exit_mmap+0x2ba/0x530 [ 124.357191][ C0] ? __ia32_sys_munmap+0x80/0x80 [ 124.362125][ C0] ? __khugepaged_exit+0xcf/0x410 [ 124.367152][ C0] mmput+0x179/0x4d0 [ 124.371291][ C0] do_exit+0xac2/0x2f50 [ 124.375426][ C0] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 124.380867][ C0] ? mm_update_next_owner+0x7c0/0x7c0 [ 124.386228][ C0] ? preempt_schedule_common+0x63/0xe0 [ 124.391665][ C0] ? preempt_schedule+0x4b/0x60 [ 124.396507][ C0] ? ___preempt_schedule+0x16/0x18 [ 124.401609][ C0] do_group_exit+0x135/0x360 [ 124.406188][ C0] __x64_sys_exit_group+0x44/0x50 [ 124.411216][ C0] do_syscall_64+0xfa/0x790 [ 124.415758][ C0] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 124.421631][ C0] RIP: 0033:0x45af3b [ 124.425514][ C0] Code: Bad RIP value. [ 124.429669][ C0] RSP: 002b:000000c000041f58 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 124.438070][ C0] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 000000000045af3b [ 124.446018][ C0] RDX: 0000000000000001 RSI: 000000c000021310 RDI: 0000000000000000 [ 124.453975][ C0] RBP: 000000c000041f58 R08: 0000000000000080 R09: 0000000000000000 [ 124.461926][ C0] R10: 0000000000000000 R11: 0000000000000246 R12: ffffffffffffffff [ 124.469882][ C0] R13: 0000000000000049 R14: 0000000000000048 R15: 000000