[ 12.423449][ T249] ip (249) used greatest stack depth: 24320 bytes left [ 12.423465][ T249] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.423466][ T249] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 249, name: ip [ 12.423468][ T249] preempt_count: 2, expected: 0 [ 12.423469][ T249] RCU nest depth: 0, expected: 0 [ 12.423470][ T249] locks held by ip/249: 5, last CPU#3: [ 12.423472][ T249] #0: ffffffffb1a127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 12.423483][ T249] #1: ffffffffb1b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.423488][ T249] #2: ffffffffb1b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.423492][ T249] #3: ffffffffb1a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.423496][ T249] #4: ffffffffb1a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.423500][ T249] irq event stamp: 34640 [ 12.423501][ T249] hardirqs last enabled at (34639): [] __down_trylock_console_sem+0x86/0xa0 [ 12.423503][ T249] hardirqs last disabled at (34640): [] console_emit_next_record+0x3d4/0x4c0 [ 12.423505][ T249] softirqs last enabled at (34394): [] handle_softirqs+0x67c/0x900 [ 12.423507][ T249] softirqs last disabled at (33877): [] __irq_exit_rcu+0x145/0x1c0 [ 12.423509][ T249] Preemption disabled at: [ 12.423510][ T249] [<0000000000000000>] 0x0 [ 12.423517][ T249] CPU: 3 UID: 0 PID: 249 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.423520][ T249] Tainted: [W]=WARN [ 12.423521][ T249] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.423529][ T249] Call Trace: [ 12.423530][ T249] [ 12.423531][ T249] dump_stack_lvl+0x6f/0xa0 [ 12.423538][ T249] __might_resched.cold+0x1fe/0x2c1 [ 12.423542][ T249] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.423546][ T249] ? __kmalloc_noprof+0xdb/0x760 [ 12.423552][ T249] __kmalloc_noprof+0x443/0x760 [ 12.423554][ T249] ? alloc_buf.isra.0+0x4b/0x260 [ 12.423560][ T249] ? do_raw_spin_unlock+0x59/0x250 [ 12.423562][ T249] alloc_buf.isra.0+0x4b/0x260 [ 12.423566][ T249] put_chars+0x1e1/0x2f0 [ 12.423569][ T249] ? __send_to_port+0x420/0x420 [ 12.423570][ T249] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.423575][ T249] ? validate_chain+0x38b/0xc20 [ 12.423580][ T249] hvc_console_print+0x292/0x780 [ 12.423587][ T249] ? hvc_write+0x3a0/0x3a0 [ 12.423589][ T249] ? rcu_is_watching+0x16/0xd0 [ 12.423592][ T249] ? lock_acquire+0x13c/0x160 [ 12.423595][ T249] console_emit_next_record+0x22f/0x4c0 [ 12.423599][ T249] ? devkmsg_read+0x4b0/0x4b0 [ 12.423601][ T249] ? console_flush_one_record+0x106/0x710 [ 12.423604][ T249] ? rcu_is_watching+0x16/0xd0 [ 12.423606][ T249] ? lock_acquire+0x13c/0x160 [ 12.423610][ T249] console_flush_one_record+0x46f/0x710 [ 12.423614][ T249] ? console_emit_next_record+0x4c0/0x4c0 [ 12.423616][ T249] ? __lock_acquire+0x518/0xc20 [ 12.423621][ T249] console_unlock+0xee/0x1f0 [ 12.423624][ T249] ? console_flush_one_record+0x710/0x710 [ 12.423625][ T249] ? rcu_is_watching+0x16/0xd0 [ 12.423628][ T249] ? lock_acquire+0x60/0x160 [ 12.423631][ T249] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.423633][ T249] ? vprintk_emit+0x320/0x3e0 [ 12.423636][ T249] vprintk_emit+0x37c/0x3e0 [ 12.423640][ T249] ? wake_up_klogd_work_func+0x90/0x90 [ 12.423643][ T249] ? __lock_acquire+0x518/0xc20 [ 12.423647][ T249] _printk+0xc7/0x100 [ 12.423650][ T249] ? snapshot_read.cold+0x21/0x21 [ 12.423653][ T249] ? do_raw_spin_lock+0x131/0x280 [ 12.423656][ T249] ? __rwlock_init+0x150/0x150 [ 12.423659][ T249] ? do_raw_spin_lock+0x131/0x280 [ 12.423662][ T249] do_exit.cold+0x82/0x9c [ 12.423666][ T249] ? exit_notify+0x890/0x890 [ 12.423667][ T249] ? __lock_release.isra.0+0x69/0x1a0 [ 12.423670][ T249] ? rcu_is_watching+0x16/0xd0 [ 12.423674][ T249] do_group_exit+0xb8/0x370 [ 12.423677][ T249] __x64_sys_exit_group+0x3c/0x50 [ 12.423678][ T249] x64_sys_call+0x1567/0x1570 [ 12.423681][ T249] do_syscall_64+0xff/0x530 [ 12.423685][ T249] ? exc_page_fault+0xee/0x100 [ 12.423688][ T249] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.423690][ T249] RIP: 0033:0x7f722ea5f1b8 [ 12.423692][ T249] Code: Unable to access opcode bytes at 0x7f722ea5f18e. [ 12.423693][ T249] RSP: 002b:00007fff34051bd8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 12.423696][ T249] RAX: ffffffffffffffda RBX: 00007f722eb8ff88 RCX: 00007f722ea5f1b8 [ 12.423697][ T249] RDX: 00007f722e7a9fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 12.423698][ T249] RBP: 00007fff34051c30 R08: 0000000000000000 R09: 0000000000000000 [ 12.423699][ T249] R10: 00007fff340519f0 R11: 0000000000000246 R12: 0000000000000001 [ 12.423700][ T249] R13: 0000000000000000 R14: 00007f722eb8e680 R15: 00007f722eb8ffa0 [ 12.423706][ T249] [ 13.442924][ T278] ip (278) used greatest stack depth: 23808 bytes left [ 13.442940][ T278] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.442942][ T278] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 278, name: ip [ 13.442944][ T278] preempt_count: 2, expected: 0 [ 13.442944][ T278] RCU nest depth: 0, expected: 0 [ 13.442945][ T278] locks held by ip/278: 5, last CPU#3: [ 13.442947][ T278] #0: ffffffffb1a127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 13.442958][ T278] #1: ffffffffb1b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.442964][ T278] #2: ffffffffb1b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.442967][ T278] #3: ffffffffb1a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.442971][ T278] #4: ffffffffb1a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.442975][ T278] irq event stamp: 31632 [ 13.442976][ T278] hardirqs last enabled at (31631): [] __down_trylock_console_sem+0x86/0xa0 [ 13.442978][ T278] hardirqs last disabled at (31632): [] console_emit_next_record+0x3d4/0x4c0 [ 13.442980][ T278] softirqs last enabled at (31580): [] handle_softirqs+0x67c/0x900 [ 13.442983][ T278] softirqs last disabled at (31575): [] __irq_exit_rcu+0x145/0x1c0 [ 13.442985][ T278] Preemption disabled at: [ 13.442986][ T278] [<0000000000000000>] 0x0 [ 13.442993][ T278] CPU: 3 UID: 0 PID: 278 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.442996][ T278] Tainted: [W]=WARN [ 13.442997][ T278] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.442999][ T278] Call Trace: [ 13.443001][ T278] [ 13.443002][ T278] dump_stack_lvl+0x6f/0xa0 [ 13.443008][ T278] __might_resched.cold+0x1fe/0x2c1 [ 13.443013][ T278] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.443017][ T278] ? __kmalloc_noprof+0xdb/0x760 [ 13.443022][ T278] __kmalloc_noprof+0x443/0x760 [ 13.443024][ T278] ? alloc_buf.isra.0+0x4b/0x260 [ 13.443030][ T278] ? do_raw_spin_unlock+0x59/0x250 [ 13.443033][ T278] alloc_buf.isra.0+0x4b/0x260 [ 13.443036][ T278] put_chars+0x1e1/0x2f0 [ 13.443039][ T278] ? __send_to_port+0x420/0x420 [ 13.443041][ T278] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.443046][ T278] ? validate_chain+0x38b/0xc20 [ 13.443051][ T278] hvc_console_print+0x292/0x780 [ 13.443057][ T278] ? hvc_write+0x3a0/0x3a0 [ 13.443059][ T278] ? rcu_is_watching+0x16/0xd0 [ 13.443062][ T278] ? lock_acquire+0x13c/0x160 [ 13.443066][ T278] console_emit_next_record+0x22f/0x4c0 [ 13.443070][ T278] ? devkmsg_read+0x4b0/0x4b0 [ 13.443071][ T278] ? console_flush_one_record+0x106/0x710 [ 13.443074][ T278] ? rcu_is_watching+0x16/0xd0 [ 13.443077][ T278] ? lock_acquire+0x13c/0x160 [ 13.443080][ T278] console_flush_one_record+0x46f/0x710 [ 13.443084][ T278] ? console_emit_next_record+0x4c0/0x4c0 [ 13.443086][ T278] ? __lock_acquire+0x518/0xc20 [ 13.443091][ T278] console_unlock+0xee/0x1f0 [ 13.443094][ T278] ? console_flush_one_record+0x710/0x710 [ 13.443095][ T278] ? rcu_is_watching+0x16/0xd0 [ 13.443098][ T278] ? lock_acquire+0x60/0x160 [ 13.443101][ T278] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.443103][ T278] ? vprintk_emit+0x320/0x3e0 [ 13.443106][ T278] vprintk_emit+0x37c/0x3e0 [ 13.443110][ T278] ? wake_up_klogd_work_func+0x90/0x90 [ 13.443113][ T278] ? __lock_acquire+0x518/0xc20 [ 13.443117][ T278] _printk+0xc7/0x100 [ 13.443120][ T278] ? snapshot_read.cold+0x21/0x21 [ 13.443123][ T278] ? do_raw_spin_lock+0x131/0x280 [ 13.443125][ T278] ? __rwlock_init+0x150/0x150 [ 13.443129][ T278] ? do_raw_spin_lock+0x131/0x280 [ 13.443132][ T278] do_exit.cold+0x82/0x9c [ 13.443135][ T278] ? exit_notify+0x890/0x890 [ 13.443136][ T278] ? __lock_release.isra.0+0x69/0x1a0 [ 13.443139][ T278] ? rcu_is_watching+0x16/0xd0 [ 13.443143][ T278] do_group_exit+0xb8/0x370 [ 13.443146][ T278] __x64_sys_exit_group+0x3c/0x50 [ 13.443148][ T278] x64_sys_call+0x1567/0x1570 [ 13.443150][ T278] do_syscall_64+0xff/0x530 [ 13.443154][ T278] ? exc_page_fault+0xee/0x100 [ 13.443157][ T278] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.443159][ T278] RIP: 0033:0x7f30655401b8 [ 13.443161][ T278] Code: Unable to access opcode bytes at 0x7f306554018e. [ 13.443162][ T278] RSP: 002b:00007ffd3b4f6448 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 13.443165][ T278] RAX: ffffffffffffffda RBX: 00007f3065670f88 RCX: 00007f30655401b8 [ 13.443166][ T278] RDX: 00007f306528afc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 13.443167][ T278] RBP: 00007ffd3b4f64a0 R08: 0000000000000000 R09: 0000000000000000 [ 13.443167][ T278] R10: 00007ffd3b4f6260 R11: 0000000000000246 R12: 0000000000000001 [ 13.443168][ T278] R13: 0000000000000000 R14: 00007f306566f680 R15: 00007f3065670fa0 [ 13.443175][ T278] [ 20.346960][ C1] tun0: no available key for peer 1, key-id: 0 [ 20.346996][ C1] [ 20.346997][ C1] ======================================================== [ 20.346998][ C1] WARNING: possible irq lock inversion dependency detected [ 20.347000][ C1] 7.2.0-virtme #1 Tainted: G W [ 20.347002][ C1] -------------------------------------------------------- [ 20.347003][ C1] kworker/u16:1/73 just changed the state of lock: [ 20.347004][ C1] ffffffffb1a99660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 20.347016][ C1] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 20.347018][ C1] (fs_reclaim){+.+.}-{0:0} [ 20.347019][ C1] [ 20.347019][ C1] [ 20.347019][ C1] and interrupts could create inverse lock ordering between them. [ 20.347019][ C1] [ 20.347020][ C1] [ 20.347020][ C1] other info that might help us debug this: [ 20.347020][ C1] Possible interrupt unsafe locking scenario: [ 20.347020][ C1] [ 20.347021][ C1] CPU0 CPU1 [ 20.347021][ C1] ---- ---- [ 20.347022][ C1] lock(fs_reclaim); [ 20.347023][ C1] local_irq_disable(); [ 20.347023][ C1] lock(console_owner); [ 20.347024][ C1] lock(fs_reclaim); [ 20.347025][ C1] [ 20.347025][ C1] lock(console_owner); [ 20.347026][ C1] [ 20.347026][ C1] *** DEADLOCK *** [ 20.347026][ C1] [ 20.347027][ C1] locks held by kworker/u16:1/73: 11, last CPU#1: [ 20.347028][ C1] #0: ff11000008d71d40 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 20.347033][ C1] #1: ffa00000004e7d10 ((work_completion)(&(&ifa->dad_work)->work)){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 20.347036][ C1] #2: ffffffffb22e3740 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_dad_work+0x11a/0x930 [ 20.347042][ C1] #3: ffffffffb1b90b00 (rcu_read_lock){....}-{1:3}, at: ndisc_send_skb+0x175/0x1520 [ 20.347045][ C1] #4: ffffffffb1b90b00 (rcu_read_lock){....}-{1:3}, at: ip6_output+0x11d/0x7f0 [ 20.347048][ C1] #5: ffffffffb1b90b00 (rcu_read_lock){....}-{1:3}, at: process_backlog+0x3f1/0x14c0 [ 20.347054][ C1] #6: ffffffffb1b90b00 (rcu_read_lock){....}-{1:3}, at: ip_local_deliver_finish+0x2ba/0x610 [ 20.347058][ C1] #7: ff11000004e71d18 (slock-AF_INET/1){+.-.}-{3:3}, at: tcp_v4_rcv+0x1a81/0x2b70 [ 20.347063][ C1] #8: ffffffffb1b90b00 (rcu_read_lock){....}-{1:3}, at: ovpn_tcp_data_ready+0x62/0x390 [ovpn] [ 20.347070][ C1] #9: ffffffffb1b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 20.347073][ C1] #10: ffffffffb1b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 20.347076][ C1] [ 20.347076][ C1] the shortest dependencies between 2nd lock and 1st lock: [ 20.347082][ C1] -> (fs_reclaim){+.+.}-{0:0} { [ 20.347084][ C1] HARDIRQ-ON-W at: [ 20.347085][ C1] __lock_acquire+0x388/0xc20 [ 20.347088][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347089][ C1] fs_reclaim_acquire+0xd5/0x120 [ 20.347092][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 20.347095][ C1] kthread_create_worker_on_node+0xea/0x210 [ 20.347097][ C1] workqueue_init+0x2a/0x680 [ 20.347101][ C1] kernel_init_freeable+0x2fe/0x630 [ 20.347104][ C1] kernel_init+0x21/0x150 [ 20.347106][ C1] ret_from_fork+0x474/0x6b0 [ 20.347110][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347113][ C1] SOFTIRQ-ON-W at: [ 20.347114][ C1] __lock_acquire+0x388/0xc20 [ 20.347116][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347118][ C1] fs_reclaim_acquire+0xd5/0x120 [ 20.347120][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 20.347122][ C1] kthread_create_worker_on_node+0xea/0x210 [ 20.347124][ C1] workqueue_init+0x2a/0x680 [ 20.347125][ C1] kernel_init_freeable+0x2fe/0x630 [ 20.347127][ C1] kernel_init+0x21/0x150 [ 20.347129][ C1] ret_from_fork+0x474/0x6b0 [ 20.347131][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347133][ C1] INITIAL USE at: [ 20.347134][ C1] __lock_acquire+0x388/0xc20 [ 20.347136][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347137][ C1] fs_reclaim_acquire+0xd5/0x120 [ 20.347138][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 20.347140][ C1] kthread_create_worker_on_node+0xea/0x210 [ 20.347141][ C1] workqueue_init+0x2a/0x680 [ 20.347142][ C1] kernel_init_freeable+0x2fe/0x630 [ 20.347144][ C1] kernel_init+0x21/0x150 [ 20.347145][ C1] ret_from_fork+0x474/0x6b0 [ 20.347146][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347148][ C1] } [ 20.347148][ C1] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 20.347152][ C1] ... acquired at: [ 20.347153][ C1] __lock_acquire+0x518/0xc20 [ 20.347154][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347155][ C1] fs_reclaim_acquire+0xd5/0x120 [ 20.347156][ C1] __kmalloc_noprof+0xd3/0x760 [ 20.347158][ C1] alloc_buf.isra.0+0x4b/0x260 [ 20.347161][ C1] put_chars+0x1e1/0x2f0 [ 20.347162][ C1] hvc_console_print+0x292/0x780 [ 20.347165][ C1] console_emit_next_record+0x22f/0x4c0 [ 20.347166][ C1] console_flush_one_record+0x46f/0x710 [ 20.347168][ C1] console_unlock+0xee/0x1f0 [ 20.347169][ C1] vprintk_emit+0x37c/0x3e0 [ 20.347171][ C1] _printk+0xc7/0x100 [ 20.347174][ C1] loop_init+0x12a/0x130 [ 20.347176][ C1] do_one_initcall+0x124/0x4f0 [ 20.347178][ C1] kernel_init_freeable+0x596/0x630 [ 20.347180][ C1] kernel_init+0x21/0x150 [ 20.347181][ C1] ret_from_fork+0x474/0x6b0 [ 20.347182][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347183][ C1] [ 20.347183][ C1] -> (console_owner){..-.}-{0:0} { [ 20.347185][ C1] IN-SOFTIRQ-W at: [ 20.347186][ C1] __lock_acquire+0x388/0xc20 [ 20.347188][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347189][ C1] console_lock_spinning_enable+0x5c/0x60 [ 20.347191][ C1] console_emit_next_record+0x1d1/0x4c0 [ 20.347192][ C1] console_flush_one_record+0x46f/0x710 [ 20.347194][ C1] console_unlock+0xee/0x1f0 [ 20.347195][ C1] vprintk_emit+0x37c/0x3e0 [ 20.347197][ C1] _printk+0xc7/0x100 [ 20.347198][ C1] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 20.347202][ C1] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 20.347204][ C1] __strp_recv+0x20f/0x1c20 [ 20.347207][ C1] __tcp_read_sock+0x1c7/0x850 [ 20.347209][ C1] strp_read_sock+0x20f/0x2b0 [ 20.347211][ C1] strp_data_ready+0x18e/0x2c0 [ 20.347213][ C1] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 20.347215][ C1] tcp_rcv_established+0x1e1d/0x3910 [ 20.347218][ C1] tcp_v4_do_rcv+0x4f6/0xb90 [ 20.347219][ C1] tcp_v4_rcv+0x1caa/0x2b70 [ 20.347220][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 20.347222][ C1] ip_local_deliver_finish+0x36f/0x610 [ 20.347223][ C1] ip_local_deliver+0x184/0x4c0 [ 20.347225][ C1] ip_rcv+0xdc/0x3d0 [ 20.347226][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 20.347228][ C1] process_backlog+0x431/0x14c0 [ 20.347230][ C1] __napi_poll+0xa7/0x3b0 [ 20.347231][ C1] net_rx_action+0x513/0xf50 [ 20.347233][ C1] handle_softirqs+0x1d3/0x900 [ 20.347235][ C1] do_softirq+0xac/0xe0 [ 20.347237][ C1] __local_bh_enable_ip+0x118/0x150 [ 20.347238][ C1] __dev_queue_xmit+0x979/0x1b80 [ 20.347240][ C1] ip6_finish_output2+0x423/0x12a0 [ 20.347241][ C1] ip6_finish_output+0x646/0xda0 [ 20.347243][ C1] ip6_output+0x23f/0x7f0 [ 20.347244][ C1] ndisc_send_skb+0xba3/0x1520 [ 20.347245][ C1] addrconf_dad_completed+0x37e/0xe10 [ 20.347247][ C1] addrconf_dad_begin+0x2e1/0x540 [ 20.347249][ C1] addrconf_dad_work+0x170/0x930 [ 20.347250][ C1] process_one_work+0xe3e/0x1560 [ 20.347252][ C1] worker_thread+0x4f1/0xd60 [ 20.347253][ C1] kthread+0x367/0x460 [ 20.347255][ C1] ret_from_fork+0x474/0x6b0 [ 20.347256][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347258][ C1] INITIAL USE at: [ 20.347258][ C1] } [ 20.347259][ C1] ... key at: [] console_owner_dep_map+0x0/0x60 [ 20.347262][ C1] ... acquired at: [ 20.347263][ C1] mark_lock+0x1d7/0xa00 [ 20.347265][ C1] mark_usage+0x42/0x170 [ 20.347267][ C1] __lock_acquire+0x388/0xc20 [ 20.347268][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347270][ C1] console_lock_spinning_enable+0x5c/0x60 [ 20.347273][ C1] console_emit_next_record+0x1d1/0x4c0 [ 20.347275][ C1] console_flush_one_record+0x46f/0x710 [ 20.347276][ C1] console_unlock+0xee/0x1f0 [ 20.347278][ C1] vprintk_emit+0x37c/0x3e0 [ 20.347279][ C1] _printk+0xc7/0x100 [ 20.347281][ C1] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 20.347283][ C1] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 20.347285][ C1] __strp_recv+0x20f/0x1c20 [ 20.347287][ C1] __tcp_read_sock+0x1c7/0x850 [ 20.347288][ C1] strp_read_sock+0x20f/0x2b0 [ 20.347290][ C1] strp_data_ready+0x18e/0x2c0 [ 20.347291][ C1] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 20.347294][ C1] tcp_rcv_established+0x1e1d/0x3910 [ 20.347295][ C1] tcp_v4_do_rcv+0x4f6/0xb90 [ 20.347297][ C1] tcp_v4_rcv+0x1caa/0x2b70 [ 20.347298][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 20.347299][ C1] ip_local_deliver_finish+0x36f/0x610 [ 20.347301][ C1] ip_local_deliver+0x184/0x4c0 [ 20.347302][ C1] ip_rcv+0xdc/0x3d0 [ 20.347303][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 20.347305][ C1] process_backlog+0x431/0x14c0 [ 20.347306][ C1] __napi_poll+0xa7/0x3b0 [ 20.347308][ C1] net_rx_action+0x513/0xf50 [ 20.347310][ C1] handle_softirqs+0x1d3/0x900 [ 20.347311][ C1] do_softirq+0xac/0xe0 [ 20.347312][ C1] __local_bh_enable_ip+0x118/0x150 [ 20.347313][ C1] __dev_queue_xmit+0x979/0x1b80 [ 20.347315][ C1] ip6_finish_output2+0x423/0x12a0 [ 20.347316][ C1] ip6_finish_output+0x646/0xda0 [ 20.347317][ C1] ip6_output+0x23f/0x7f0 [ 20.347319][ C1] ndisc_send_skb+0xba3/0x1520 [ 20.347320][ C1] addrconf_dad_completed+0x37e/0xe10 [ 20.347321][ C1] addrconf_dad_begin+0x2e1/0x540 [ 20.347323][ C1] addrconf_dad_work+0x170/0x930 [ 20.347325][ C1] process_one_work+0xe3e/0x1560 [ 20.347326][ C1] worker_thread+0x4f1/0xd60 [ 20.347327][ C1] kthread+0x367/0x460 [ 20.347329][ C1] ret_from_fork+0x474/0x6b0 [ 20.347330][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347331][ C1] [ 20.347331][ C1] [ 20.347331][ C1] stack backtrace: [ 20.347334][ C1] CPU: 1 UID: 0 PID: 73 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 20.347337][ C1] Tainted: [W]=WARN [ 20.347338][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.347340][ C1] Workqueue: ipv6_addrconf addrconf_dad_work [ 20.347343][ C1] Call Trace: [ 20.347344][ C1] [ 20.347346][ C1] dump_stack_lvl+0x6f/0xa0 [ 20.347350][ C1] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 20.347352][ C1] mark_lock_irq+0x989/0x9c0 [ 20.347355][ C1] mark_lock+0x1d7/0xa00 [ 20.347357][ C1] mark_usage+0x42/0x170 [ 20.347358][ C1] __lock_acquire+0x388/0xc20 [ 20.347360][ C1] lock_acquire.part.0+0xd4/0x280 [ 20.347362][ C1] ? console_lock_spinning_enable+0x40/0x60 [ 20.347364][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347368][ C1] ? lock_acquire+0x13c/0x160 [ 20.347370][ C1] console_lock_spinning_enable+0x5c/0x60 [ 20.347371][ C1] ? console_lock_spinning_enable+0x40/0x60 [ 20.347373][ C1] console_emit_next_record+0x1d1/0x4c0 [ 20.347375][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 20.347377][ C1] ? console_flush_one_record+0x106/0x710 [ 20.347379][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347381][ C1] ? lock_acquire+0x13c/0x160 [ 20.347383][ C1] console_flush_one_record+0x46f/0x710 [ 20.347385][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 20.347387][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347389][ C1] console_unlock+0xee/0x1f0 [ 20.347391][ C1] ? console_flush_one_record+0x710/0x710 [ 20.347392][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347394][ C1] ? lock_acquire+0x60/0x160 [ 20.347396][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 20.347398][ C1] ? vprintk_emit+0x320/0x3e0 [ 20.347400][ C1] vprintk_emit+0x37c/0x3e0 [ 20.347402][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 20.347404][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347406][ C1] _printk+0xc7/0x100 [ 20.347408][ C1] ? snapshot_read.cold+0x21/0x21 [ 20.347409][ C1] ? ___ratelimit+0x4e7/0xa00 [ 20.347413][ C1] ? ovpn_netdev_write+0x730/0x730 [ovpn] [ 20.347416][ C1] ? idr_get_free+0x830/0x830 [ 20.347418][ C1] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 20.347422][ C1] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 20.347425][ C1] ? ovpn_tcp_write_space+0x2e0/0x2e0 [ovpn] [ 20.347427][ C1] ? __copy_skb_header+0xb7/0x510 [ 20.347430][ C1] ? ovpn_tcp_disconnect+0x10/0x10 [ovpn] [ 20.347433][ C1] ? sock_queue_err_skb+0xb0/0x680 [ 20.347435][ C1] __strp_recv+0x20f/0x1c20 [ 20.347438][ C1] __tcp_read_sock+0x1c7/0x850 [ 20.347440][ C1] ? strp_process+0xb0/0xb0 [ 20.347442][ C1] ? __tcp_transmit_skb+0x1b6e/0x3c10 [ 20.347443][ C1] ? tcp_cleanup_rbuf+0x1c0/0x1c0 [ 20.347445][ C1] ? mark_usage+0x61/0x170 [ 20.347447][ C1] strp_read_sock+0x20f/0x2b0 [ 20.347449][ C1] ? strp_stop+0x50/0x50 [ 20.347451][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347453][ C1] ? lock_acquire+0x13c/0x160 [ 20.347455][ C1] strp_data_ready+0x18e/0x2c0 [ 20.347457][ C1] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 20.347461][ C1] tcp_rcv_established+0x1e1d/0x3910 [ 20.347466][ C1] ? __tcp_check_space+0x600/0x600 [ 20.347468][ C1] ? tcp_v4_rcv+0x1a81/0x2b70 [ 20.347470][ C1] ? do_raw_spin_lock+0x131/0x280 [ 20.347472][ C1] tcp_v4_do_rcv+0x4f6/0xb90 [ 20.347474][ C1] tcp_v4_rcv+0x1caa/0x2b70 [ 20.347477][ C1] ? mark_usage+0x61/0x170 [ 20.347478][ C1] ? tcp_add_backlog+0x1e30/0x1e30 [ 20.347480][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347482][ C1] ? ip_local_deliver_finish+0x2ba/0x610 [ 20.347484][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 20.347486][ C1] ? process_backlog+0x3f1/0x14c0 [ 20.347488][ C1] ip_local_deliver_finish+0x36f/0x610 [ 20.347489][ C1] ip_local_deliver+0x184/0x4c0 [ 20.347491][ C1] ? ip_local_deliver_finish+0x610/0x610 [ 20.347493][ C1] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 20.347495][ C1] ? process_backlog+0x3f1/0x14c0 [ 20.347497][ C1] ip_rcv+0xdc/0x3d0 [ 20.347499][ C1] ? ip_local_deliver+0x4c0/0x4c0 [ 20.347500][ C1] ? mark_usage+0x61/0x170 [ 20.347502][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347504][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 20.347506][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347507][ C1] ? process_backlog+0x3f1/0x14c0 [ 20.347509][ C1] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 20.347511][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347514][ C1] process_backlog+0x431/0x14c0 [ 20.347516][ C1] ? rcu_read_lock_any_held+0x3c/0x90 [ 20.347518][ C1] __napi_poll+0xa7/0x3b0 [ 20.347520][ C1] net_rx_action+0x513/0xf50 [ 20.347523][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347524][ C1] ? __napi_poll+0x3b0/0x3b0 [ 20.347534][ C1] ? ktime_get_update_offsets_now+0x2a8/0x490 [ 20.347538][ C1] ? mark_held_locks+0x40/0x70 [ 20.347540][ C1] handle_softirqs+0x1d3/0x900 [ 20.347542][ C1] ? _local_bh_enable+0xc0/0xc0 [ 20.347543][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 20.347545][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347547][ C1] ? __dev_queue_xmit+0x964/0x1b80 [ 20.347549][ C1] do_softirq+0xac/0xe0 [ 20.347550][ C1] [ 20.347551][ C1] [ 20.347552][ C1] __local_bh_enable_ip+0x118/0x150 [ 20.347553][ C1] __dev_queue_xmit+0x979/0x1b80 [ 20.347555][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347557][ C1] ? do_ipv6_getsockopt+0x1935/0x2e80 [ 20.347559][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 20.347561][ C1] ? find_held_lock+0x2b/0x80 [ 20.347562][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347564][ C1] ? __rwlock_init+0x150/0x150 [ 20.347565][ C1] ? mark_held_locks+0x40/0x70 [ 20.347567][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 20.347569][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347570][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347571][ C1] ? ___neigh_create+0x811/0x1070 [ 20.347574][ C1] ip6_finish_output2+0x423/0x12a0 [ 20.347577][ C1] ? ip6_dst_lookup+0x80/0x80 [ 20.347578][ C1] ? find_held_lock+0x2b/0x80 [ 20.347580][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347581][ C1] ? ip6_mtu+0x15d/0x310 [ 20.347584][ C1] ip6_finish_output+0x646/0xda0 [ 20.347586][ C1] ip6_output+0x23f/0x7f0 [ 20.347588][ C1] ? ip6_finish_output+0xda0/0xda0 [ 20.347589][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347591][ C1] ? find_held_lock+0x2b/0x80 [ 20.347592][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347593][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347595][ C1] ndisc_send_skb+0xba3/0x1520 [ 20.347597][ C1] ? ndisc_recv_na+0xea0/0xea0 [ 20.347600][ C1] ? ndisc_alloc_skb+0x2da/0x490 [ 20.347601][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347603][ C1] addrconf_dad_completed+0x37e/0xe10 [ 20.347605][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347606][ C1] ? addrconf_rs_timer+0x770/0x770 [ 20.347608][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347610][ C1] ? __rwlock_init+0x150/0x150 [ 20.347611][ C1] ? mark_held_locks+0x40/0x70 [ 20.347613][ C1] ? addrconf_dad_begin+0x2e1/0x540 [ 20.347615][ C1] addrconf_dad_begin+0x2e1/0x540 [ 20.347617][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347619][ C1] ? addrconf_dad_completed+0xe10/0xe10 [ 20.347621][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347622][ C1] ? __rwlock_init+0x150/0x150 [ 20.347623][ C1] ? mark_held_locks+0x40/0x70 [ 20.347625][ C1] addrconf_dad_work+0x170/0x930 [ 20.347628][ C1] ? addrconf_dad_begin+0x540/0x540 [ 20.347630][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347632][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347633][ C1] ? lock_acquire+0x13c/0x160 [ 20.347635][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347637][ C1] process_one_work+0xe3e/0x1560 [ 20.347640][ C1] ? __queue_delayed_work+0x470/0x470 [ 20.347642][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347645][ C1] worker_thread+0x4f1/0xd60 [ 20.347647][ C1] ? rescuer_thread+0x1340/0x1340 [ 20.347648][ C1] ? __kthread_parkme+0xbd/0x210 [ 20.347650][ C1] ? rescuer_thread+0x1340/0x1340 [ 20.347652][ C1] ? rescuer_thread+0x1340/0x1340 [ 20.347653][ C1] kthread+0x367/0x460 [ 20.347655][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 20.347657][ C1] ? kthread_affine_preferred+0x4c0/0x4c0 [ 20.347659][ C1] ret_from_fork+0x474/0x6b0 [ 20.347661][ C1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 20.347662][ C1] ? __switch_to+0x5a3/0xe00 [ 20.347665][ C1] ? kthread_affine_preferred+0x4c0/0x4c0 [ 20.347667][ C1] ret_from_fork_asm+0x11/0x20 [ 20.347670][ C1] [ 20.347674][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 20.347675][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 73, name: kworker/u16:1 [ 20.347676][ C1] preempt_count: 103, expected: 0 [ 20.347677][ C1] RCU nest depth: 5, expected: 0 [ 20.347678][ C1] INFO: lockdep is turned off. [ 20.347678][ C1] irq event stamp: 13531 [ 20.347679][ C1] hardirqs last enabled at (13530): [] __down_trylock_console_sem+0x86/0xa0 [ 20.347681][ C1] hardirqs last disabled at (13531): [] console_emit_next_record+0x3d4/0x4c0 [ 20.347683][ C1] softirqs last enabled at (13412): [] __dev_queue_xmit+0x964/0x1b80 [ 20.347685][ C1] softirqs last disabled at (13413): [] do_softirq+0xac/0xe0 [ 20.347687][ C1] Preemption disabled at: [ 20.347687][ C1] [] __dev_queue_xmit+0x204/0x1b80 [ 20.347690][ C1] CPU: 1 UID: 0 PID: 73 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 20.347692][ C1] Tainted: [W]=WARN [ 20.347693][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.347693][ C1] Workqueue: ipv6_addrconf addrconf_dad_work [ 20.347695][ C1] Call Trace: [ 20.347696][ C1] [ 20.347697][ C1] dump_stack_lvl+0x6f/0xa0 [ 20.347699][ C1] ? __dev_queue_xmit+0x204/0x1b80 [ 20.347701][ C1] __might_resched.cold+0x1fe/0x2c1 [ 20.347703][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 20.347706][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347709][ C1] __kmalloc_noprof+0x443/0x760 [ 20.347710][ C1] ? __rwlock_init+0x150/0x150 [ 20.347712][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 20.347714][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 20.347715][ C1] alloc_buf.isra.0+0x4b/0x260 [ 20.347718][ C1] put_chars+0x1e1/0x2f0 [ 20.347720][ C1] ? __send_to_port+0x420/0x420 [ 20.347722][ C1] hvc_console_print+0x292/0x780 [ 20.347724][ C1] ? __lock_acquire+0x388/0xc20 [ 20.347726][ C1] ? hvc_write+0x3a0/0x3a0 [ 20.347728][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347730][ C1] ? lock_acquire+0x13c/0x160 [ 20.347731][ C1] console_emit_next_record+0x22f/0x4c0 [ 20.347734][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 20.347735][ C1] ? console_flush_one_record+0x106/0x710 [ 20.347737][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347739][ C1] ? lock_acquire+0x13c/0x160 [ 20.347741][ C1] console_flush_one_record+0x46f/0x710 [ 20.347743][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 20.347745][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347747][ C1] console_unlock+0xee/0x1f0 [ 20.347749][ C1] ? console_flush_one_record+0x710/0x710 [ 20.347750][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347752][ C1] ? lock_acquire+0x60/0x160 [ 20.347754][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 20.347755][ C1] ? vprintk_emit+0x320/0x3e0 [ 20.347757][ C1] vprintk_emit+0x37c/0x3e0 [ 20.347760][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 20.347761][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347764][ C1] _printk+0xc7/0x100 [ 20.347765][ C1] ? snapshot_read.cold+0x21/0x21 [ 20.347767][ C1] ? ___ratelimit+0x4e7/0xa00 [ 20.347769][ C1] ? ovpn_netdev_write+0x730/0x730 [ovpn] [ 20.347771][ C1] ? idr_get_free+0x830/0x830 [ 20.347773][ C1] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 20.347776][ C1] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 20.347779][ C1] ? ovpn_tcp_write_space+0x2e0/0x2e0 [ovpn] [ 20.347781][ C1] ? __copy_skb_header+0xb7/0x510 [ 20.347783][ C1] ? ovpn_tcp_disconnect+0x10/0x10 [ovpn] [ 20.347786][ C1] ? sock_queue_err_skb+0xb0/0x680 [ 20.347788][ C1] __strp_recv+0x20f/0x1c20 [ 20.347791][ C1] __tcp_read_sock+0x1c7/0x850 [ 20.347793][ C1] ? strp_process+0xb0/0xb0 [ 20.347795][ C1] ? __tcp_transmit_skb+0x1b6e/0x3c10 [ 20.347796][ C1] ? tcp_cleanup_rbuf+0x1c0/0x1c0 [ 20.347798][ C1] ? mark_usage+0x61/0x170 [ 20.347799][ C1] strp_read_sock+0x20f/0x2b0 [ 20.347802][ C1] ? strp_stop+0x50/0x50 [ 20.347804][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347806][ C1] ? lock_acquire+0x13c/0x160 [ 20.347807][ C1] strp_data_ready+0x18e/0x2c0 [ 20.347809][ C1] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 20.347812][ C1] tcp_rcv_established+0x1e1d/0x3910 [ 20.347815][ C1] ? __tcp_check_space+0x600/0x600 [ 20.347817][ C1] ? tcp_v4_rcv+0x1a81/0x2b70 [ 20.347818][ C1] ? do_raw_spin_lock+0x131/0x280 [ 20.347820][ C1] tcp_v4_do_rcv+0x4f6/0xb90 [ 20.347822][ C1] tcp_v4_rcv+0x1caa/0x2b70 [ 20.347825][ C1] ? mark_usage+0x61/0x170 [ 20.347826][ C1] ? tcp_add_backlog+0x1e30/0x1e30 [ 20.347829][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347830][ C1] ? ip_local_deliver_finish+0x2ba/0x610 [ 20.347832][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 20.347834][ C1] ? process_backlog+0x3f1/0x14c0 [ 20.347836][ C1] ip_local_deliver_finish+0x36f/0x610 [ 20.347838][ C1] ip_local_deliver+0x184/0x4c0 [ 20.347839][ C1] ? ip_local_deliver_finish+0x610/0x610 [ 20.347841][ C1] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 20.347843][ C1] ? process_backlog+0x3f1/0x14c0 [ 20.347845][ C1] ip_rcv+0xdc/0x3d0 [ 20.347847][ C1] ? ip_local_deliver+0x4c0/0x4c0 [ 20.347849][ C1] ? mark_usage+0x61/0x170 [ 20.347850][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347852][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 20.347854][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347856][ C1] ? process_backlog+0x3f1/0x14c0 [ 20.347859][ C1] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 20.347865][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347867][ C1] process_backlog+0x431/0x14c0 [ 20.347870][ C1] ? rcu_read_lock_any_held+0x3c/0x90 [ 20.347871][ C1] __napi_poll+0xa7/0x3b0 [ 20.347873][ C1] net_rx_action+0x513/0xf50 [ 20.347876][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347877][ C1] ? __napi_poll+0x3b0/0x3b0 [ 20.347881][ C1] ? ktime_get_update_offsets_now+0x2a8/0x490 [ 20.347883][ C1] ? mark_held_locks+0x40/0x70 [ 20.347885][ C1] handle_softirqs+0x1d3/0x900 [ 20.347887][ C1] ? _local_bh_enable+0xc0/0xc0 [ 20.347888][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 20.347889][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347891][ C1] ? __dev_queue_xmit+0x964/0x1b80 [ 20.347893][ C1] do_softirq+0xac/0xe0 [ 20.347895][ C1] [ 20.347895][ C1] [ 20.347896][ C1] __local_bh_enable_ip+0x118/0x150 [ 20.347897][ C1] __dev_queue_xmit+0x979/0x1b80 [ 20.347899][ C1] ? __lock_acquire+0x518/0xc20 [ 20.347901][ C1] ? do_ipv6_getsockopt+0x1935/0x2e80 [ 20.347903][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 20.347904][ C1] ? find_held_lock+0x2b/0x80 [ 20.347906][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347907][ C1] ? __rwlock_init+0x150/0x150 [ 20.347909][ C1] ? mark_held_locks+0x40/0x70 [ 20.347910][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 20.347912][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347913][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347914][ C1] ? ___neigh_create+0x811/0x1070 [ 20.347917][ C1] ip6_finish_output2+0x423/0x12a0 [ 20.347919][ C1] ? ip6_dst_lookup+0x80/0x80 [ 20.347920][ C1] ? find_held_lock+0x2b/0x80 [ 20.347922][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347923][ C1] ? ip6_mtu+0x15d/0x310 [ 20.347925][ C1] ip6_finish_output+0x646/0xda0 [ 20.347927][ C1] ip6_output+0x23f/0x7f0 [ 20.347929][ C1] ? ip6_finish_output+0xda0/0xda0 [ 20.347930][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347932][ C1] ? find_held_lock+0x2b/0x80 [ 20.347933][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347935][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347937][ C1] ndisc_send_skb+0xba3/0x1520 [ 20.347939][ C1] ? ndisc_recv_na+0xea0/0xea0 [ 20.347941][ C1] ? ndisc_alloc_skb+0x2da/0x490 [ 20.347942][ C1] ? __local_bh_enable_ip+0xaa/0x150 [ 20.347944][ C1] addrconf_dad_completed+0x37e/0xe10 [ 20.347946][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347947][ C1] ? addrconf_rs_timer+0x770/0x770 [ 20.347949][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347950][ C1] ? __rwlock_init+0x150/0x150 [ 20.347952][ C1] ? mark_held_locks+0x40/0x70 [ 20.347954][ C1] ? addrconf_dad_begin+0x2e1/0x540 [ 20.347956][ C1] addrconf_dad_begin+0x2e1/0x540 [ 20.347957][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347959][ C1] ? addrconf_dad_completed+0xe10/0xe10 [ 20.347961][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 20.347962][ C1] ? __rwlock_init+0x150/0x150 [ 20.347964][ C1] ? mark_held_locks+0x40/0x70 [ 20.347966][ C1] addrconf_dad_work+0x170/0x930 [ 20.347968][ C1] ? addrconf_dad_begin+0x540/0x540 [ 20.347970][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347972][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347973][ C1] ? lock_acquire+0x13c/0x160 [ 20.347975][ C1] ? rcu_is_watching+0x16/0xd0 [ 20.347977][ C1] process_one_work+0xe3e/0x1560 [ 20.347980][ C1] ? __queue_delayed_work+0x470/0x470 [ 20.347982][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 20.347984][ C1] worker_thread+0x4f1/0xd60 [ 20.347986][ C1] ? rescuer_thread+0x1340/0x1340 [ 20.347988][ C1] ? __kthread_parkme+0xbd/0x210 [ 20.347990][ C1] ? rescuer_thread+0x1340/0x1340 [ 20.347991][ C1] ? rescuer_thread+0x1340/0x1340 [ 20.347993][ C1] kthread+0x367/0x460 [ 20.347994][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 20.347996][ C1] ? kthread_affine_preferred+0x4c0/0x4c0 [ 20.347997][ C1] ret_from_fork+0x474/0x6b0 [ 20.347999][ C1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 20.348000][ C1] ? __switch_to+0x5a3/0xe00 [ 20.348002][ C1] ? kthread_affine_preferred+0x4c0/0x4c0 [ 20.348004][ C1] ret_from_fork_asm+0x11/0x20 [ 20.348006][ C1] [ 20.524036][ C3] tun0: no available key for peer 2, key-id: 0 [ 20.741784][ C0] tun0: no available key for peer 5, key-id: 0 [ 20.798903][ C0] tun0: no available key for peer 6, key-id: 0 [ 26.512043][ T480] iperf3 (480) used greatest stack depth: 23128 bytes left [ 26.512063][ T480] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.512066][ T480] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 480, name: iperf3 [ 26.512068][ T480] preempt_count: 2, expected: 0 [ 26.512070][ T480] RCU nest depth: 0, expected: 0 [ 26.512071][ T480] INFO: lockdep is turned off. [ 26.512073][ T480] irq event stamp: 0 [ 26.512074][ T480] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 26.512079][ T480] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 26.512087][ T480] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 26.512089][ T480] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 26.512092][ T480] Preemption disabled at: [ 26.512093][ T480] [<0000000000000000>] 0x0 [ 26.512099][ T480] CPU: 2 UID: 0 PID: 480 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.512104][ T480] Tainted: [W]=WARN [ 26.512105][ T480] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.512107][ T480] Call Trace: [ 26.512109][ T480] [ 26.512111][ T480] dump_stack_lvl+0x6f/0xa0 [ 26.512117][ T480] __might_resched.cold+0x1fe/0x2c1 [ 26.512123][ T480] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.512127][ T480] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 26.512130][ T480] ? rcu_is_watching+0x16/0xd0 [ 26.512135][ T480] __kmalloc_noprof+0x443/0x760 [ 26.512139][ T480] ? __rwlock_init+0x150/0x150 [ 26.512143][ T480] ? alloc_buf.isra.0+0x4b/0x260 [ 26.512148][ T480] ? do_raw_spin_unlock+0x59/0x250 [ 26.512151][ T480] alloc_buf.isra.0+0x4b/0x260 [ 26.512154][ T480] put_chars+0x1e1/0x2f0 [ 26.512157][ T480] ? prb_final_commit+0x50/0x50 [ 26.512159][ T480] ? __send_to_port+0x420/0x420 [ 26.512162][ T480] ? printk_get_next_message+0x240/0x650 [ 26.512166][ T480] hvc_console_print+0x292/0x780 [ 26.512171][ T480] ? hvc_write+0x3a0/0x3a0 [ 26.512174][ T480] ? do_raw_spin_lock+0x131/0x280 [ 26.512176][ T480] ? vsnprintf+0x668/0x1700 [ 26.512180][ T480] ? __rwlock_init+0x150/0x150 [ 26.512182][ T480] ? rcu_is_watching+0x16/0xd0 [ 26.512185][ T480] ? lock_acquire+0x13c/0x160 [ 26.512188][ T480] console_emit_next_record+0x22f/0x4c0 [ 26.512192][ T480] ? devkmsg_read+0x4b0/0x4b0 [ 26.512195][ T480] ? rcu_is_watching+0x16/0xd0 [ 26.512197][ T480] ? rcu_is_watching+0x16/0xd0 [ 26.512199][ T480] ? rcu_is_watching+0x16/0xd0 [ 26.512202][ T480] ? lock_acquire+0x13c/0x160 [ 26.512205][ T480] ? console_flush_one_record+0x111/0x710 [ 26.512207][ T480] console_flush_one_record+0x46f/0x710 [ 26.512211][ T480] ? console_emit_next_record+0x4c0/0x4c0 [ 26.512215][ T480] console_unlock+0xee/0x1f0 [ 26.512217][ T480] ? lock_acquire+0x13c/0x160 [ 26.512220][ T480] ? console_flush_one_record+0x710/0x710 [ 26.512222][ T480] ? rcu_is_watching+0x16/0xd0 [ 26.512224][ T480] ? lock_release+0x184/0x1f0 [ 26.512226][ T480] ? lock_acquire+0x60/0x160 [ 26.512229][ T480] ? __rcu_read_lock+0x30/0x70 [ 26.512232][ T480] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.512234][ T480] ? vprintk_emit+0x320/0x3e0 [ 26.512237][ T480] vprintk_emit+0x37c/0x3e0 [ 26.512241][ T480] ? wake_up_klogd_work_func+0x90/0x90 [ 26.512244][ T480] _printk+0xc7/0x100 [ 26.512248][ T480] ? snapshot_read.cold+0x21/0x21 [ 26.512250][ T480] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.512254][ T480] ? do_raw_spin_lock+0x131/0x280 [ 26.512257][ T480] ? __rwlock_init+0x150/0x150 [ 26.512260][ T480] ? do_raw_spin_lock+0x131/0x280 [ 26.512262][ T480] do_exit.cold+0x82/0x9c [ 26.512266][ T480] ? exit_notify+0x890/0x890 [ 26.512270][ T480] __x64_sys_exit+0x42/0x50 [ 26.512272][ T480] x64_sys_call+0x156c/0x1570 [ 26.512275][ T480] do_syscall_64+0xff/0x530 [ 26.512278][ T480] ? exc_page_fault+0xee/0x100 [ 26.512281][ T480] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.512285][ T480] RIP: 0033:0x7f59973a8c49 [ 26.512287][ T480] Code: Unable to access opcode bytes at 0x7f59973a8c1f. [ 26.512289][ T480] RSP: 002b:00007f5996b67e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 26.512293][ T480] RAX: ffffffffffffffda RBX: 00007f5996b686c0 RCX: 00007f59973a8c49 [ 26.512295][ T480] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 26.512297][ T480] RBP: 00007f5996b67f70 R08: 0000000000000001 R09: 00007f5996b686c0 [ 26.512298][ T480] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f5996368000 [ 26.512299][ T480] R13: 00007ffdcd4d5670 R14: 0000000000000002 R15: 00007ffdcd4d5777 [ 26.512303][ T480] [ 26.673518][ T357] tun2: deleting peer with id 11, reason 4 [ 26.674939][ T371] tun4: deleting peer with id 13, reason 4 [ 26.674989][ T378] tun5: deleting peer with id 14, reason 4 [ 26.675481][ T386] tun6: deleting peer with id 15, reason 4 [ 26.675742][ T350] tun1: deleting peer with id 10, reason 4 [ 26.675882][ T364] tun3: deleting peer with id 12, reason 4 [ 26.676930][ T387] tun0: deleting peer with id 6, reason 4 [ 26.684230][ T387] tun0: deleting peer with id 5, reason 4 [ 26.690847][ T387] tun0: deleting peer with id 4, reason 4 [ 26.698083][ T387] tun0: deleting peer with id 3, reason 4 [ 26.706083][ T387] tun0: deleting peer with id 2, reason 4 [ 26.714117][ T387] tun0: deleting peer with id 1, reason 4