[ 9.913169][ T212] gre: GRE over IPv4 demultiplexer driver [ 9.913187][ T212] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.913190][ T212] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 212, name: modprobe [ 9.913191][ T212] preempt_count: 1, expected: 0 [ 9.913192][ T212] RCU nest depth: 0, expected: 0 [ 9.913193][ T212] locks held by modprobe/212: 4, last CPU#2: [ 9.913195][ T212] #0: ffffffffa397ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.913207][ T212] #1: ffffffffa397de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.913212][ T212] #2: ffffffffa389d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.913216][ T212] #3: ffffffffa389d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.913220][ T212] irq event stamp: 19236 [ 9.913221][ T212] hardirqs last enabled at (19235): [] __down_trylock_console_sem+0x86/0xa0 [ 9.913224][ T212] hardirqs last disabled at (19236): [] console_emit_next_record+0x3d4/0x4c0 [ 9.913227][ T212] softirqs last enabled at (17918): [] handle_softirqs+0x67c/0x900 [ 9.913230][ T212] softirqs last disabled at (17859): [] __irq_exit_rcu+0x145/0x1c0 [ 9.913233][ T212] Preemption disabled at: [ 9.913233][ T212] [] vprintk_emit+0x31b/0x3e0 [ 9.913239][ T212] CPU: 2 UID: 0 PID: 212 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.913242][ T212] Tainted: [W]=WARN [ 9.913243][ T212] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.913245][ T212] Call Trace: [ 9.913247][ T212] [ 9.913248][ T212] dump_stack_lvl+0x6f/0xa0 [ 9.913254][ T212] ? vprintk_emit+0x31b/0x3e0 [ 9.913256][ T212] __might_resched.cold+0x1fe/0x2c1 [ 9.913260][ T212] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.913264][ T212] ? __kmalloc_noprof+0xdb/0x760 [ 9.913269][ T212] __kmalloc_noprof+0x443/0x760 [ 9.913271][ T212] ? alloc_buf.isra.0+0x4b/0x260 [ 9.913278][ T212] ? do_raw_spin_unlock+0x59/0x250 [ 9.913280][ T212] alloc_buf.isra.0+0x4b/0x260 [ 9.913284][ T212] put_chars+0x1e1/0x2f0 [ 9.913286][ T212] ? desc_read_finalized_seq+0x79/0x120 [ 9.913288][ T212] ? __send_to_port+0x420/0x420 [ 9.913291][ T212] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.913294][ T212] ? validate_chain+0x38b/0xc20 [ 9.913299][ T212] hvc_console_print+0x292/0x780 [ 9.913303][ T212] ? __lock_acquire+0x518/0xc20 [ 9.913305][ T212] ? __lock_acquire+0x518/0xc20 [ 9.913309][ T212] ? hvc_write+0x3a0/0x3a0 [ 9.913311][ T212] ? console_emit_next_record+0x1df/0x4c0 [ 9.913315][ T212] ? rcu_is_watching+0x16/0xd0 [ 9.913317][ T212] ? lock_acquire+0x13c/0x160 [ 9.913321][ T212] console_emit_next_record+0x22f/0x4c0 [ 9.913325][ T212] ? devkmsg_read+0x4b0/0x4b0 [ 9.913327][ T212] ? console_flush_one_record+0x106/0x710 [ 9.913330][ T212] ? rcu_is_watching+0x16/0xd0 [ 9.913332][ T212] ? lock_acquire+0x13c/0x160 [ 9.913336][ T212] console_flush_one_record+0x46f/0x710 [ 9.913340][ T212] ? console_emit_next_record+0x4c0/0x4c0 [ 9.913342][ T212] ? __lock_acquire+0x518/0xc20 [ 9.913347][ T212] console_unlock+0xee/0x1f0 [ 9.913350][ T212] ? console_flush_one_record+0x710/0x710 [ 9.913352][ T212] ? rcu_is_watching+0x16/0xd0 [ 9.913353][ T212] ? lock_acquire+0xe0/0x160 [ 9.913357][ T212] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.913359][ T212] ? vprintk_emit+0x320/0x3e0 [ 9.913362][ T212] vprintk_emit+0x37c/0x3e0 [ 9.913365][ T212] ? wake_up_klogd_work_func+0x90/0x90 [ 9.913370][ T212] ? gre_exit+0x3f0/0x3f0 [gre] [ 9.913373][ T212] _printk+0xc7/0x100 [ 9.913376][ T212] ? snapshot_read.cold+0x21/0x21 [ 9.913382][ T212] ? do_one_initcall+0x113/0x4f0 [ 9.913386][ T212] gre_init+0x15/0x1000 [gre] [ 9.913388][ T212] do_one_initcall+0x124/0x4f0 [ 9.913391][ T212] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.913395][ T212] ? do_init_module+0xae/0x8a0 [ 9.913398][ T212] ? kasan_unpoison+0x44/0x70 [ 9.913404][ T212] do_init_module+0x2ad/0x8a0 [ 9.913407][ T212] ? lockdep_hardirqs_on+0x91/0x130 [ 9.913411][ T212] ? free_module+0x470/0x470 [ 9.913413][ T212] ? kfree+0x123/0x590 [ 9.913417][ T212] load_module+0x1424/0x1bb0 [ 9.913422][ T212] ? security_kernel_post_read_file+0x2f/0x60 [ 9.913427][ T212] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.913432][ T212] ? backing_file_read_iter+0x541/0x790 [ 9.913435][ T212] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.913439][ T212] init_module_from_file+0x11f/0x1a0 [ 9.913441][ T212] ? __do_sys_init_module+0x270/0x270 [ 9.913449][ T212] ? __rwlock_init+0x150/0x150 [ 9.913453][ T212] ? do_raw_spin_unlock+0x59/0x250 [ 9.913456][ T212] idempotent_init_module+0x37b/0x7c0 [ 9.913459][ T212] ? init_module_from_file+0x1a0/0x1a0 [ 9.913464][ T212] ? cap_capable+0x1d7/0x3d0 [ 9.913466][ T212] ? __x64_sys_pread64+0x18d/0x250 [ 9.913472][ T212] __x64_sys_finit_module+0xdf/0x190 [ 9.913473][ T212] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.913477][ T212] do_syscall_64+0xff/0x530 [ 9.913480][ T212] ? exc_page_fault+0xee/0x100 [ 9.913482][ T212] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.913485][ T212] RIP: 0033:0x7f5eb830737d [ 9.913488][ T212] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 9.913489][ T212] RSP: 002b:00007fff32117f18 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.913492][ T212] RAX: ffffffffffffffda RBX: 000055ba83b9ca20 RCX: 00007f5eb830737d [ 9.913493][ T212] RDX: 0000000000000000 RSI: 000055ba64305ffd RDI: 0000000000000000 [ 9.913494][ T212] RBP: 00007fff32117fb0 R08: 0000000000000000 R09: 0000000000000000 [ 9.913495][ T212] R10: 0000000000000000 R11: 0000000000000246 R12: 000055ba64305ffd [ 9.913496][ T212] R13: 000055ba83b9c9e0 R14: 0000000000040000 R15: 0000000000000000 [ 9.913503][ T212] [ 9.950930][ T212] ip6_gre: GRE over IPv6 tunneling driver [ 10.024945][ T9] ip6_tunnel: er6 xmit: Local address not yet configured! [ 10.025012][ T9] [ 10.025013][ T9] ============================= [ 10.025014][ T9] WARNING: suspicious RCU usage [ 10.025016][ T9] 7.2.0-virtme #1 Tainted: G W [ 10.025018][ T9] ----------------------------- [ 10.025018][ T9] kernel/sched/core.c:9196 Illegal context switch in RCU-bh read-side critical section! [ 10.025020][ T9] [ 10.025020][ T9] other info that might help us debug this: [ 10.025020][ T9] [ 10.025020][ T9] [ 10.025020][ T9] rcu_scheduler_active = 2, debug_locks = 1 [ 10.025022][ T9] locks held by kworker/0:0/9: 13, last CPU#0: [ 10.025023][ T9] #0: ff110000093f1540 ((wq_completion)mld){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 10.025036][ T9] #1: ffa0000000097d10 ((work_completion)(&(&idev->mc_ifc_work)->work)){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 10.025040][ T9] #2: ff1100000f0aed30 (&idev->mc_lock){+.+.}-{4:4}, at: mld_ifc_work+0x2e/0x190 [ 10.025046][ T9] #3: ffffffffa3994c00 (rcu_read_lock){....}-{1:3}, at: mld_sendpack+0x1c9/0xec0 [ 10.025049][ T9] #4: ffffffffa3994c00 (rcu_read_lock){....}-{1:3}, at: ip6_output+0x11d/0x7f0 [ 10.025053][ T9] #5: ffffffffa3994ba0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x204/0x1b90 [ 10.025059][ T9] #6: ff1100000f0f5228 (dev->qdisc_tx_busylock ?: &qdisc_tx_busylock){+...}-{3:3}, at: __dev_xmit_skb+0xcb8/0x1be0 [ 10.025062][ T9] #7: ff1100000ee93918 (&qdisc_xmit_lock_key#2){+...}-{3:3}, at: sch_direct_xmit+0x2e5/0x1100 [ 10.025067][ T9] #8: ffffffffa3994c00 (rcu_read_lock){....}-{1:3}, at: ip6_tnl_xmit_ctl+0xfd/0x390 [ 10.025072][ T9] #9: ffffffffa397ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.025076][ T9] #10: ffffffffa397de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.025080][ T9] #11: ffffffffa389d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.025084][ T9] #12: ffffffffa389d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.025088][ T9] [ 10.025088][ T9] stack backtrace: [ 10.025090][ T9] CPU: 0 UID: 0 PID: 9 Comm: kworker/0:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.025093][ T9] Tainted: [W]=WARN [ 10.025094][ T9] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.025096][ T9] Workqueue: mld mld_ifc_work [ 10.025099][ T9] Call Trace: [ 10.025101][ T9] [ 10.025102][ T9] dump_stack_lvl+0x6f/0xa0 [ 10.025110][ T9] lockdep_rcu_suspicious.cold+0x37/0x8a [ 10.025115][ T9] __might_resched+0x2ac/0x390 [ 10.025120][ T9] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.025123][ T9] ? __kmalloc_noprof+0xdb/0x760 [ 10.025128][ T9] __kmalloc_noprof+0x443/0x760 [ 10.025130][ T9] ? alloc_buf.isra.0+0x4b/0x260 [ 10.025137][ T9] ? do_raw_spin_unlock+0x59/0x250 [ 10.025140][ T9] alloc_buf.isra.0+0x4b/0x260 [ 10.025144][ T9] put_chars+0x1e1/0x2f0 [ 10.025147][ T9] ? __send_to_port+0x420/0x420 [ 10.025150][ T9] ? validate_chain+0x34a/0xc20 [ 10.025156][ T9] hvc_console_print+0x292/0x780 [ 10.025160][ T9] ? __lock_acquire+0x518/0xc20 [ 10.025166][ T9] ? hvc_write+0x3a0/0x3a0 [ 10.025169][ T9] ? rcu_is_watching+0x16/0xd0 [ 10.025175][ T9] console_emit_next_record+0x22f/0x4c0 [ 10.025179][ T9] ? devkmsg_read+0x4b0/0x4b0 [ 10.025181][ T9] ? console_flush_one_record+0x106/0x710 [ 10.025185][ T9] ? rcu_is_watching+0x16/0xd0 [ 10.025187][ T9] ? lock_acquire+0x13c/0x160 [ 10.025191][ T9] console_flush_one_record+0x46f/0x710 [ 10.025196][ T9] ? console_emit_next_record+0x4c0/0x4c0 [ 10.025198][ T9] ? __lock_acquire+0x518/0xc20 [ 10.025204][ T9] console_unlock+0xee/0x1f0 [ 10.025207][ T9] ? console_flush_one_record+0x710/0x710 [ 10.025209][ T9] ? rcu_is_watching+0x16/0xd0 [ 10.025211][ T9] ? lock_acquire+0xe0/0x160 [ 10.025215][ T9] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.025217][ T9] ? vprintk_emit+0x320/0x3e0 [ 10.025221][ T9] vprintk_emit+0x37c/0x3e0 [ 10.025224][ T9] ? wake_up_klogd_work_func+0x90/0x90 [ 10.025226][ T9] ? __lock_release.isra.0+0x69/0x1a0 [ 10.025231][ T9] _printk+0xc7/0x100 [ 10.025235][ T9] ? snapshot_read.cold+0x21/0x21 [ 10.025236][ T9] ? ___ratelimit+0x4e7/0xa00 [ 10.025243][ T9] ? idr_get_free+0x830/0x830 [ 10.025248][ T9] ip6_tnl_xmit_ctl.cold+0x75/0x7a [ 10.025253][ T9] ip6erspan_tunnel_xmit+0x2cf/0x2db4 [ip6_gre] [ 10.025262][ T9] ? ip6gre_tunnel_siocdevprivate+0x1a70/0x1a70 [ip6_gre] [ 10.025266][ T9] ? validate_chain+0x34a/0xc20 [ 10.025277][ T9] dev_hard_start_xmit+0xf4/0x620 [ 10.025283][ T9] sch_direct_xmit+0x25b/0x1100 [ 10.025286][ T9] ? lock_acquire.part.0+0xd4/0x280 [ 10.025289][ T9] ? dequeue_skb+0x1db0/0x1db0 [ 10.025292][ T9] ? __rcu_read_lock+0x70/0x70 [ 10.025293][ T9] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.025295][ T9] ? lock_acquire+0x13c/0x160 [ 10.025300][ T9] __dev_xmit_skb+0xecc/0x1be0 [ 10.025305][ T9] ? alloc_netdev_dummy+0x30/0x30 [ 10.025307][ T9] ? __dev_queue_xmit+0x204/0x1b90 [ 10.025310][ T9] ? rcu_is_watching+0x16/0xd0 [ 10.025312][ T9] ? lock_acquire+0x13c/0x160 [ 10.025316][ T9] __dev_queue_xmit+0x952/0x1b90 [ 10.025319][ T9] ? __lock_acquire+0x518/0xc20 [ 10.025324][ T9] ? lock_acquire.part.0+0xd4/0x280 [ 10.025326][ T9] ? find_held_lock+0x2b/0x80 [ 10.025328][ T9] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 10.025341][ T9] ip6_finish_output2+0x48d/0x13f0 [ 10.025346][ T9] ? ip6_dst_lookup+0x80/0x80 [ 10.025348][ T9] ? find_held_lock+0x2b/0x80 [ 10.025351][ T9] ? __lock_release.isra.0+0x69/0x1a0 [ 10.025355][ T9] ? ip6_mtu+0x174/0x410 [ 10.025360][ T9] ip6_finish_output+0x701/0xe80 [ 10.025365][ T9] ip6_output+0x23f/0x7f0 [ 10.025368][ T9] ? ip6_finish_output+0xe80/0xe80 [ 10.025370][ T9] ? __lock_release.isra.0+0x69/0x1a0 [ 10.025373][ T9] ? xfrm_bundle_lookup.constprop.0+0xba0/0xba0 [ 10.025375][ T9] ? mark_held_locks+0x40/0x70 [ 10.025379][ T9] ? __local_bh_enable_ip+0xaa/0x150 [ 10.025382][ T9] ? __local_bh_enable_ip+0xaa/0x150 [ 10.025384][ T9] ? icmp6_dst_alloc+0x317/0x4d0 [ 10.025388][ T9] mld_sendpack+0x9d6/0xec0 [ 10.025393][ T9] ? mld_dad_start_work+0x240/0x240 [ 10.025399][ T9] ? mld_send_cr+0x50f/0x820 [ 10.025404][ T9] mld_ifc_work+0x36/0x190 [ 10.025406][ T9] ? process_one_work+0xdfc/0x1560 [ 10.025408][ T9] process_one_work+0xe3e/0x1560 [ 10.025415][ T9] ? __queue_delayed_work+0x470/0x470 [ 10.025418][ T9] ? lock_acquire.part.0+0xd4/0x280 [ 10.025427][ T9] worker_thread+0x4f1/0xd60 [ 10.025434][ T9] ? rescuer_thread+0x1340/0x1340 [ 10.025437][ T9] kthread+0x367/0x460 [ 10.025439][ T9] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.025443][ T9] ? kthread_affine_preferred+0x4c0/0x4c0 [ 10.025446][ T9] ret_from_fork+0x474/0x6b0 [ 10.025450][ T9] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 10.025454][ T9] ? __switch_to+0x5a3/0xe00 [ 10.025458][ T9] ? kthread_affine_preferred+0x4c0/0x4c0 [ 10.025461][ T9] ret_from_fork_asm+0x11/0x20 [ 10.025470][ T9] [ 10.107906][ T9] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 10.322898][ T12] ip6_tunnel: er6 xmit: Local address not yet configured! [ 10.410864][ T9] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 10.618851][ T9] ip6_tunnel: er6 xmit: Local address not yet configured! [ 10.746896][ T12] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 11.225695][ T223] Mirror/redirect action on [ 11.225712][ T223] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.225714][ T223] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 223, name: modprobe [ 11.225715][ T223] preempt_count: 1, expected: 0 [ 11.225716][ T223] RCU nest depth: 0, expected: 0 [ 11.225717][ T223] locks held by modprobe/223: 4, last CPU#2: [ 11.225720][ T223] #0: ffffffffa397ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.225731][ T223] #1: ffffffffa397de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.225736][ T223] #2: ffffffffa389d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.225740][ T223] #3: ffffffffa389d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.225744][ T223] irq event stamp: 23376 [ 11.225745][ T223] hardirqs last enabled at (23375): [] __down_trylock_console_sem+0x86/0xa0 [ 11.225748][ T223] hardirqs last disabled at (23376): [] console_emit_next_record+0x3d4/0x4c0 [ 11.225750][ T223] softirqs last enabled at (22170): [] handle_softirqs+0x67c/0x900 [ 11.225754][ T223] softirqs last disabled at (22163): [] __irq_exit_rcu+0x145/0x1c0 [ 11.225756][ T223] Preemption disabled at: [ 11.225757][ T223] [] vprintk_emit+0x31b/0x3e0 [ 11.225763][ T223] CPU: 2 UID: 0 PID: 223 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.225767][ T223] Tainted: [W]=WARN [ 11.225767][ T223] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.225769][ T223] Call Trace: [ 11.225771][ T223] [ 11.225772][ T223] dump_stack_lvl+0x6f/0xa0 [ 11.225778][ T223] ? vprintk_emit+0x31b/0x3e0 [ 11.225780][ T223] __might_resched.cold+0x1fe/0x2c1 [ 11.225784][ T223] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.225788][ T223] ? __kmalloc_noprof+0xdb/0x760 [ 11.225792][ T223] __kmalloc_noprof+0x443/0x760 [ 11.225794][ T223] ? alloc_buf.isra.0+0x4b/0x260 [ 11.225800][ T223] ? do_raw_spin_unlock+0x59/0x250 [ 11.225803][ T223] alloc_buf.isra.0+0x4b/0x260 [ 11.225807][ T223] put_chars+0x1e1/0x2f0 [ 11.225809][ T223] ? __send_to_port+0x420/0x420 [ 11.225810][ T223] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.225814][ T223] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.225816][ T223] ? validate_chain+0x38b/0xc20 [ 11.225821][ T223] hvc_console_print+0x292/0x780 [ 11.225825][ T223] ? __lock_acquire+0x518/0xc20 [ 11.225830][ T223] ? hvc_write+0x3a0/0x3a0 [ 11.225833][ T223] ? rcu_is_watching+0x16/0xd0 [ 11.225837][ T223] console_emit_next_record+0x22f/0x4c0 [ 11.225841][ T223] ? devkmsg_read+0x4b0/0x4b0 [ 11.225843][ T223] ? console_flush_one_record+0x106/0x710 [ 11.225846][ T223] ? rcu_is_watching+0x16/0xd0 [ 11.225848][ T223] ? lock_acquire+0x13c/0x160 [ 11.225855][ T223] console_flush_one_record+0x46f/0x710 [ 11.225859][ T223] ? console_emit_next_record+0x4c0/0x4c0 [ 11.225861][ T223] ? __lock_acquire+0x518/0xc20 [ 11.225866][ T223] console_unlock+0xee/0x1f0 [ 11.225869][ T223] ? console_flush_one_record+0x710/0x710 [ 11.225871][ T223] ? rcu_is_watching+0x16/0xd0 [ 11.225873][ T223] ? lock_acquire+0xe0/0x160 [ 11.225876][ T223] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.225878][ T223] ? vprintk_emit+0x320/0x3e0 [ 11.225881][ T223] vprintk_emit+0x37c/0x3e0 [ 11.225884][ T223] ? wake_up_klogd_work_func+0x90/0x90 [ 11.225886][ T223] ? netdev_run_todo+0x2a8/0xfd0 [ 11.225889][ T223] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 11.225894][ T223] ? gre_exit+0x3f0/0x3f0 [gre] [ 11.225896][ T223] _printk+0xc7/0x100 [ 11.225900][ T223] ? snapshot_read.cold+0x21/0x21 [ 11.225901][ T223] ? __lock_release.isra.0+0x69/0x1a0 [ 11.225904][ T223] ? gre_exit+0x3f0/0x3f0 [gre] [ 11.225906][ T223] ? __up_write+0x23d/0x5f0 [ 11.225907][ T223] ? __up_write+0x2e5/0x5f0 [ 11.225911][ T223] ? __up_write+0x2c7/0x5f0 [ 11.225913][ T223] mirred_init_module+0x28/0x1000 [act_mirred] [ 11.225916][ T223] do_one_initcall+0x124/0x4f0 [ 11.225919][ T223] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 11.225924][ T223] ? do_init_module+0xae/0x8a0 [ 11.225926][ T223] ? kasan_unpoison+0x44/0x70 [ 11.225932][ T223] do_init_module+0x2ad/0x8a0 [ 11.225935][ T223] ? lockdep_hardirqs_on+0x91/0x130 [ 11.225939][ T223] ? free_module+0x470/0x470 [ 11.225941][ T223] ? kfree+0x123/0x590 [ 11.225945][ T223] load_module+0x1424/0x1bb0 [ 11.225950][ T223] ? security_kernel_post_read_file+0x2f/0x60 [ 11.225955][ T223] ? layout_and_allocate.isra.0+0x680/0x680 [ 11.225958][ T223] ? __ia32_sys_fsconfig+0x180/0x180 [ 11.225962][ T223] ? backing_file_read_iter+0x541/0x790 [ 11.225965][ T223] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.225968][ T223] init_module_from_file+0x11f/0x1a0 [ 11.225971][ T223] ? __do_sys_init_module+0x270/0x270 [ 11.225978][ T223] ? __rwlock_init+0x150/0x150 [ 11.225982][ T223] ? do_raw_spin_unlock+0x59/0x250 [ 11.225985][ T223] idempotent_init_module+0x37b/0x7c0 [ 11.225987][ T223] ? init_module_from_file+0x1a0/0x1a0 [ 11.225992][ T223] ? cap_capable+0x1d7/0x3d0 [ 11.225995][ T223] ? __x64_sys_pread64+0x18d/0x250 [ 11.226000][ T223] __x64_sys_finit_module+0xdf/0x190 [ 11.226001][ T223] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.226005][ T223] do_syscall_64+0xff/0x530 [ 11.226008][ T223] ? exc_page_fault+0xee/0x100 [ 11.226011][ T223] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.226013][ T223] RIP: 0033:0x7f4df494237d [ 11.226016][ T223] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 11.226018][ T223] RSP: 002b:00007ffff7768c48 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 11.226020][ T223] RAX: ffffffffffffffda RBX: 0000562357c8b810 RCX: 00007f4df494237d [ 11.226021][ T223] RDX: 0000000000000000 RSI: 000056231d986ffd RDI: 0000000000000000 [ 11.226022][ T223] RBP: 00007ffff7768ce0 R08: 0000000000000000 R09: 0000000000000000 [ 11.226023][ T223] R10: 0000000000000000 R11: 0000000000000246 R12: 000056231d986ffd [ 11.226024][ T223] R13: 0000562357c8b960 R14: 0000000000040000 R15: 0000000000000000 [ 11.226030][ T223] [ 13.372003][ C2] [ 13.372018][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.372020][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 13.372022][ C2] preempt_count: 305, expected: 0 [ 13.372023][ C2] RCU nest depth: 1, expected: 0 [ 13.372024][ C2] INFO: lockdep is turned off. [ 13.372025][ C2] irq event stamp: 345460 [ 13.372026][ C2] hardirqs last enabled at (345460): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 13.372035][ C2] hardirqs last disabled at (345459): [] _raw_spin_lock_irqsave+0x50/0x60 [ 13.372036][ C2] softirqs last enabled at (345306): [] handle_softirqs+0x67c/0x900 [ 13.372041][ C2] softirqs last disabled at (345313): [] __irq_exit_rcu+0x145/0x1c0 [ 13.372043][ C2] Preemption disabled at: [ 13.372044][ C2] [<0000000000000000>] 0x0 [ 13.372050][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.372053][ C2] Tainted: [W]=WARN [ 13.372054][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.372056][ C2] Call Trace: [ 13.372057][ C2] [ 13.372059][ C2] dump_stack_lvl+0x6f/0xa0 [ 13.372064][ C2] __might_resched.cold+0x1fe/0x2c1 [ 13.372068][ C2] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 13.372071][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.372074][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372077][ C2] __kmalloc_noprof+0x443/0x760 [ 13.372080][ C2] ? __rwlock_init+0x150/0x150 [ 13.372083][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 13.372087][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 13.372089][ C2] alloc_buf.isra.0+0x4b/0x260 [ 13.372091][ C2] put_chars+0x1e1/0x2f0 [ 13.372093][ C2] ? __send_to_port+0x420/0x420 [ 13.372094][ C2] ? console_prepend_replay+0x20/0x20 [ 13.372097][ C2] hvc_console_print+0x292/0x780 [ 13.372102][ C2] ? hvc_write+0x3a0/0x3a0 [ 13.372103][ C2] ? vsnprintf+0x668/0x1700 [ 13.372106][ C2] ? __rwlock_init+0x150/0x150 [ 13.372107][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372108][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372110][ C2] console_emit_next_record+0x22f/0x4c0 [ 13.372113][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 13.372115][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372116][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372117][ C2] ? lock_acquire+0x13c/0x160 [ 13.372119][ C2] ? console_flush_one_record+0x111/0x710 [ 13.372121][ C2] console_flush_one_record+0x46f/0x710 [ 13.372123][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 13.372126][ C2] console_unlock+0xee/0x1f0 [ 13.372128][ C2] ? lock_acquire+0x13c/0x160 [ 13.372129][ C2] ? console_flush_one_record+0x710/0x710 [ 13.372131][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372132][ C2] ? lock_release+0x184/0x1f0 [ 13.372133][ C2] ? lock_acquire+0xe0/0x160 [ 13.372135][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.372137][ C2] ? vprintk_emit+0x320/0x3e0 [ 13.372139][ C2] vprintk_emit+0x37c/0x3e0 [ 13.372140][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 13.372142][ C2] ? tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.372144][ C2] ? lock_release+0x184/0x1f0 [ 13.372146][ C2] ? tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.372147][ C2] ? tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.372149][ C2] ? is_module_text_address+0x154/0x250 [ 13.372152][ C2] _printk+0xc7/0x100 [ 13.372155][ C2] ? snapshot_read.cold+0x21/0x21 [ 13.372156][ C2] ? arch_stack_walk+0xd7/0x130 [ 13.372159][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.372161][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 13.372163][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 13.372165][ C2] mark_lock_irq+0x989/0x9c0 [ 13.372167][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.372169][ C2] mark_lock+0x1d7/0xa00 [ 13.372171][ C2] mark_usage+0x42/0x170 [ 13.372173][ C2] __lock_acquire+0x388/0xc20 [ 13.372174][ C2] ? skb_network_protocol+0x3b0/0x3b0 [ 13.372178][ C2] ? lock_pin_lock+0x87/0x180 [ 13.372180][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.372182][ C2] ? sch_direct_xmit+0x2e5/0x1100 [ 13.372185][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372186][ C2] ? lock_acquire+0x13c/0x160 [ 13.372188][ C2] _raw_spin_lock+0x33/0x40 [ 13.372189][ C2] ? sch_direct_xmit+0x2e5/0x1100 [ 13.372190][ C2] sch_direct_xmit+0x2e5/0x1100 [ 13.372192][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 13.372194][ C2] ? dequeue_skb+0x1db0/0x1db0 [ 13.372195][ C2] ? __rcu_read_lock+0x70/0x70 [ 13.372196][ C2] ? tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.372198][ C2] ? lock_acquire+0x13c/0x160 [ 13.372200][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.372202][ C2] ? alloc_netdev_dummy+0x30/0x30 [ 13.372204][ C2] ? __dev_queue_xmit+0x204/0x1b90 [ 13.372206][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372207][ C2] ? lock_acquire+0x13c/0x160 [ 13.372209][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.372211][ C2] ? find_held_lock+0x2b/0x80 [ 13.372212][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 13.372214][ C2] ? tcf_classify+0x67/0x90 [ 13.372217][ C2] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 13.372219][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 13.372221][ C2] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.372223][ C2] ? __copy_skb_header+0xb7/0x510 [ 13.372226][ C2] ? __asan_memcpy+0x3c/0x60 [ 13.372229][ C2] ? __skb_clone+0x55e/0x760 [ 13.372231][ C2] tcf_mirred_to_dev+0x941/0x1500 [act_mirred] [ 13.372233][ C2] ? common_startup_64+0x99/0x148 [ 13.372236][ C2] ? __asan_memset+0x27/0x50 [ 13.372238][ C2] tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.372240][ C2] tcf_action_exec.part.0+0xca/0x3c0 [ 13.372243][ C2] __tcf_classify.constprop.0+0xcf/0x3a0 [ 13.372245][ C2] tcf_classify+0x67/0x90 [ 13.372247][ C2] ? tcf_qevent_handle+0x3d0/0x3d0 [ 13.372249][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.372251][ C2] tc_run+0x3d0/0x7e0 [ 13.372253][ C2] ? netdev_offload_xstats_push_delta+0x430/0x430 [ 13.372255][ C2] __netif_receive_skb_core.constprop.0+0xdb7/0x2bb0 [ 13.372259][ C2] ? do_xdp_generic+0x4a0/0x4a0 [ 13.372261][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.372263][ C2] ? mark_usage+0x61/0x170 [ 13.372265][ C2] ? __lock_acquire+0x518/0xc20 [ 13.372267][ C2] ? process_backlog+0x3f1/0x14c0 [ 13.372268][ C2] __netif_receive_skb_one_core+0xa2/0x180 [ 13.372270][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 13.372272][ C2] ? process_backlog+0x3f1/0x14c0 [ 13.372273][ C2] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 13.372275][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372277][ C2] process_backlog+0x431/0x14c0 [ 13.372279][ C2] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 13.372282][ C2] __napi_poll+0xa7/0x3b0 [ 13.372284][ C2] net_rx_action+0x513/0xf50 [ 13.372287][ C2] ? __napi_poll+0x3b0/0x3b0 [ 13.372289][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 13.372291][ C2] ? sched_balance_softirq+0x29c/0x320 [ 13.372294][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372295][ C2] handle_softirqs+0x1d3/0x900 [ 13.372297][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 13.372299][ C2] ? _local_bh_enable+0xc0/0xc0 [ 13.372301][ C2] __irq_exit_rcu+0x145/0x1c0 [ 13.372302][ C2] irq_exit_rcu+0xe/0x30 [ 13.372304][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 13.372306][ C2] [ 13.372306][ C2] [ 13.372307][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 13.372308][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.372311][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 13.372313][ C2] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 13.372314][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 13.372317][ C2] RAX: 00000000000544df RBX: ff11000001c1c740 RCX: ffffffff9fd06247 [ 13.372319][ C2] RDX: ff11000001c1c740 RSI: ffffffffa2f11011 RDI: ffffffffa28949e0 [ 13.372320][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 13.372320][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 13.372321][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 13.372323][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.372325][ C2] default_idle+0x9/0x10 [ 13.372327][ C2] default_idle_call+0x6e/0xb0 [ 13.372329][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.372330][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 13.372332][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 13.372335][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.372337][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.372339][ C2] do_idle+0xd8/0x190 [ 13.372340][ C2] cpu_startup_entry+0x53/0x70 [ 13.372342][ C2] start_secondary+0x204/0x2b0 [ 13.372344][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 13.372346][ C2] common_startup_64+0x13e/0x148 [ 13.372349][ C2] [ 13.404198][ C2] ======================================================== [ 13.404481][ C2] WARNING: possible irq lock inversion dependency detected [ 13.404753][ C2] 7.2.0-virtme #1 Tainted: G W [ 13.404939][ C2] -------------------------------------------------------- [ 13.405211][ C2] swapper/2/0 just changed the state of lock: [ 13.405447][ C2] ff1100000ee93918 (&qdisc_xmit_lock_key#2){+.-.}-{3:3}, at: sch_direct_xmit+0x2e5/0x1100 [ 13.405760][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 13.405984][ C2] (fs_reclaim){+.+.}-{0:0} [ 13.405986][ C2] [ 13.405986][ C2] [ 13.405986][ C2] and interrupts could create inverse lock ordering between them. [ 13.405986][ C2] [ 13.406635][ C2] [ 13.406635][ C2] other info that might help us debug this: [ 13.406911][ C2] Chain exists of: [ 13.406911][ C2] &qdisc_xmit_lock_key#2 --> console_owner --> fs_reclaim [ 13.406911][ C2] [ 13.407335][ C2] Possible interrupt unsafe locking scenario: [ 13.407335][ C2] [ 13.407608][ C2] CPU0 CPU1 [ 13.407811][ C2] ---- ---- [ 13.407964][ C2] lock(fs_reclaim); [ 13.408078][ C2] local_irq_disable(); [ 13.408315][ C2] lock(&qdisc_xmit_lock_key#2); [ 13.408591][ C2] lock(console_owner); [ 13.408778][ C2] [ 13.408948][ C2] lock(&qdisc_xmit_lock_key#2); [ 13.409096][ C2] [ 13.409096][ C2] *** DEADLOCK *** [ 13.409096][ C2] [ 13.409369][ C2] locks held by swapper/2/0: 3, last CPU#2: [ 13.409605][ C2] #0: ffffffffa3994c00 (rcu_read_lock){....}-{1:3}, at: process_backlog+0x3f1/0x14c0 [ 13.409921][ C2] #1: ffffffffa3994ba0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x204/0x1b90 [ 13.410178][ C2] #2: ff1100000f0f5228 (dev->qdisc_tx_busylock ?: &qdisc_tx_busylock){+...}-{3:3}, at: __dev_xmit_skb+0xcb8/0x1be0 [ 13.410611][ C2] [ 13.410611][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 13.410870][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 13.411076][ C2] HARDIRQ-ON-W at: [ 13.411190][ C2] __lock_acquire+0x388/0xc20 [ 13.411438][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.411679][ C2] fs_reclaim_acquire+0xd5/0x120 [ 13.411870][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 13.412147][ C2] kthread_create_worker_on_node+0xea/0x210 [ 13.412427][ C2] workqueue_init+0x2a/0x680 [ 13.412615][ C2] kernel_init_freeable+0x2fe/0x630 [ 13.412897][ C2] kernel_init+0x21/0x150 [ 13.413138][ C2] ret_from_fork+0x474/0x6b0 [ 13.413322][ C2] ret_from_fork_asm+0x11/0x20 [ 13.413568][ C2] SOFTIRQ-ON-W at: [ 13.413677][ C2] __lock_acquire+0x388/0xc20 [ 13.413919][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.414161][ C2] fs_reclaim_acquire+0xd5/0x120 [ 13.414345][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 13.414619][ C2] kthread_create_worker_on_node+0xea/0x210 [ 13.414841][ C2] workqueue_init+0x2a/0x680 [ 13.415028][ C2] kernel_init_freeable+0x2fe/0x630 [ 13.415305][ C2] kernel_init+0x21/0x150 [ 13.415545][ C2] ret_from_fork+0x474/0x6b0 [ 13.415729][ C2] ret_from_fork_asm+0x11/0x20 [ 13.415919][ C2] INITIAL USE at: [ 13.416028][ C2] __lock_acquire+0x388/0xc20 [ 13.416268][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.416506][ C2] fs_reclaim_acquire+0xd5/0x120 [ 13.416690][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 13.416917][ C2] kthread_create_worker_on_node+0xea/0x210 [ 13.417194][ C2] workqueue_init+0x2a/0x680 [ 13.417379][ C2] kernel_init_freeable+0x2fe/0x630 [ 13.417619][ C2] kernel_init+0x21/0x150 [ 13.417805][ C2] ret_from_fork+0x474/0x6b0 [ 13.418016][ C2] ret_from_fork_asm+0x11/0x20 [ 13.418202][ C2] } [ 13.418278][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 13.418551][ C2] ... acquired at: [ 13.418664][ C2] __lock_acquire+0x518/0xc20 [ 13.418813][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.418963][ C2] fs_reclaim_acquire+0xd5/0x120 [ 13.419165][ C2] __kmalloc_noprof+0xd3/0x760 [ 13.419308][ C2] alloc_buf.isra.0+0x4b/0x260 [ 13.419511][ C2] put_chars+0x1e1/0x2f0 [ 13.419657][ C2] hvc_console_print+0x292/0x780 [ 13.419858][ C2] console_emit_next_record+0x22f/0x4c0 [ 13.420008][ C2] console_flush_one_record+0x46f/0x710 [ 13.420209][ C2] console_unlock+0xee/0x1f0 [ 13.420359][ C2] vprintk_emit+0x37c/0x3e0 [ 13.420560][ C2] _printk+0xc7/0x100 [ 13.420672][ C2] tun_init+0x24/0xb0 [ 13.420785][ C2] do_one_initcall+0x124/0x4f0 [ 13.420994][ C2] kernel_init_freeable+0x596/0x630 [ 13.421139][ C2] kernel_init+0x21/0x150 [ 13.421341][ C2] ret_from_fork+0x474/0x6b0 [ 13.421488][ C2] ret_from_fork_asm+0x11/0x20 [ 13.421689][ C2] [ 13.421765][ C2] -> (console_owner){....}-{0:0} { [ 13.421970][ C2] INITIAL USE at: [ 13.422080][ C2] } [ 13.422152][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 13.422423][ C2] ... acquired at: [ 13.422531][ C2] __lock_acquire+0x518/0xc20 [ 13.422731][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.422886][ C2] console_lock_spinning_enable+0x5c/0x60 [ 13.423125][ C2] console_emit_next_record+0x1d1/0x4c0 [ 13.423325][ C2] console_flush_one_record+0x46f/0x710 [ 13.423472][ C2] console_unlock+0xee/0x1f0 [ 13.423673][ C2] vprintk_emit+0x37c/0x3e0 [ 13.423819][ C2] _printk+0xc7/0x100 [ 13.423933][ C2] ip6_tnl_xmit_ctl.cold+0x75/0x7a [ 13.424136][ C2] ip6erspan_tunnel_xmit+0x2cf/0x2db4 [ip6_gre] [ 13.424373][ C2] dev_hard_start_xmit+0xf4/0x620 [ 13.424521][ C2] sch_direct_xmit+0x25b/0x1100 [ 13.424723][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.424873][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.425076][ C2] ip6_finish_output2+0x48d/0x13f0 [ 13.425226][ C2] ip6_finish_output+0x701/0xe80 [ 13.425428][ C2] ip6_output+0x23f/0x7f0 [ 13.425576][ C2] mld_sendpack+0x9d6/0xec0 [ 13.425778][ C2] mld_ifc_work+0x36/0x190 [ 13.425929][ C2] process_one_work+0xe3e/0x1560 [ 13.426132][ C2] worker_thread+0x4f1/0xd60 [ 13.426280][ C2] kthread+0x367/0x460 [ 13.426394][ C2] ret_from_fork+0x474/0x6b0 [ 13.426596][ C2] ret_from_fork_asm+0x11/0x20 [ 13.426746][ C2] [ 13.426820][ C2] -> (&qdisc_xmit_lock_key#2){+.-.}-{3:3} { [ 13.427010][ C2] HARDIRQ-ON-W at: [ 13.427176][ C2] __lock_acquire+0x388/0xc20 [ 13.427366][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.427609][ C2] _raw_spin_lock+0x33/0x40 [ 13.427804][ C2] sch_direct_xmit+0x2e5/0x1100 [ 13.427999][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.428242][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.428429][ C2] ip6_finish_output2+0x48d/0x13f0 [ 13.428674][ C2] ip6_finish_output+0x701/0xe80 [ 13.428924][ C2] ip6_output+0x23f/0x7f0 [ 13.429110][ C2] mld_sendpack+0x9d6/0xec0 [ 13.429350][ C2] mld_ifc_work+0x36/0x190 [ 13.429588][ C2] process_one_work+0xe3e/0x1560 [ 13.429773][ C2] worker_thread+0x4f1/0xd60 [ 13.430016][ C2] kthread+0x367/0x460 [ 13.430167][ C2] ret_from_fork+0x474/0x6b0 [ 13.430405][ C2] ret_from_fork_asm+0x11/0x20 [ 13.430644][ C2] IN-SOFTIRQ-W at: [ 13.430763][ C2] __lock_acquire+0x388/0xc20 [ 13.431005][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.431187][ C2] _raw_spin_lock+0x33/0x40 [ 13.431434][ C2] sch_direct_xmit+0x2e5/0x1100 [ 13.431620][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.431872][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.432110][ C2] tcf_mirred_to_dev+0x941/0x1500 [act_mirred] [ 13.432351][ C2] tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.432626][ C2] tcf_action_exec.part.0+0xca/0x3c0 [ 13.432875][ C2] __tcf_classify.constprop.0+0xcf/0x3a0 [ 13.433154][ C2] tcf_classify+0x67/0x90 [ 13.433336][ C2] tc_run+0x3d0/0x7e0 [ 13.433543][ C2] __netif_receive_skb_core.constprop.0+0xdb7/0x2bb0 [ 13.433764][ C2] __netif_receive_skb_one_core+0xa2/0x180 [ 13.433994][ C2] process_backlog+0x431/0x14c0 [ 13.434236][ C2] __napi_poll+0xa7/0x3b0 [ 13.434418][ C2] net_rx_action+0x513/0xf50 [ 13.434661][ C2] handle_softirqs+0x1d3/0x900 [ 13.434910][ C2] __irq_exit_rcu+0x145/0x1c0 [ 13.435098][ C2] irq_exit_rcu+0xe/0x30 [ 13.435338][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 13.435621][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.435839][ C2] pv_native_safe_halt+0xf/0x10 [ 13.436084][ C2] default_idle+0x9/0x10 [ 13.436324][ C2] default_idle_call+0x6e/0xb0 [ 13.436512][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.436791][ C2] do_idle+0xd8/0x190 [ 13.436995][ C2] cpu_startup_entry+0x53/0x70 [ 13.437188][ C2] start_secondary+0x204/0x2b0 [ 13.437425][ C2] common_startup_64+0x13e/0x148 [ 13.437608][ C2] INITIAL USE at: [ 13.437785][ C2] __lock_acquire+0x388/0xc20 [ 13.437971][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.438207][ C2] _raw_spin_lock+0x33/0x40 [ 13.438454][ C2] sch_direct_xmit+0x2e5/0x1100 [ 13.438636][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.438879][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.439121][ C2] ip6_finish_output2+0x48d/0x13f0 [ 13.439306][ C2] ip6_finish_output+0x701/0xe80 [ 13.439549][ C2] ip6_output+0x23f/0x7f0 [ 13.439731][ C2] mld_sendpack+0x9d6/0xec0 [ 13.439979][ C2] mld_ifc_work+0x36/0x190 [ 13.440216][ C2] process_one_work+0xe3e/0x1560 [ 13.440401][ C2] worker_thread+0x4f1/0xd60 [ 13.440648][ C2] kthread+0x367/0x460 [ 13.440794][ C2] ret_from_fork+0x474/0x6b0 [ 13.441041][ C2] ret_from_fork_asm+0x11/0x20 [ 13.441280][ C2] } [ 13.441354][ C2] ... key at: [] qdisc_xmit_lock_key.158+0x0/0x40 [ip6_gre] [ 13.441668][ C2] ... acquired at: [ 13.441776][ C2] mark_lock+0x1d7/0xa00 [ 13.441979][ C2] mark_usage+0x42/0x170 [ 13.442125][ C2] __lock_acquire+0x388/0xc20 [ 13.442326][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.442470][ C2] _raw_spin_lock+0x33/0x40 [ 13.442670][ C2] sch_direct_xmit+0x2e5/0x1100 [ 13.442816][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.443020][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.443165][ C2] tcf_mirred_to_dev+0x941/0x1500 [act_mirred] [ 13.443400][ C2] tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.443580][ C2] tcf_action_exec.part.0+0xca/0x3c0 [ 13.443781][ C2] __tcf_classify.constprop.0+0xcf/0x3a0 [ 13.444019][ C2] tcf_classify+0x67/0x90 [ 13.444165][ C2] tc_run+0x3d0/0x7e0 [ 13.444275][ C2] __netif_receive_skb_core.constprop.0+0xdb7/0x2bb0 [ 13.444509][ C2] __netif_receive_skb_one_core+0xa2/0x180 [ 13.444744][ C2] process_backlog+0x431/0x14c0 [ 13.444894][ C2] __napi_poll+0xa7/0x3b0 [ 13.445093][ C2] net_rx_action+0x513/0xf50 [ 13.445236][ C2] handle_softirqs+0x1d3/0x900 [ 13.445437][ C2] __irq_exit_rcu+0x145/0x1c0 [ 13.445581][ C2] irq_exit_rcu+0xe/0x30 [ 13.445779][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 13.445964][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.446199][ C2] pv_native_safe_halt+0xf/0x10 [ 13.446342][ C2] default_idle+0x9/0x10 [ 13.446540][ C2] default_idle_call+0x6e/0xb0 [ 13.446684][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.446923][ C2] do_idle+0xd8/0x190 [ 13.447033][ C2] cpu_startup_entry+0x53/0x70 [ 13.447231][ C2] start_secondary+0x204/0x2b0 [ 13.447377][ C2] common_startup_64+0x13e/0x148 [ 13.447578][ C2] [ 13.447651][ C2] [ 13.447651][ C2] stack backtrace: [ 13.447889][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.447892][ C2] Tainted: [W]=WARN [ 13.447893][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.447894][ C2] Call Trace: [ 13.447895][ C2] [ 13.447896][ C2] dump_stack_lvl+0x6f/0xa0 [ 13.447900][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 13.447903][ C2] mark_lock_irq+0x989/0x9c0 [ 13.447904][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.447908][ C2] mark_lock+0x1d7/0xa00 [ 13.447910][ C2] mark_usage+0x42/0x170 [ 13.447911][ C2] __lock_acquire+0x388/0xc20 [ 13.447913][ C2] ? skb_network_protocol+0x3b0/0x3b0 [ 13.447915][ C2] ? lock_pin_lock+0x87/0x180 [ 13.447917][ C2] lock_acquire.part.0+0xd4/0x280 [ 13.447918][ C2] ? sch_direct_xmit+0x2e5/0x1100 [ 13.447920][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.447921][ C2] ? lock_acquire+0x13c/0x160 [ 13.447923][ C2] _raw_spin_lock+0x33/0x40 [ 13.447924][ C2] ? sch_direct_xmit+0x2e5/0x1100 [ 13.447926][ C2] sch_direct_xmit+0x2e5/0x1100 [ 13.447927][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 13.447929][ C2] ? dequeue_skb+0x1db0/0x1db0 [ 13.447930][ C2] ? __rcu_read_lock+0x70/0x70 [ 13.447931][ C2] ? tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.447933][ C2] ? lock_acquire+0x13c/0x160 [ 13.447935][ C2] __dev_xmit_skb+0xecc/0x1be0 [ 13.447937][ C2] ? alloc_netdev_dummy+0x30/0x30 [ 13.447938][ C2] ? __dev_queue_xmit+0x204/0x1b90 [ 13.447940][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.447941][ C2] ? lock_acquire+0x13c/0x160 [ 13.447943][ C2] __dev_queue_xmit+0x952/0x1b90 [ 13.447945][ C2] ? find_held_lock+0x2b/0x80 [ 13.447947][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 13.447948][ C2] ? tcf_classify+0x67/0x90 [ 13.447950][ C2] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 13.447952][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 13.447953][ C2] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.447956][ C2] ? __copy_skb_header+0xb7/0x510 [ 13.447958][ C2] ? __asan_memcpy+0x3c/0x60 [ 13.447961][ C2] ? __skb_clone+0x55e/0x760 [ 13.447963][ C2] tcf_mirred_to_dev+0x941/0x1500 [act_mirred] [ 13.447965][ C2] ? common_startup_64+0x99/0x148 [ 13.447966][ C2] ? __asan_memset+0x27/0x50 [ 13.447968][ C2] tcf_mirred_act+0x618/0xe10 [act_mirred] [ 13.447970][ C2] tcf_action_exec.part.0+0xca/0x3c0 [ 13.447972][ C2] __tcf_classify.constprop.0+0xcf/0x3a0 [ 13.447975][ C2] tcf_classify+0x67/0x90 [ 13.447977][ C2] ? tcf_qevent_handle+0x3d0/0x3d0 [ 13.447979][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.447980][ C2] tc_run+0x3d0/0x7e0 [ 13.447982][ C2] ? netdev_offload_xstats_push_delta+0x430/0x430 [ 13.447984][ C2] __netif_receive_skb_core.constprop.0+0xdb7/0x2bb0 [ 13.447987][ C2] ? do_xdp_generic+0x4a0/0x4a0 [ 13.447990][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.447992][ C2] ? mark_usage+0x61/0x170 [ 13.447993][ C2] ? __lock_acquire+0x518/0xc20 [ 13.447995][ C2] ? process_backlog+0x3f1/0x14c0 [ 13.447997][ C2] __netif_receive_skb_one_core+0xa2/0x180 [ 13.447999][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 13.448000][ C2] ? process_backlog+0x3f1/0x14c0 [ 13.448002][ C2] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 13.448003][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.448005][ C2] process_backlog+0x431/0x14c0 [ 13.448007][ C2] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 13.448010][ C2] __napi_poll+0xa7/0x3b0 [ 13.448012][ C2] net_rx_action+0x513/0xf50 [ 13.448014][ C2] ? __napi_poll+0x3b0/0x3b0 [ 13.448016][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 13.448018][ C2] ? sched_balance_softirq+0x29c/0x320 [ 13.448021][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.448022][ C2] handle_softirqs+0x1d3/0x900 [ 13.448024][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 13.448026][ C2] ? _local_bh_enable+0xc0/0xc0 [ 13.448028][ C2] __irq_exit_rcu+0x145/0x1c0 [ 13.448029][ C2] irq_exit_rcu+0xe/0x30 [ 13.448031][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 13.448032][ C2] [ 13.448033][ C2] [ 13.448033][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 13.448035][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.448036][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 13.448038][ C2] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 13.448039][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 13.448041][ C2] RAX: 00000000000544df RBX: ff11000001c1c740 RCX: ffffffff9fd06247 [ 13.448042][ C2] RDX: ff11000001c1c740 RSI: ffffffffa2f11011 RDI: ffffffffa28949e0 [ 13.448043][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 13.448044][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 13.448044][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 13.448046][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.448048][ C2] default_idle+0x9/0x10 [ 13.448049][ C2] default_idle_call+0x6e/0xb0 [ 13.448051][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.448052][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 13.448054][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 13.448056][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.448058][ C2] ? rcu_is_watching+0x16/0xd0 [ 13.448060][ C2] do_idle+0xd8/0x190 [ 13.448061][ C2] cpu_startup_entry+0x53/0x70 [ 13.448063][ C2] start_secondary+0x204/0x2b0 [ 13.448064][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 13.448066][ C2] common_startup_64+0x13e/0x148 [ 13.448069][ C2]