[ 11.856554][ T250] ip (250) used greatest stack depth: 24400 bytes left [ 11.856570][ T250] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.856572][ T250] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 250, name: ip [ 11.856574][ T250] preempt_count: 2, expected: 0 [ 11.856574][ T250] RCU nest depth: 0, expected: 0 [ 11.856576][ T250] locks held by ip/250: 5, last CPU#2: [ 11.856578][ T250] #0: ffffffff888127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 11.856589][ T250] #1: ffffffff88979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.856595][ T250] #2: ffffffff88979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.856599][ T250] #3: ffffffff88899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.856603][ T250] #4: ffffffff88899560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.856607][ T250] irq event stamp: 35218 [ 11.856608][ T250] hardirqs last enabled at (35217): [] __down_trylock_console_sem+0x86/0xa0 [ 11.856610][ T250] hardirqs last disabled at (35218): [] console_emit_next_record+0x3d4/0x4c0 [ 11.856612][ T250] softirqs last enabled at (35132): [] handle_softirqs+0x67c/0x900 [ 11.856615][ T250] softirqs last disabled at (35123): [] __irq_exit_rcu+0x145/0x1c0 [ 11.856617][ T250] Preemption disabled at: [ 11.856618][ T250] [<0000000000000000>] 0x0 [ 11.856625][ T250] CPU: 2 UID: 0 PID: 250 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.856629][ T250] Tainted: [W]=WARN [ 11.856630][ T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.856632][ T250] Call Trace: [ 11.856633][ T250] [ 11.856635][ T250] dump_stack_lvl+0x6f/0xa0 [ 11.856641][ T250] __might_resched.cold+0x1fe/0x2c1 [ 11.856646][ T250] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.856650][ T250] ? __kmalloc_noprof+0xdb/0x760 [ 11.856655][ T250] __kmalloc_noprof+0x443/0x760 [ 11.856657][ T250] ? alloc_buf.isra.0+0x4b/0x260 [ 11.856663][ T250] ? do_raw_spin_unlock+0x59/0x250 [ 11.856666][ T250] alloc_buf.isra.0+0x4b/0x260 [ 11.856669][ T250] put_chars+0x1e1/0x2f0 [ 11.856672][ T250] ? __send_to_port+0x420/0x420 [ 11.856674][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.856679][ T250] ? validate_chain+0x38b/0xc20 [ 11.856684][ T250] hvc_console_print+0x292/0x780 [ 11.856690][ T250] ? hvc_write+0x3a0/0x3a0 [ 11.856692][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.856695][ T250] ? lock_acquire+0x13c/0x160 [ 11.856699][ T250] console_emit_next_record+0x22f/0x4c0 [ 11.856703][ T250] ? devkmsg_read+0x4b0/0x4b0 [ 11.856704][ T250] ? console_flush_one_record+0x106/0x710 [ 11.856707][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.856710][ T250] ? lock_acquire+0x13c/0x160 [ 11.856714][ T250] console_flush_one_record+0x46f/0x710 [ 11.856718][ T250] ? console_emit_next_record+0x4c0/0x4c0 [ 11.856719][ T250] ? __lock_acquire+0x518/0xc20 [ 11.856724][ T250] console_unlock+0xee/0x1f0 [ 11.856727][ T250] ? console_flush_one_record+0x710/0x710 [ 11.856729][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.856731][ T250] ? lock_acquire+0x60/0x160 [ 11.856735][ T250] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.856736][ T250] ? vprintk_emit+0x320/0x3e0 [ 11.856740][ T250] vprintk_emit+0x37c/0x3e0 [ 11.856743][ T250] ? wake_up_klogd_work_func+0x90/0x90 [ 11.856747][ T250] ? __lock_acquire+0x518/0xc20 [ 11.856750][ T250] _printk+0xc7/0x100 [ 11.856754][ T250] ? snapshot_read.cold+0x21/0x21 [ 11.856757][ T250] ? do_raw_spin_lock+0x131/0x280 [ 11.856759][ T250] ? __rwlock_init+0x150/0x150 [ 11.856763][ T250] ? do_raw_spin_lock+0x131/0x280 [ 11.856766][ T250] do_exit.cold+0x82/0x9c [ 11.856770][ T250] ? exit_notify+0x890/0x890 [ 11.856771][ T250] ? __lock_release.isra.0+0x69/0x1a0 [ 11.856774][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.856778][ T250] do_group_exit+0xb8/0x370 [ 11.856781][ T250] __x64_sys_exit_group+0x3c/0x50 [ 11.856782][ T250] x64_sys_call+0x1567/0x1570 [ 11.856785][ T250] do_syscall_64+0xff/0x530 [ 11.856789][ T250] ? exc_page_fault+0xee/0x100 [ 11.856792][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.856795][ T250] RIP: 0033:0x7f0ca580f1b8 [ 11.856797][ T250] Code: Unable to access opcode bytes at 0x7f0ca580f18e. [ 11.856798][ T250] RSP: 002b:00007ffd95477008 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 11.856800][ T250] RAX: ffffffffffffffda RBX: 00007f0ca593ff88 RCX: 00007f0ca580f1b8 [ 11.856802][ T250] RDX: 00007f0ca5559fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 11.856803][ T250] RBP: 00007ffd95477060 R08: 0000000000000000 R09: 0000000000000000 [ 11.856804][ T250] R10: 00007ffd95476e20 R11: 0000000000000246 R12: 0000000000000001 [ 11.856804][ T250] R13: 0000000000000000 R14: 00007f0ca593e680 R15: 00007f0ca593ffa0 [ 11.856811][ T250] [ 12.947431][ T282] ip (282) used greatest stack depth: 24352 bytes left [ 12.947452][ T282] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.947455][ T282] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 282, name: ip [ 12.947457][ T282] preempt_count: 2, expected: 0 [ 12.947457][ T282] RCU nest depth: 0, expected: 0 [ 12.947459][ T282] locks held by ip/282: 5, last CPU#2: [ 12.947461][ T282] #0: ffffffff888127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 12.947477][ T282] #1: ffffffff88979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.947484][ T282] #2: ffffffff88979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.947488][ T282] #3: ffffffff88899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.947492][ T282] #4: ffffffff88899560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.947496][ T282] irq event stamp: 25280 [ 12.947497][ T282] hardirqs last enabled at (25279): [] __down_trylock_console_sem+0x86/0xa0 [ 12.947499][ T282] hardirqs last disabled at (25280): [] console_emit_next_record+0x3d4/0x4c0 [ 12.947501][ T282] softirqs last enabled at (25226): [] handle_softirqs+0x67c/0x900 [ 12.947504][ T282] softirqs last disabled at (25221): [] __irq_exit_rcu+0x145/0x1c0 [ 12.947506][ T282] Preemption disabled at: [ 12.947507][ T282] [<0000000000000000>] 0x0 [ 12.947514][ T282] CPU: 2 UID: 0 PID: 282 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.947517][ T282] Tainted: [W]=WARN [ 12.947518][ T282] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.947520][ T282] Call Trace: [ 12.947522][ T282] [ 12.947524][ T282] dump_stack_lvl+0x6f/0xa0 [ 12.947530][ T282] __might_resched.cold+0x1fe/0x2c1 [ 12.947534][ T282] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.947538][ T282] ? __kmalloc_noprof+0xdb/0x760 [ 12.947543][ T282] __kmalloc_noprof+0x443/0x760 [ 12.947546][ T282] ? alloc_buf.isra.0+0x4b/0x260 [ 12.947552][ T282] ? do_raw_spin_unlock+0x59/0x250 [ 12.947555][ T282] alloc_buf.isra.0+0x4b/0x260 [ 12.947558][ T282] put_chars+0x1e1/0x2f0 [ 12.947561][ T282] ? __send_to_port+0x420/0x420 [ 12.947562][ T282] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.947567][ T282] ? validate_chain+0x38b/0xc20 [ 12.947572][ T282] hvc_console_print+0x292/0x780 [ 12.947579][ T282] ? hvc_write+0x3a0/0x3a0 [ 12.947581][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.947584][ T282] ? lock_acquire+0x13c/0x160 [ 12.947588][ T282] console_emit_next_record+0x22f/0x4c0 [ 12.947592][ T282] ? devkmsg_read+0x4b0/0x4b0 [ 12.947593][ T282] ? console_flush_one_record+0x106/0x710 [ 12.947597][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.947599][ T282] ? lock_acquire+0x13c/0x160 [ 12.947603][ T282] console_flush_one_record+0x46f/0x710 [ 12.947607][ T282] ? console_emit_next_record+0x4c0/0x4c0 [ 12.947609][ T282] ? __lock_acquire+0x518/0xc20 [ 12.947614][ T282] console_unlock+0xee/0x1f0 [ 12.947616][ T282] ? console_flush_one_record+0x710/0x710 [ 12.947618][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.947621][ T282] ? lock_acquire+0x60/0x160 [ 12.947624][ T282] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.947626][ T282] ? vprintk_emit+0x320/0x3e0 [ 12.947629][ T282] vprintk_emit+0x37c/0x3e0 [ 12.947633][ T282] ? wake_up_klogd_work_func+0x90/0x90 [ 12.947637][ T282] ? __lock_acquire+0x518/0xc20 [ 12.947640][ T282] _printk+0xc7/0x100 [ 12.947644][ T282] ? snapshot_read.cold+0x21/0x21 [ 12.947647][ T282] ? do_raw_spin_lock+0x131/0x280 [ 12.947649][ T282] ? __rwlock_init+0x150/0x150 [ 12.947653][ T282] ? do_raw_spin_lock+0x131/0x280 [ 12.947656][ T282] do_exit.cold+0x82/0x9c [ 12.947660][ T282] ? exit_notify+0x890/0x890 [ 12.947661][ T282] ? __lock_release.isra.0+0x69/0x1a0 [ 12.947664][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.947668][ T282] do_group_exit+0xb8/0x370 [ 12.947671][ T282] __x64_sys_exit_group+0x3c/0x50 [ 12.947672][ T282] x64_sys_call+0x1567/0x1570 [ 12.947675][ T282] do_syscall_64+0xff/0x530 [ 12.947679][ T282] ? exc_page_fault+0xee/0x100 [ 12.947682][ T282] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.947684][ T282] RIP: 0033:0x7f428255e1b8 [ 12.947686][ T282] Code: Unable to access opcode bytes at 0x7f428255e18e. [ 12.947687][ T282] RSP: 002b:00007fff862941a8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 12.947690][ T282] RAX: ffffffffffffffda RBX: 00007f428268ef88 RCX: 00007f428255e1b8 [ 12.947691][ T282] RDX: 00007f42822a8fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 12.947692][ T282] RBP: 00007fff86294200 R08: 0000000000000000 R09: 0000000000000050 [ 12.947693][ T282] R10: 00007fff86293fc0 R11: 0000000000000246 R12: 0000000000000001 [ 12.947694][ T282] R13: 0000000000000000 R14: 00007f428268d680 R15: 00007f428268efa0 [ 12.947700][ T282] [ 13.492729][ T299] ovpn-cli (299) used greatest stack depth: 23936 bytes left [ 19.760472][ C0] tun0: no available key for peer 2, key-id: 0 [ 19.760527][ C0] [ 19.760529][ C0] ======================================================== [ 19.760530][ C0] WARNING: possible irq lock inversion dependency detected [ 19.760533][ C0] 7.2.0-virtme #1 Tainted: G W [ 19.760535][ C0] -------------------------------------------------------- [ 19.760536][ C0] kworker/u16:1/72 just changed the state of lock: [ 19.760538][ C0] ffffffff88899660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 19.760553][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 19.760555][ C0] (fs_reclaim){+.+.}-{0:0} [ 19.760557][ C0] [ 19.760557][ C0] [ 19.760557][ C0] and interrupts could create inverse lock ordering between them. [ 19.760557][ C0] [ 19.760558][ C0] [ 19.760558][ C0] other info that might help us debug this: [ 19.760559][ C0] Possible interrupt unsafe locking scenario: [ 19.760559][ C0] [ 19.760560][ C0] CPU0 CPU1 [ 19.760561][ C0] ---- ---- [ 19.760561][ C0] lock(fs_reclaim); [ 19.760563][ C0] local_irq_disable(); [ 19.760564][ C0] lock(console_owner); [ 19.760565][ C0] lock(fs_reclaim); [ 19.760567][ C0] [ 19.760567][ C0] lock(console_owner); [ 19.760569][ C0] [ 19.760569][ C0] *** DEADLOCK *** [ 19.760569][ C0] [ 19.760569][ C0] locks held by kworker/u16:1/72: 11, last CPU#0: [ 19.760571][ C0] #0: ff11000008e41d40 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 19.760578][ C0] #1: ffa00000004d7d10 ((work_completion)(&(&ifa->dad_work)->work)){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 19.760584][ C0] #2: ffffffff890e3740 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_dad_work+0x11a/0x930 [ 19.760591][ C0] #3: ffffffff88990b00 (rcu_read_lock){....}-{1:3}, at: ndisc_send_skb+0x175/0x1520 [ 19.760597][ C0] #4: ffffffff88990b00 (rcu_read_lock){....}-{1:3}, at: ip6_output+0x11d/0x7f0 [ 19.760602][ C0] #5: ffffffff88990b00 (rcu_read_lock){....}-{1:3}, at: process_backlog+0x3f1/0x14c0 [ 19.760609][ C0] #6: ffffffff88990b00 (rcu_read_lock){....}-{1:3}, at: ip_local_deliver_finish+0x2ba/0x610 [ 19.760616][ C0] #7: ff11000010e3b818 (slock-AF_INET/1){+.-.}-{3:3}, at: tcp_v4_rcv+0x1a81/0x2b70 [ 19.760623][ C0] #8: ffffffff88990b00 (rcu_read_lock){....}-{1:3}, at: ovpn_tcp_data_ready+0x62/0x390 [ovpn] [ 19.760632][ C0] #9: ffffffff88979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 19.760638][ C0] #10: ffffffff88979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 19.760643][ C0] [ 19.760643][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 19.760650][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 19.760653][ C0] HARDIRQ-ON-W at: [ 19.760656][ C0] __lock_acquire+0x388/0xc20 [ 19.760659][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.760662][ C0] fs_reclaim_acquire+0xd5/0x120 [ 19.760665][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 19.760669][ C0] kthread_create_worker_on_node+0xea/0x210 [ 19.760672][ C0] workqueue_init+0x2a/0x680 [ 19.760676][ C0] kernel_init_freeable+0x2fe/0x630 [ 19.760680][ C0] kernel_init+0x21/0x150 [ 19.760683][ C0] ret_from_fork+0x474/0x6b0 [ 19.760687][ C0] ret_from_fork_asm+0x11/0x20 [ 19.760690][ C0] SOFTIRQ-ON-W at: [ 19.760692][ C0] __lock_acquire+0x388/0xc20 [ 19.760694][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.760696][ C0] fs_reclaim_acquire+0xd5/0x120 [ 19.760698][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 19.760700][ C0] kthread_create_worker_on_node+0xea/0x210 [ 19.760702][ C0] workqueue_init+0x2a/0x680 [ 19.760703][ C0] kernel_init_freeable+0x2fe/0x630 [ 19.760706][ C0] kernel_init+0x21/0x150 [ 19.760708][ C0] ret_from_fork+0x474/0x6b0 [ 19.760710][ C0] ret_from_fork_asm+0x11/0x20 [ 19.760712][ C0] INITIAL USE at: [ 19.760713][ C0] __lock_acquire+0x388/0xc20 [ 19.760715][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.760717][ C0] fs_reclaim_acquire+0xd5/0x120 [ 19.760719][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 19.760721][ C0] kthread_create_worker_on_node+0xea/0x210 [ 19.760723][ C0] workqueue_init+0x2a/0x680 [ 19.760725][ C0] kernel_init_freeable+0x2fe/0x630 [ 19.760727][ C0] kernel_init+0x21/0x150 [ 19.760729][ C0] ret_from_fork+0x474/0x6b0 [ 19.760731][ C0] ret_from_fork_asm+0x11/0x20 [ 19.760733][ C0] } [ 19.760733][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 19.760738][ C0] ... acquired at: [ 19.760739][ C0] __lock_acquire+0x518/0xc20 [ 19.760741][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.760743][ C0] fs_reclaim_acquire+0xd5/0x120 [ 19.760745][ C0] __kmalloc_noprof+0xd3/0x760 [ 19.760747][ C0] alloc_buf.isra.0+0x4b/0x260 [ 19.760750][ C0] put_chars+0x1e1/0x2f0 [ 19.760753][ C0] hvc_console_print+0x292/0x780 [ 19.760756][ C0] console_emit_next_record+0x22f/0x4c0 [ 19.760758][ C0] console_flush_one_record+0x46f/0x710 [ 19.760760][ C0] console_unlock+0xee/0x1f0 [ 19.760763][ C0] vprintk_emit+0x37c/0x3e0 [ 19.760765][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 19.760769][ C0] dev_printk_emit+0xb9/0xee [ 19.760771][ C0] _dev_info+0xe2/0x116 [ 19.760773][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 19.760777][ C0] cmos_do_probe+0x73b/0x98a [ 19.760779][ C0] platform_probe+0xfe/0x1f0 [ 19.760782][ C0] call_driver_probe+0x61/0x1c0 [ 19.760785][ C0] really_probe+0x199/0x760 [ 19.760787][ C0] __driver_probe_device+0x24f/0x440 [ 19.760789][ C0] driver_probe_device+0x4a/0xf0 [ 19.760792][ C0] __driver_attach+0x1b8/0x540 [ 19.760794][ C0] bus_for_each_dev+0x130/0x1e0 [ 19.760796][ C0] bus_add_driver+0x2c8/0x530 [ 19.760798][ C0] driver_register+0x1a3/0x390 [ 19.760800][ C0] __platform_driver_probe+0x13f/0x270 [ 19.760802][ C0] cmos_init+0x31/0x40 [ 19.760806][ C0] do_one_initcall+0x124/0x4f0 [ 19.760808][ C0] kernel_init_freeable+0x596/0x630 [ 19.760811][ C0] kernel_init+0x21/0x150 [ 19.760813][ C0] ret_from_fork+0x474/0x6b0 [ 19.760814][ C0] ret_from_fork_asm+0x11/0x20 [ 19.760816][ C0] [ 19.760817][ C0] -> (console_owner){..-.}-{0:0} { [ 19.760820][ C0] IN-SOFTIRQ-W at: [ 19.760821][ C0] __lock_acquire+0x388/0xc20 [ 19.760824][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.760826][ C0] console_lock_spinning_enable+0x5c/0x60 [ 19.760828][ C0] console_emit_next_record+0x1d1/0x4c0 [ 19.760830][ C0] console_flush_one_record+0x46f/0x710 [ 19.760832][ C0] console_unlock+0xee/0x1f0 [ 19.760834][ C0] vprintk_emit+0x37c/0x3e0 [ 19.760837][ C0] _printk+0xc7/0x100 [ 19.760840][ C0] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 19.760845][ C0] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 19.760849][ C0] __strp_recv+0x20f/0x1c20 [ 19.760853][ C0] __tcp_read_sock+0x1c7/0x850 [ 19.760856][ C0] strp_read_sock+0x20f/0x2b0 [ 19.760859][ C0] strp_data_ready+0x18e/0x2c0 [ 19.760861][ C0] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 19.760865][ C0] tcp_rcv_established+0x1e1d/0x3910 [ 19.760868][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 19.760871][ C0] tcp_v4_rcv+0x1caa/0x2b70 [ 19.760873][ C0] ip_protocol_deliver_rcu+0x82/0x350 [ 19.760875][ C0] ip_local_deliver_finish+0x36f/0x610 [ 19.760877][ C0] ip_local_deliver+0x184/0x4c0 [ 19.760879][ C0] ip_rcv+0xdc/0x3d0 [ 19.760881][ C0] __netif_receive_skb_one_core+0xfc/0x180 [ 19.760884][ C0] process_backlog+0x431/0x14c0 [ 19.760886][ C0] __napi_poll+0xa7/0x3b0 [ 19.760889][ C0] net_rx_action+0x513/0xf50 [ 19.760891][ C0] handle_softirqs+0x1d3/0x900 [ 19.760894][ C0] do_softirq+0xac/0xe0 [ 19.760896][ C0] __local_bh_enable_ip+0x118/0x150 [ 19.760898][ C0] __dev_queue_xmit+0x979/0x1b80 [ 19.760901][ C0] ip6_finish_output2+0x423/0x12a0 [ 19.760903][ C0] ip6_finish_output+0x646/0xda0 [ 19.760905][ C0] ip6_output+0x23f/0x7f0 [ 19.760907][ C0] ndisc_send_skb+0xba3/0x1520 [ 19.760910][ C0] addrconf_dad_completed+0x37e/0xe10 [ 19.760912][ C0] addrconf_dad_begin+0x2e1/0x540 [ 19.760914][ C0] addrconf_dad_work+0x170/0x930 [ 19.760917][ C0] process_one_work+0xe3e/0x1560 [ 19.760919][ C0] worker_thread+0x4f1/0xd60 [ 19.760922][ C0] kthread+0x367/0x460 [ 19.760924][ C0] ret_from_fork+0x474/0x6b0 [ 19.760925][ C0] ret_from_fork_asm+0x11/0x20 [ 19.760927][ C0] INITIAL USE at: [ 19.760929][ C0] } [ 19.760930][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 19.760934][ C0] ... acquired at: [ 19.760935][ C0] mark_lock+0x1d7/0xa00 [ 19.760937][ C0] mark_usage+0x42/0x170 [ 19.760939][ C0] __lock_acquire+0x388/0xc20 [ 19.760941][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.760943][ C0] console_lock_spinning_enable+0x5c/0x60 [ 19.760945][ C0] console_emit_next_record+0x1d1/0x4c0 [ 19.760948][ C0] console_flush_one_record+0x46f/0x710 [ 19.760950][ C0] console_unlock+0xee/0x1f0 [ 19.760952][ C0] vprintk_emit+0x37c/0x3e0 [ 19.760954][ C0] _printk+0xc7/0x100 [ 19.760956][ C0] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 19.760960][ C0] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 19.760964][ C0] __strp_recv+0x20f/0x1c20 [ 19.760967][ C0] __tcp_read_sock+0x1c7/0x850 [ 19.760969][ C0] strp_read_sock+0x20f/0x2b0 [ 19.760971][ C0] strp_data_ready+0x18e/0x2c0 [ 19.760973][ C0] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 19.760977][ C0] tcp_rcv_established+0x1e1d/0x3910 [ 19.760980][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 19.760982][ C0] tcp_v4_rcv+0x1caa/0x2b70 [ 19.760984][ C0] ip_protocol_deliver_rcu+0x82/0x350 [ 19.760986][ C0] ip_local_deliver_finish+0x36f/0x610 [ 19.760988][ C0] ip_local_deliver+0x184/0x4c0 [ 19.760990][ C0] ip_rcv+0xdc/0x3d0 [ 19.760992][ C0] __netif_receive_skb_one_core+0xfc/0x180 [ 19.760994][ C0] process_backlog+0x431/0x14c0 [ 19.760997][ C0] __napi_poll+0xa7/0x3b0 [ 19.760999][ C0] net_rx_action+0x513/0xf50 [ 19.761001][ C0] handle_softirqs+0x1d3/0x900 [ 19.761003][ C0] do_softirq+0xac/0xe0 [ 19.761005][ C0] __local_bh_enable_ip+0x118/0x150 [ 19.761007][ C0] __dev_queue_xmit+0x979/0x1b80 [ 19.761009][ C0] ip6_finish_output2+0x423/0x12a0 [ 19.761011][ C0] ip6_finish_output+0x646/0xda0 [ 19.761013][ C0] ip6_output+0x23f/0x7f0 [ 19.761015][ C0] ndisc_send_skb+0xba3/0x1520 [ 19.761016][ C0] addrconf_dad_completed+0x37e/0xe10 [ 19.761019][ C0] addrconf_dad_begin+0x2e1/0x540 [ 19.761021][ C0] addrconf_dad_work+0x170/0x930 [ 19.761023][ C0] process_one_work+0xe3e/0x1560 [ 19.761025][ C0] worker_thread+0x4f1/0xd60 [ 19.761027][ C0] kthread+0x367/0x460 [ 19.761029][ C0] ret_from_fork+0x474/0x6b0 [ 19.761031][ C0] ret_from_fork_asm+0x11/0x20 [ 19.761033][ C0] [ 19.761033][ C0] [ 19.761033][ C0] stack backtrace: [ 19.761037][ C0] CPU: 0 UID: 0 PID: 72 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 19.761042][ C0] Tainted: [W]=WARN [ 19.761043][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 19.761045][ C0] Workqueue: ipv6_addrconf addrconf_dad_work [ 19.761050][ C0] Call Trace: [ 19.761052][ C0] [ 19.761054][ C0] dump_stack_lvl+0x6f/0xa0 [ 19.761059][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 19.761062][ C0] mark_lock_irq+0x989/0x9c0 [ 19.761067][ C0] mark_lock+0x1d7/0xa00 [ 19.761070][ C0] mark_usage+0x42/0x170 [ 19.761072][ C0] __lock_acquire+0x388/0xc20 [ 19.761075][ C0] lock_acquire.part.0+0xd4/0x280 [ 19.761078][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 19.761081][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761085][ C0] ? lock_acquire+0x13c/0x160 [ 19.761088][ C0] console_lock_spinning_enable+0x5c/0x60 [ 19.761091][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 19.761093][ C0] console_emit_next_record+0x1d1/0x4c0 [ 19.761096][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 19.761099][ C0] ? console_flush_one_record+0x106/0x710 [ 19.761102][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761104][ C0] ? lock_acquire+0x13c/0x160 [ 19.761107][ C0] console_flush_one_record+0x46f/0x710 [ 19.761111][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 19.761113][ C0] ? __lock_acquire+0x518/0xc20 [ 19.761117][ C0] console_unlock+0xee/0x1f0 [ 19.761119][ C0] ? console_flush_one_record+0x710/0x710 [ 19.761122][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761124][ C0] ? lock_acquire+0x60/0x160 [ 19.761127][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 19.761129][ C0] ? vprintk_emit+0x320/0x3e0 [ 19.761132][ C0] vprintk_emit+0x37c/0x3e0 [ 19.761136][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 19.761139][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 19.761142][ C0] _printk+0xc7/0x100 [ 19.761145][ C0] ? snapshot_read.cold+0x21/0x21 [ 19.761147][ C0] ? ___ratelimit+0x4e7/0xa00 [ 19.761151][ C0] ? ovpn_netdev_write+0x730/0x730 [ovpn] [ 19.761158][ C0] ? idr_get_free+0x830/0x830 [ 19.761162][ C0] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 19.761167][ C0] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 19.761173][ C0] ? ovpn_tcp_write_space+0x2e0/0x2e0 [ovpn] [ 19.761177][ C0] ? __copy_skb_header+0xb7/0x510 [ 19.761181][ C0] ? ovpn_tcp_disconnect+0x10/0x10 [ovpn] [ 19.761185][ C0] ? sock_queue_err_skb+0xb0/0x680 [ 19.761188][ C0] __strp_recv+0x20f/0x1c20 [ 19.761193][ C0] __tcp_read_sock+0x1c7/0x850 [ 19.761196][ C0] ? strp_process+0xb0/0xb0 [ 19.761198][ C0] ? __tcp_transmit_skb+0x1b6e/0x3c10 [ 19.761201][ C0] ? tcp_cleanup_rbuf+0x1c0/0x1c0 [ 19.761204][ C0] ? mark_usage+0x61/0x170 [ 19.761207][ C0] strp_read_sock+0x20f/0x2b0 [ 19.761210][ C0] ? strp_stop+0x50/0x50 [ 19.761213][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761216][ C0] ? lock_acquire+0x13c/0x160 [ 19.761218][ C0] strp_data_ready+0x18e/0x2c0 [ 19.761221][ C0] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 19.761226][ C0] tcp_rcv_established+0x1e1d/0x3910 [ 19.761230][ C0] ? __tcp_check_space+0x600/0x600 [ 19.761233][ C0] ? tcp_v4_rcv+0x1a81/0x2b70 [ 19.761235][ C0] ? do_raw_spin_lock+0x131/0x280 [ 19.761239][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 19.761242][ C0] tcp_v4_rcv+0x1caa/0x2b70 [ 19.761246][ C0] ? mark_usage+0x61/0x170 [ 19.761248][ C0] ? tcp_add_backlog+0x1e30/0x1e30 [ 19.761252][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761254][ C0] ? ip_local_deliver_finish+0x2ba/0x610 [ 19.761257][ C0] ip_protocol_deliver_rcu+0x82/0x350 [ 19.761260][ C0] ? process_backlog+0x3f1/0x14c0 [ 19.761263][ C0] ip_local_deliver_finish+0x36f/0x610 [ 19.761265][ C0] ip_local_deliver+0x184/0x4c0 [ 19.761268][ C0] ? ip_local_deliver_finish+0x610/0x610 [ 19.761270][ C0] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 19.761274][ C0] ? process_backlog+0x3f1/0x14c0 [ 19.761277][ C0] ip_rcv+0xdc/0x3d0 [ 19.761279][ C0] ? ip_local_deliver+0x4c0/0x4c0 [ 19.761281][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761284][ C0] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 19.761287][ C0] ? mark_usage+0x61/0x170 [ 19.761290][ C0] ? __lock_acquire+0x518/0xc20 [ 19.761292][ C0] ? trace_raw_output_rcu_invoke_kfree_bulk_callback+0x57/0x130 [ 19.761295][ C0] __netif_receive_skb_one_core+0xfc/0x180 [ 19.761298][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761300][ C0] ? process_backlog+0x3f1/0x14c0 [ 19.761303][ C0] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 19.761306][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761310][ C0] process_backlog+0x431/0x14c0 [ 19.761314][ C0] __napi_poll+0xa7/0x3b0 [ 19.761317][ C0] net_rx_action+0x513/0xf50 [ 19.761321][ C0] ? __napi_poll+0x3b0/0x3b0 [ 19.761324][ C0] ? mark_held_locks+0x40/0x70 [ 19.761327][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 19.761329][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 19.761333][ C0] ? clockevents_program_event+0x307/0x7e0 [ 19.761337][ C0] ? mark_held_locks+0x40/0x70 [ 19.761339][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761341][ C0] ? mark_held_locks+0x40/0x70 [ 19.761344][ C0] handle_softirqs+0x1d3/0x900 [ 19.761347][ C0] ? _local_bh_enable+0xc0/0xc0 [ 19.761349][ C0] ? _local_bh_enable+0xc0/0xc0 [ 19.761352][ C0] ? __dev_queue_xmit+0x964/0x1b80 [ 19.761355][ C0] do_softirq+0xac/0xe0 [ 19.761357][ C0] [ 19.761358][ C0] [ 19.761359][ C0] __local_bh_enable_ip+0x118/0x150 [ 19.761362][ C0] __dev_queue_xmit+0x979/0x1b80 [ 19.761365][ C0] ? __lock_acquire+0x518/0xc20 [ 19.761367][ C0] ? do_ipv6_getsockopt+0x1a32/0x2e80 [ 19.761370][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 19.761372][ C0] ? find_held_lock+0x2b/0x80 [ 19.761375][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761377][ C0] ? __rwlock_init+0x150/0x150 [ 19.761380][ C0] ? mark_held_locks+0x40/0x70 [ 19.761386][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 19.761388][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761390][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761393][ C0] ? ___neigh_create+0x811/0x1070 [ 19.761397][ C0] ip6_finish_output2+0x423/0x12a0 [ 19.761400][ C0] ? ip6_dst_lookup+0x80/0x80 [ 19.761403][ C0] ? find_held_lock+0x2b/0x80 [ 19.761405][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761408][ C0] ? ip6_mtu+0x15d/0x310 [ 19.761411][ C0] ip6_finish_output+0x646/0xda0 [ 19.761414][ C0] ip6_output+0x23f/0x7f0 [ 19.761417][ C0] ? ip6_finish_output+0xda0/0xda0 [ 19.761419][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761422][ C0] ? find_held_lock+0x2b/0x80 [ 19.761424][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761426][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761429][ C0] ndisc_send_skb+0xba3/0x1520 [ 19.761432][ C0] ? ndisc_recv_na+0xea0/0xea0 [ 19.761435][ C0] ? ndisc_alloc_skb+0x2da/0x490 [ 19.761437][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761440][ C0] addrconf_dad_completed+0x37e/0xe10 [ 19.761443][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761446][ C0] ? addrconf_rs_timer+0x770/0x770 [ 19.761449][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761451][ C0] ? __rwlock_init+0x150/0x150 [ 19.761453][ C0] ? mark_held_locks+0x40/0x70 [ 19.761456][ C0] ? addrconf_dad_begin+0x2e1/0x540 [ 19.761459][ C0] addrconf_dad_begin+0x2e1/0x540 [ 19.761462][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761464][ C0] ? addrconf_dad_completed+0xe10/0xe10 [ 19.761467][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761469][ C0] ? __rwlock_init+0x150/0x150 [ 19.761471][ C0] ? mark_held_locks+0x40/0x70 [ 19.761474][ C0] addrconf_dad_work+0x170/0x930 [ 19.761478][ C0] ? addrconf_dad_begin+0x540/0x540 [ 19.761481][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761489][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761492][ C0] ? lock_acquire+0x13c/0x160 [ 19.761494][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761497][ C0] process_one_work+0xe3e/0x1560 [ 19.761501][ C0] ? __queue_delayed_work+0x470/0x470 [ 19.761504][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761509][ C0] worker_thread+0x4f1/0xd60 [ 19.761512][ C0] ? rescuer_thread+0x1340/0x1340 [ 19.761514][ C0] ? __kthread_parkme+0xbd/0x210 [ 19.761517][ C0] ? rescuer_thread+0x1340/0x1340 [ 19.761520][ C0] ? rescuer_thread+0x1340/0x1340 [ 19.761522][ C0] kthread+0x367/0x460 [ 19.761524][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 19.761527][ C0] ? kthread_affine_preferred+0x4c0/0x4c0 [ 19.761530][ C0] ret_from_fork+0x474/0x6b0 [ 19.761533][ C0] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 19.761536][ C0] ? __switch_to+0x5a3/0xe00 [ 19.761539][ C0] ? kthread_affine_preferred+0x4c0/0x4c0 [ 19.761542][ C0] ret_from_fork_asm+0x11/0x20 [ 19.761547][ C0] [ 19.761552][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 19.761554][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 72, name: kworker/u16:1 [ 19.761556][ C0] preempt_count: 103, expected: 0 [ 19.761557][ C0] RCU nest depth: 5, expected: 0 [ 19.761559][ C0] INFO: lockdep is turned off. [ 19.761560][ C0] irq event stamp: 17879 [ 19.761561][ C0] hardirqs last enabled at (17878): [] __down_trylock_console_sem+0x86/0xa0 [ 19.761564][ C0] hardirqs last disabled at (17879): [] console_emit_next_record+0x3d4/0x4c0 [ 19.761567][ C0] softirqs last enabled at (17760): [] __dev_queue_xmit+0x964/0x1b80 [ 19.761570][ C0] softirqs last disabled at (17761): [] do_softirq+0xac/0xe0 [ 19.761572][ C0] Preemption disabled at: [ 19.761573][ C0] [] __dev_queue_xmit+0x204/0x1b80 [ 19.761578][ C0] CPU: 0 UID: 0 PID: 72 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 19.761581][ C0] Tainted: [W]=WARN [ 19.761583][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 19.761584][ C0] Workqueue: ipv6_addrconf addrconf_dad_work [ 19.761587][ C0] Call Trace: [ 19.761588][ C0] [ 19.761589][ C0] dump_stack_lvl+0x6f/0xa0 [ 19.761593][ C0] ? __dev_queue_xmit+0x204/0x1b80 [ 19.761596][ C0] __might_resched.cold+0x1fe/0x2c1 [ 19.761600][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 19.761603][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761607][ C0] __kmalloc_noprof+0x443/0x760 [ 19.761610][ C0] ? __rwlock_init+0x150/0x150 [ 19.761612][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 19.761617][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 19.761619][ C0] alloc_buf.isra.0+0x4b/0x260 [ 19.761622][ C0] put_chars+0x1e1/0x2f0 [ 19.761625][ C0] ? __send_to_port+0x420/0x420 [ 19.761629][ C0] hvc_console_print+0x292/0x780 [ 19.761632][ C0] ? __lock_acquire+0x388/0xc20 [ 19.761636][ C0] ? hvc_write+0x3a0/0x3a0 [ 19.761639][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761641][ C0] ? lock_acquire+0x13c/0x160 [ 19.761644][ C0] console_emit_next_record+0x22f/0x4c0 [ 19.761648][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 19.761650][ C0] ? console_flush_one_record+0x106/0x710 [ 19.761653][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761655][ C0] ? lock_acquire+0x13c/0x160 [ 19.761658][ C0] console_flush_one_record+0x46f/0x710 [ 19.761662][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 19.761664][ C0] ? __lock_acquire+0x518/0xc20 [ 19.761668][ C0] console_unlock+0xee/0x1f0 [ 19.761671][ C0] ? console_flush_one_record+0x710/0x710 [ 19.761673][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761676][ C0] ? lock_acquire+0x60/0x160 [ 19.761678][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 19.761681][ C0] ? vprintk_emit+0x320/0x3e0 [ 19.761684][ C0] vprintk_emit+0x37c/0x3e0 [ 19.761687][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 19.761690][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 19.761693][ C0] _printk+0xc7/0x100 [ 19.761696][ C0] ? snapshot_read.cold+0x21/0x21 [ 19.761698][ C0] ? ___ratelimit+0x4e7/0xa00 [ 19.761701][ C0] ? ovpn_netdev_write+0x730/0x730 [ovpn] [ 19.761706][ C0] ? idr_get_free+0x830/0x830 [ 19.761709][ C0] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 19.761714][ C0] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 19.761719][ C0] ? ovpn_tcp_write_space+0x2e0/0x2e0 [ovpn] [ 19.761723][ C0] ? __copy_skb_header+0xb7/0x510 [ 19.761726][ C0] ? ovpn_tcp_disconnect+0x10/0x10 [ovpn] [ 19.761730][ C0] ? sock_queue_err_skb+0xb0/0x680 [ 19.761733][ C0] __strp_recv+0x20f/0x1c20 [ 19.761738][ C0] __tcp_read_sock+0x1c7/0x850 [ 19.761741][ C0] ? strp_process+0xb0/0xb0 [ 19.761744][ C0] ? __tcp_transmit_skb+0x1b6e/0x3c10 [ 19.761746][ C0] ? tcp_cleanup_rbuf+0x1c0/0x1c0 [ 19.761749][ C0] ? mark_usage+0x61/0x170 [ 19.761752][ C0] strp_read_sock+0x20f/0x2b0 [ 19.761755][ C0] ? strp_stop+0x50/0x50 [ 19.761758][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761761][ C0] ? lock_acquire+0x13c/0x160 [ 19.761763][ C0] strp_data_ready+0x18e/0x2c0 [ 19.761766][ C0] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 19.761771][ C0] tcp_rcv_established+0x1e1d/0x3910 [ 19.761775][ C0] ? __tcp_check_space+0x600/0x600 [ 19.761778][ C0] ? tcp_v4_rcv+0x1a81/0x2b70 [ 19.761781][ C0] ? do_raw_spin_lock+0x131/0x280 [ 19.761784][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 19.761787][ C0] tcp_v4_rcv+0x1caa/0x2b70 [ 19.761791][ C0] ? mark_usage+0x61/0x170 [ 19.761793][ C0] ? tcp_add_backlog+0x1e30/0x1e30 [ 19.761797][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761799][ C0] ? ip_local_deliver_finish+0x2ba/0x610 [ 19.761802][ C0] ip_protocol_deliver_rcu+0x82/0x350 [ 19.761805][ C0] ? process_backlog+0x3f1/0x14c0 [ 19.761808][ C0] ip_local_deliver_finish+0x36f/0x610 [ 19.761810][ C0] ip_local_deliver+0x184/0x4c0 [ 19.761813][ C0] ? ip_local_deliver_finish+0x610/0x610 [ 19.761816][ C0] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 19.761819][ C0] ? process_backlog+0x3f1/0x14c0 [ 19.761822][ C0] ip_rcv+0xdc/0x3d0 [ 19.761824][ C0] ? ip_local_deliver+0x4c0/0x4c0 [ 19.761827][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761829][ C0] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 19.761832][ C0] ? mark_usage+0x61/0x170 [ 19.761834][ C0] ? __lock_acquire+0x518/0xc20 [ 19.761836][ C0] ? trace_raw_output_rcu_invoke_kfree_bulk_callback+0x57/0x130 [ 19.761840][ C0] __netif_receive_skb_one_core+0xfc/0x180 [ 19.761843][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761845][ C0] ? process_backlog+0x3f1/0x14c0 [ 19.761848][ C0] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 19.761850][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761854][ C0] process_backlog+0x431/0x14c0 [ 19.761858][ C0] __napi_poll+0xa7/0x3b0 [ 19.761861][ C0] net_rx_action+0x513/0xf50 [ 19.761865][ C0] ? __napi_poll+0x3b0/0x3b0 [ 19.761868][ C0] ? mark_held_locks+0x40/0x70 [ 19.761871][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 19.761873][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 19.761877][ C0] ? clockevents_program_event+0x307/0x7e0 [ 19.761880][ C0] ? mark_held_locks+0x40/0x70 [ 19.761882][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.761884][ C0] ? mark_held_locks+0x40/0x70 [ 19.761887][ C0] handle_softirqs+0x1d3/0x900 [ 19.761890][ C0] ? _local_bh_enable+0xc0/0xc0 [ 19.761892][ C0] ? _local_bh_enable+0xc0/0xc0 [ 19.761895][ C0] ? __dev_queue_xmit+0x964/0x1b80 [ 19.761898][ C0] do_softirq+0xac/0xe0 [ 19.761900][ C0] [ 19.761901][ C0] [ 19.761902][ C0] __local_bh_enable_ip+0x118/0x150 [ 19.761904][ C0] __dev_queue_xmit+0x979/0x1b80 [ 19.761908][ C0] ? __lock_acquire+0x518/0xc20 [ 19.761910][ C0] ? do_ipv6_getsockopt+0x1a32/0x2e80 [ 19.761913][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 19.761915][ C0] ? find_held_lock+0x2b/0x80 [ 19.761918][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761920][ C0] ? __rwlock_init+0x150/0x150 [ 19.761922][ C0] ? mark_held_locks+0x40/0x70 [ 19.761925][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 19.761927][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761929][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761931][ C0] ? ___neigh_create+0x811/0x1070 [ 19.761935][ C0] ip6_finish_output2+0x423/0x12a0 [ 19.761938][ C0] ? ip6_dst_lookup+0x80/0x80 [ 19.761941][ C0] ? find_held_lock+0x2b/0x80 [ 19.761943][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761946][ C0] ? ip6_mtu+0x15d/0x310 [ 19.761949][ C0] ip6_finish_output+0x646/0xda0 [ 19.761952][ C0] ip6_output+0x23f/0x7f0 [ 19.761955][ C0] ? ip6_finish_output+0xda0/0xda0 [ 19.761957][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761959][ C0] ? find_held_lock+0x2b/0x80 [ 19.761962][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761964][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761967][ C0] ndisc_send_skb+0xba3/0x1520 [ 19.761970][ C0] ? ndisc_recv_na+0xea0/0xea0 [ 19.761974][ C0] ? ndisc_alloc_skb+0x2da/0x490 [ 19.761976][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 19.761979][ C0] addrconf_dad_completed+0x37e/0xe10 [ 19.761982][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.761984][ C0] ? addrconf_rs_timer+0x770/0x770 [ 19.761987][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.761990][ C0] ? __rwlock_init+0x150/0x150 [ 19.761992][ C0] ? mark_held_locks+0x40/0x70 [ 19.761995][ C0] ? addrconf_dad_begin+0x2e1/0x540 [ 19.761998][ C0] addrconf_dad_begin+0x2e1/0x540 [ 19.762000][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.762003][ C0] ? addrconf_dad_completed+0xe10/0xe10 [ 19.762005][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 19.762007][ C0] ? __rwlock_init+0x150/0x150 [ 19.762010][ C0] ? mark_held_locks+0x40/0x70 [ 19.762013][ C0] addrconf_dad_work+0x170/0x930 [ 19.762016][ C0] ? addrconf_dad_begin+0x540/0x540 [ 19.762019][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.762022][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.762024][ C0] ? lock_acquire+0x13c/0x160 [ 19.762026][ C0] ? rcu_is_watching+0x16/0xd0 [ 19.762030][ C0] process_one_work+0xe3e/0x1560 [ 19.762034][ C0] ? __queue_delayed_work+0x470/0x470 [ 19.762037][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 19.762041][ C0] worker_thread+0x4f1/0xd60 [ 19.762045][ C0] ? rescuer_thread+0x1340/0x1340 [ 19.762047][ C0] ? __kthread_parkme+0xbd/0x210 [ 19.762050][ C0] ? rescuer_thread+0x1340/0x1340 [ 19.762052][ C0] ? rescuer_thread+0x1340/0x1340 [ 19.762055][ C0] kthread+0x367/0x460 [ 19.762057][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 19.762059][ C0] ? kthread_affine_preferred+0x4c0/0x4c0 [ 19.762062][ C0] ret_from_fork+0x474/0x6b0 [ 19.762064][ C0] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 19.762067][ C0] ? __switch_to+0x5a3/0xe00 [ 19.762070][ C0] ? kthread_affine_preferred+0x4c0/0x4c0 [ 19.762072][ C0] ret_from_fork_asm+0x11/0x20 [ 19.762077][ C0] [ 20.145178][ C0] tun0: no available key for peer 5, key-id: 0 [ 20.243088][ C3] tun0: no available key for peer 6, key-id: 0 [ 25.977092][ T479] iperf3 (479) used greatest stack depth: 22768 bytes left [ 25.977110][ T479] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 25.977112][ T479] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 479, name: iperf3 [ 25.977114][ T479] preempt_count: 2, expected: 0 [ 25.977115][ T479] RCU nest depth: 0, expected: 0 [ 25.977116][ T479] INFO: lockdep is turned off. [ 25.977117][ T479] irq event stamp: 0 [ 25.977118][ T479] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 25.977122][ T479] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 25.977128][ T479] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 25.977130][ T479] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 25.977132][ T479] Preemption disabled at: [ 25.977133][ T479] [<0000000000000000>] 0x0 [ 25.977137][ T479] CPU: 0 UID: 0 PID: 479 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 25.977141][ T479] Tainted: [W]=WARN [ 25.977142][ T479] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 25.977144][ T479] Call Trace: [ 25.977145][ T479] [ 25.977146][ T479] dump_stack_lvl+0x6f/0xa0 [ 25.977152][ T479] __might_resched.cold+0x1fe/0x2c1 [ 25.977155][ T479] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 25.977159][ T479] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 25.977162][ T479] ? rcu_is_watching+0x16/0xd0 [ 25.977166][ T479] __kmalloc_noprof+0x443/0x760 [ 25.977170][ T479] ? __rwlock_init+0x150/0x150 [ 25.977172][ T479] ? alloc_buf.isra.0+0x4b/0x260 [ 25.977177][ T479] ? do_raw_spin_unlock+0x59/0x250 [ 25.977178][ T479] alloc_buf.isra.0+0x4b/0x260 [ 25.977180][ T479] put_chars+0x1e1/0x2f0 [ 25.977183][ T479] ? prb_final_commit+0x50/0x50 [ 25.977184][ T479] ? __send_to_port+0x420/0x420 [ 25.977186][ T479] ? printk_get_next_message+0x240/0x650 [ 25.977189][ T479] hvc_console_print+0x292/0x780 [ 25.977192][ T479] ? hvc_write+0x3a0/0x3a0 [ 25.977194][ T479] ? do_raw_spin_lock+0x131/0x280 [ 25.977195][ T479] ? vsnprintf+0x668/0x1700 [ 25.977198][ T479] ? __rwlock_init+0x150/0x150 [ 25.977200][ T479] ? rcu_is_watching+0x16/0xd0 [ 25.977202][ T479] ? lock_acquire+0x13c/0x160 [ 25.977204][ T479] console_emit_next_record+0x22f/0x4c0 [ 25.977207][ T479] ? devkmsg_read+0x4b0/0x4b0 [ 25.977209][ T479] ? rcu_is_watching+0x16/0xd0 [ 25.977210][ T479] ? rcu_is_watching+0x16/0xd0 [ 25.977212][ T479] ? rcu_is_watching+0x16/0xd0 [ 25.977214][ T479] ? lock_acquire+0x13c/0x160 [ 25.977216][ T479] ? console_flush_one_record+0x111/0x710 [ 25.977218][ T479] console_flush_one_record+0x46f/0x710 [ 25.977220][ T479] ? console_emit_next_record+0x4c0/0x4c0 [ 25.977222][ T479] console_unlock+0xee/0x1f0 [ 25.977224][ T479] ? lock_acquire+0x13c/0x160 [ 25.977226][ T479] ? console_flush_one_record+0x710/0x710 [ 25.977228][ T479] ? rcu_is_watching+0x16/0xd0 [ 25.977229][ T479] ? lock_release+0x184/0x1f0 [ 25.977231][ T479] ? lock_acquire+0x60/0x160 [ 25.977232][ T479] ? __rcu_read_lock+0x30/0x70 [ 25.977234][ T479] ? __down_trylock_console_sem+0x5e/0xa0 [ 25.977236][ T479] ? vprintk_emit+0x320/0x3e0 [ 25.977238][ T479] vprintk_emit+0x37c/0x3e0 [ 25.977240][ T479] ? wake_up_klogd_work_func+0x90/0x90 [ 25.977243][ T479] _printk+0xc7/0x100 [ 25.977246][ T479] ? snapshot_read.cold+0x21/0x21 [ 25.977247][ T479] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 25.977250][ T479] ? do_raw_spin_lock+0x131/0x280 [ 25.977252][ T479] ? __rwlock_init+0x150/0x150 [ 25.977254][ T479] ? do_raw_spin_lock+0x131/0x280 [ 25.977255][ T479] do_exit.cold+0x82/0x9c [ 25.977258][ T479] ? exit_notify+0x890/0x890 [ 25.977261][ T479] __x64_sys_exit+0x42/0x50 [ 25.977262][ T479] x64_sys_call+0x156c/0x1570 [ 25.977265][ T479] do_syscall_64+0xff/0x530 [ 25.977267][ T479] ? exc_page_fault+0xee/0x100 [ 25.977269][ T479] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 25.977271][ T479] RIP: 0033:0x7f8806481c49 [ 25.977273][ T479] Code: Unable to access opcode bytes at 0x7f8806481c1f. [ 25.977274][ T479] RSP: 002b:00007f8805c40e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 25.977277][ T479] RAX: ffffffffffffffda RBX: 00007f8805c416c0 RCX: 00007f8806481c49 [ 25.977279][ T479] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 25.977280][ T479] RBP: 00007f8805c40f70 R08: 0000000000000001 R09: 00007f8805c416c0 [ 25.977280][ T479] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f8805441000 [ 25.977281][ T479] R13: 00007ffe4a1d26c0 R14: 0000000000000002 R15: 00007ffe4a1d27c7 [ 25.977283][ T479] [ 26.142547][ T351] tun1: deleting peer with id 10, reason 4 [ 26.142908][ T386] tun0: deleting peer with id 6, reason 4 [ 26.143077][ T358] tun2: deleting peer with id 11, reason 4 [ 26.143166][ T372] tun4: deleting peer with id 13, reason 4 [ 26.143468][ T387] tun6: deleting peer with id 15, reason 4 [ 26.143548][ T365] tun3: deleting peer with id 12, reason 4 [ 26.144397][ T379] tun5: deleting peer with id 14, reason 4 [ 26.145087][ T386] tun0: deleting peer with id 5, reason 4 [ 26.154678][ T386] tun0: deleting peer with id 4, reason 4 [ 26.161636][ T386] tun0: deleting peer with id 3, reason 4 [ 26.169608][ T386] tun0: deleting peer with id 2, reason 4 [ 26.177613][ T386] tun0: deleting peer with id 1, reason 4