Enabling of bearer rejected, already enabled Enabling of bearer rejected, already enabled ================================ Enabling of bearer rejected, already enabled WARNING: inconsistent lock state 4.20.0-rc6+ #150 Not tainted -------------------------------- inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage. ksoftirqd/0/9 [HC0[0]:SC1[3]:HE1:SE0] takes: 00000000b8fc8cda (&(&i->lock)->rlock){?.-.}, at: spin_trylock include/linux/spinlock.h:339 [inline] 00000000b8fc8cda (&(&i->lock)->rlock){?.-.}, at: icmp_xmit_lock net/ipv4/icmp.c:219 [inline] 00000000b8fc8cda (&(&i->lock)->rlock){?.-.}, at: icmp_send+0x59e/0x1bd0 net/ipv4/icmp.c:665 Enabling of bearer rejected, already enabled {IN-HARDIRQ-W} state was registered at: lock_acquire+0x1ed/0x520 kernel/locking/lockdep.c:3844 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2d/0x40 kernel/locking/spinlock.c:144 spin_lock include/linux/spinlock.h:329 [inline] serial8250_interrupt+0x29/0x190 drivers/tty/serial/8250/8250_core.c:115 Enabling of bearer rejected, already enabled __handle_irq_event_percpu+0x195/0xb30 kernel/irq/handle.c:149 handle_irq_event_percpu+0xa0/0x1d0 kernel/irq/handle.c:189 handle_irq_event+0xa7/0x135 kernel/irq/handle.c:206 kobject: 'loop0' (000000006a387ba7): kobject_uevent_env handle_edge_irq+0x227/0x880 kernel/irq/chip.c:791 Enabling of bearer rejected, already enabled generic_handle_irq_desc include/linux/irqdesc.h:154 [inline] handle_irq+0x252/0x3d8 arch/x86/kernel/irq_64.c:78 do_IRQ+0x98/0x1c0 arch/x86/kernel/irq.c:246 ret_from_intr+0x0/0x1e arch_local_irq_restore arch/x86/include/asm/paravirt.h:761 [inline] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] _raw_spin_unlock_irqrestore+0xaf/0xd0 kernel/locking/spinlock.c:184 spin_unlock_irqrestore include/linux/spinlock.h:384 [inline] serial8250_do_startup+0xc86/0x2080 drivers/tty/serial/8250/8250_port.c:2370 kobject: 'loop0' (000000006a387ba7): fill_kobj_path: path = '/devices/virtual/block/loop0' serial8250_startup+0x61/0x80 drivers/tty/serial/8250/8250_port.c:2425 uart_port_startup+0x51f/0x9c0 drivers/tty/serial/serial_core.c:213 uart_startup drivers/tty/serial/serial_core.c:252 [inline] uart_port_activate+0x194/0x230 drivers/tty/serial/serial_core.c:1769 kobject: 'loop1' (000000009d41c739): kobject_uevent_env tty_port_open+0x137/0x1c0 drivers/tty/tty_port.c:688 uart_open+0xe6/0x130 drivers/tty/serial/serial_core.c:1748 tty_open+0x2aa/0xb30 drivers/tty/tty_io.c:2037 chrdev_open+0x25a/0x710 fs/char_dev.c:417 do_dentry_open+0x499/0x1250 fs/open.c:771 vfs_open+0xa0/0xd0 fs/open.c:880 kobject: 'loop1' (000000009d41c739): fill_kobj_path: path = '/devices/virtual/block/loop1' do_last fs/namei.c:3418 [inline] path_openat+0x12bc/0x5150 fs/namei.c:3534 do_filp_open+0x255/0x380 fs/namei.c:3564 Enabling of bearer rejected, already enabled do_sys_open+0x568/0x700 fs/open.c:1063 ksys_open include/linux/syscalls.h:1279 [inline] kernel_init_freeable+0x612/0x6bf init/main.c:1154 kernel_init+0x11/0x1ae init/main.c:1071 Enabling of bearer rejected, already enabled ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 irq event stamp: 357964 hardirqs last enabled at (357964): [] __local_bh_enable_ip+0x160/0x260 kernel/softirq.c:194 hardirqs last disabled at (357963): [] __local_bh_enable_ip+0x120/0x260 kernel/softirq.c:171 softirqs last enabled at (357944): [] __do_softirq+0x7df/0xb7e kernel/softirq.c:319 Enabling of bearer rejected, already enabled softirqs last disabled at (357949): [] run_ksoftirqd+0x5e/0x100 kernel/softirq.c:654 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&(&i->lock)->rlock); lock(&(&i->lock)->rlock); Enabling of bearer rejected, already enabled *** DEADLOCK *** 2 locks held by ksoftirqd/0/9: #0: 000000008aedccf1 (rcu_read_lock){....}, at: __skb_unlink include/linux/skbuff.h:1909 [inline] #0: 000000008aedccf1 (rcu_read_lock){....}, at: __skb_dequeue include/linux/skbuff.h:1925 [inline] #0: 000000008aedccf1 (rcu_read_lock){....}, at: process_backlog+0x1dd/0x7a0 net/core/dev.c:5862 #1: 000000008aedccf1 (rcu_read_lock){....}, at: __skb_pull include/linux/skbuff.h:2142 [inline] #1: 000000008aedccf1 (rcu_read_lock){....}, at: ip_local_deliver_finish+0x19a/0xda0 net/ipv4/ip_input.c:193 stack backtrace: Enabling of bearer rejected, already enabled CPU: 0 PID: 9 Comm: ksoftirqd/0 Not tainted 4.20.0-rc6+ #150 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x244/0x39d lib/dump_stack.c:113 Enabling of bearer rejected, already enabled print_usage_bug.cold.59+0x320/0x41a kernel/locking/lockdep.c:2472 kobject: 'loop4' (000000002b4feb64): kobject_uevent_env valid_state kernel/locking/lockdep.c:2485 [inline] mark_lock_irq kernel/locking/lockdep.c:2679 [inline] mark_lock+0x1276/0x1cd0 kernel/locking/lockdep.c:3059 mark_irqflags kernel/locking/lockdep.c:2951 [inline] __lock_acquire+0xcad/0x4c20 kernel/locking/lockdep.c:3298 kobject: 'loop4' (000000002b4feb64): fill_kobj_path: path = '/devices/virtual/block/loop4' Enabling of bearer rejected, already enabled kobject: 'loop5' (0000000077f1f8fc): kobject_uevent_env lock_acquire+0x1ed/0x520 kernel/locking/lockdep.c:3844 Enabling of bearer rejected, already enabled Enabling of bearer rejected, already enabled __raw_spin_trylock include/linux/spinlock_api_smp.h:90 [inline] _raw_spin_trylock+0x61/0x80 kernel/locking/spinlock.c:128 spin_trylock include/linux/spinlock.h:339 [inline] icmp_xmit_lock net/ipv4/icmp.c:219 [inline] icmp_send+0x59e/0x1bd0 net/ipv4/icmp.c:665 kobject: 'loop5' (0000000077f1f8fc): fill_kobj_path: path = '/devices/virtual/block/loop5' Enabling of bearer rejected, already enabled Enabling of bearer rejected, already enabled Enabling of bearer rejected, already enabled __udp4_lib_rcv+0x2484/0x32e0 net/ipv4/udp.c:2233 kobject: 'loop1' (000000009d41c739): kobject_uevent_env Enabling of bearer rejected, already enabled kobject: 'loop1' (000000009d41c739): fill_kobj_path: path = '/devices/virtual/block/loop1' udp_rcv+0x21/0x30 net/ipv4/udp.c:2392 ip_local_deliver_finish+0x2e9/0xda0 net/ipv4/ip_input.c:215 kobject: 'loop4' (000000002b4feb64): kobject_uevent_env NF_HOOK include/linux/netfilter.h:289 [inline] ip_local_deliver+0x1e9/0x750 net/ipv4/ip_input.c:256 kobject: 'loop4' (000000002b4feb64): fill_kobj_path: path = '/devices/virtual/block/loop4' kobject: 'loop0' (000000006a387ba7): kobject_uevent_env kobject: 'loop0' (000000006a387ba7): fill_kobj_path: path = '/devices/virtual/block/loop0' dst_input include/net/dst.h:450 [inline] ip_rcv_finish+0x1f9/0x300 net/ipv4/ip_input.c:415 Enabling of bearer rejected, already enabled NF_HOOK include/linux/netfilter.h:289 [inline] ip_rcv+0xed/0x600 net/ipv4/ip_input.c:524 Enabling of bearer rejected, already enabled __netif_receive_skb_one_core+0x14d/0x200 net/core/dev.c:4946 kobject: 'loop5' (0000000077f1f8fc): kobject_uevent_env __netif_receive_skb+0x2c/0x1e0 net/core/dev.c:5056 process_backlog+0x24e/0x7a0 net/core/dev.c:5864 kobject: 'loop5' (0000000077f1f8fc): fill_kobj_path: path = '/devices/virtual/block/loop5' napi_poll net/core/dev.c:6287 [inline] net_rx_action+0x7fa/0x19b0 net/core/dev.c:6353 __do_softirq+0x308/0xb7e kernel/softirq.c:292 run_ksoftirqd+0x5e/0x100 kernel/softirq.c:654 smpboot_thread_fn+0x68b/0xa00 kernel/smpboot.c:164 kthread+0x35a/0x440 kernel/kthread.c:246 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 kasan: CONFIG_KASAN_INLINE enabled kasan: GPF could be caused by NULL-ptr deref or user memory access kobject: 'loop2' (0000000094a80a8b): kobject_uevent_env general protection fault: 0000 [#1] PREEMPT SMP KASAN CPU: 0 PID: 9 Comm: ksoftirqd/0 Not tainted 4.20.0-rc6+ #150 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:__ip_append_data.isra.48+0x138a/0x29b0 net/ipv4/ip_output.c:888 Code: dc 03 00 00 e9 97 ef ff ff e8 12 b1 e0 fa 48 8b 85 48 fe ff ff 48 8d 78 3c 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 <0f> b6 14 02 48 89 f8 83 e0 07 83 c0 01 38 d0 7c 08 84 d2 0f 85 df RSP: 0018:ffff8881d9ae69c0 EFLAGS: 00010203 RAX: dffffc0000000000 RBX: dffffc0000000000 RCX: ffff8881b46b772c RDX: 0000000000000007 RSI: ffffffff869ed35e RDI: 000000000000003c RBP: ffff8881d9ae6c28 R08: ffff8881b46b75a4 R09: ffffffff86b113b0 R10: ffff8881d9ae6da0 R11: ffff8881dae2dafb R12: ffff8881b46b74dc R13: 0000000000000000 R14: 0000000000000070 R15: 0000000000000000 FS: 0000000000000000(0000) GS:ffff8881dae00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f8a69920000 CR3: 00000001b2258000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 kobject: 'loop2' (0000000094a80a8b): fill_kobj_path: path = '/devices/virtual/block/loop2' DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: kobject: 'loop3' (00000000e8f67fba): kobject_uevent_env Enabling of bearer rejected, already enabled ip_append_data.part.49+0xef/0x170 net/ipv4/ip_output.c:1197 ip_append_data+0x6d/0x90 net/ipv4/ip_output.c:1186 icmp_push_reply+0x18e/0x540 net/ipv4/icmp.c:375 icmp_send+0x1544/0x1bd0 net/ipv4/icmp.c:736 __udp4_lib_rcv+0x2484/0x32e0 net/ipv4/udp.c:2233 udp_rcv+0x21/0x30 net/ipv4/udp.c:2392 ip_local_deliver_finish+0x2e9/0xda0 net/ipv4/ip_input.c:215 NF_HOOK include/linux/netfilter.h:289 [inline] ip_local_deliver+0x1e9/0x750 net/ipv4/ip_input.c:256 dst_input include/net/dst.h:450 [inline] ip_rcv_finish+0x1f9/0x300 net/ipv4/ip_input.c:415 NF_HOOK include/linux/netfilter.h:289 [inline] ip_rcv+0xed/0x600 net/ipv4/ip_input.c:524 __netif_receive_skb_one_core+0x14d/0x200 net/core/dev.c:4946 __netif_receive_skb+0x2c/0x1e0 net/core/dev.c:5056 process_backlog+0x24e/0x7a0 net/core/dev.c:5864 napi_poll net/core/dev.c:6287 [inline] net_rx_action+0x7fa/0x19b0 net/core/dev.c:6353 __do_softirq+0x308/0xb7e kernel/softirq.c:292 run_ksoftirqd+0x5e/0x100 kernel/softirq.c:654 smpboot_thread_fn+0x68b/0xa00 kernel/smpboot.c:164 kthread+0x35a/0x440 kernel/kthread.c:246 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 Modules linked in: ---[ end trace b9edc56913d2c907 ]--- RIP: 0010:__ip_append_data.isra.48+0x138a/0x29b0 net/ipv4/ip_output.c:888 Enabling of bearer rejected, already enabled Code: dc 03 00 00 e9 97 ef ff ff e8 12 b1 e0 fa 48 8b 85 48 fe ff ff 48 8d 78 3c 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 <0f> b6 14 02 48 89 f8 83 e0 07 83 c0 01 38 d0 7c 08 84 d2 0f 85 df kobject: 'loop3' (00000000e8f67fba): fill_kobj_path: path = '/devices/virtual/block/loop3' RSP: 0018:ffff8881d9ae69c0 EFLAGS: 00010203 RAX: dffffc0000000000 RBX: dffffc0000000000 RCX: ffff8881b46b772c RDX: 0000000000000007 RSI: ffffffff869ed35e RDI: 000000000000003c RBP: ffff8881d9ae6c28 R08: ffff8881b46b75a4 R09: ffffffff86b113b0 R10: ffff8881d9ae6da0 R11: ffff8881dae2dafb R12: ffff8881b46b74dc R13: 0000000000000000 R14: 0000000000000070 R15: 0000000000000000 FS: 0000000000000000(0000) GS:ffff8881dae00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 kobject: 'loop0' (000000006a387ba7): kobject_uevent_env CR2: 00007f8a69920000 CR3: 000000000946a000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400