[ 9.653026][ T187] ip (187) used greatest stack depth: 23808 bytes left [ 9.653046][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.653049][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 9.653051][ T187] preempt_count: 2, expected: 0 [ 9.653052][ T187] RCU nest depth: 0, expected: 0 [ 9.653054][ T187] locks held by ip/187: 5, last CPU#3: [ 9.653057][ T187] #0: ffffffff9e6027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.653073][ T187] #1: ffffffff9e769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.653082][ T187] #2: ffffffff9e769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.653088][ T187] #3: ffffffff9e689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.653094][ T187] #4: ffffffff9e689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.653101][ T187] irq event stamp: 34762 [ 9.653102][ T187] hardirqs last enabled at (34761): [] __down_trylock_console_sem+0x86/0xa0 [ 9.653106][ T187] hardirqs last disabled at (34762): [] console_emit_next_record+0x3d4/0x4c0 [ 9.653109][ T187] softirqs last enabled at (34468): [] handle_softirqs+0x67c/0x900 [ 9.653112][ T187] softirqs last disabled at (33587): [] __irq_exit_rcu+0x145/0x1c0 [ 9.653116][ T187] Preemption disabled at: [ 9.653117][ T187] [<0000000000000000>] 0x0 [ 9.653126][ T187] CPU: 3 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.653131][ T187] Tainted: [W]=WARN [ 9.653132][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.653134][ T187] Call Trace: [ 9.653136][ T187] [ 9.653138][ T187] dump_stack_lvl+0x6f/0xa0 [ 9.653147][ T187] __might_resched.cold+0x1fe/0x2c1 [ 9.653153][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.653159][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 9.653167][ T187] __kmalloc_noprof+0x443/0x760 [ 9.653171][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 9.653180][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 9.653184][ T187] alloc_buf.isra.0+0x4b/0x260 [ 9.653190][ T187] put_chars+0x1e1/0x2f0 [ 9.653195][ T187] ? __send_to_port+0x420/0x420 [ 9.653197][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.653204][ T187] ? validate_chain+0x38b/0xc20 [ 9.653214][ T187] hvc_console_print+0x292/0x780 [ 9.653225][ T187] ? hvc_write+0x3a0/0x3a0 [ 9.653229][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.653234][ T187] ? lock_acquire+0x13c/0x160 [ 9.653242][ T187] console_emit_next_record+0x22f/0x4c0 [ 9.653249][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 9.653252][ T187] ? console_flush_one_record+0x106/0x710 [ 9.653258][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.653262][ T187] ? lock_acquire+0x13c/0x160 [ 9.653269][ T187] console_flush_one_record+0x46f/0x710 [ 9.653277][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 9.653280][ T187] ? __lock_acquire+0x518/0xc20 [ 9.653291][ T187] console_unlock+0xee/0x1f0 [ 9.653296][ T187] ? console_flush_one_record+0x710/0x710 [ 9.653299][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.653303][ T187] ? lock_acquire+0x60/0x160 [ 9.653310][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.653313][ T187] ? vprintk_emit+0x320/0x3e0 [ 9.653319][ T187] vprintk_emit+0x37c/0x3e0 [ 9.653326][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 9.653332][ T187] ? __lock_acquire+0x518/0xc20 [ 9.653339][ T187] _printk+0xc7/0x100 [ 9.653344][ T187] ? snapshot_read.cold+0x21/0x21 [ 9.653349][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.653354][ T187] ? __rwlock_init+0x150/0x150 [ 9.653362][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.653366][ T187] do_exit.cold+0x82/0x9c [ 9.653372][ T187] ? exit_notify+0x890/0x890 [ 9.653375][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 9.653380][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.653388][ T187] do_group_exit+0xb8/0x370 [ 9.653394][ T187] __x64_sys_exit_group+0x3c/0x50 [ 9.653397][ T187] x64_sys_call+0x1567/0x1570 [ 9.653400][ T187] do_syscall_64+0xff/0x530 [ 9.653405][ T187] ? exc_page_fault+0xee/0x100 [ 9.653410][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.653414][ T187] RIP: 0033:0x7f4abe60b1b8 [ 9.653417][ T187] Code: Unable to access opcode bytes at 0x7f4abe60b18e. [ 9.653419][ T187] RSP: 002b:00007ffffeb948c8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.653422][ T187] RAX: ffffffffffffffda RBX: 00007f4abe73bf88 RCX: 00007f4abe60b1b8 [ 9.653424][ T187] RDX: 00007f4abe355fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.653426][ T187] RBP: 00007ffffeb94920 R08: 0000000000000000 R09: 0000000000008010 [ 9.653428][ T187] R10: 00007ffffeb946e0 R11: 0000000000000246 R12: 0000000000000001 [ 9.653429][ T187] R13: 0000000000000000 R14: 00007f4abe73a680 R15: 00007f4abe73bfa0 [ 9.653444][ T187] [ 11.711131][ T265] gre: GRE over IPv4 demultiplexer driver [ 11.711150][ T265] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.711152][ T265] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 265, name: modprobe [ 11.711154][ T265] preempt_count: 1, expected: 0 [ 11.711155][ T265] RCU nest depth: 0, expected: 0 [ 11.711156][ T265] locks held by modprobe/265: 4, last CPU#3: [ 11.711158][ T265] #0: ffffffff9e769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.711171][ T265] #1: ffffffff9e769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.711176][ T265] #2: ffffffff9e689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.711180][ T265] #3: ffffffff9e689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.711184][ T265] irq event stamp: 23862 [ 11.711185][ T265] hardirqs last enabled at (23861): [] __down_trylock_console_sem+0x86/0xa0 [ 11.711188][ T265] hardirqs last disabled at (23862): [] console_emit_next_record+0x3d4/0x4c0 [ 11.711190][ T265] softirqs last enabled at (22208): [] handle_softirqs+0x67c/0x900 [ 11.711193][ T265] softirqs last disabled at (22203): [] __irq_exit_rcu+0x145/0x1c0 [ 11.711195][ T265] Preemption disabled at: [ 11.711196][ T265] [] vprintk_emit+0x31b/0x3e0 [ 11.711202][ T265] CPU: 3 UID: 0 PID: 265 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.711205][ T265] Tainted: [W]=WARN [ 11.711206][ T265] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.711208][ T265] Call Trace: [ 11.711209][ T265] [ 11.711211][ T265] dump_stack_lvl+0x6f/0xa0 [ 11.711217][ T265] ? vprintk_emit+0x31b/0x3e0 [ 11.711219][ T265] __might_resched.cold+0x1fe/0x2c1 [ 11.711224][ T265] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.711228][ T265] ? __kmalloc_noprof+0xdb/0x760 [ 11.711233][ T265] __kmalloc_noprof+0x443/0x760 [ 11.711235][ T265] ? alloc_buf.isra.0+0x4b/0x260 [ 11.711241][ T265] ? do_raw_spin_unlock+0x59/0x250 [ 11.711244][ T265] alloc_buf.isra.0+0x4b/0x260 [ 11.711247][ T265] put_chars+0x1e1/0x2f0 [ 11.711249][ T265] ? desc_read_finalized_seq+0x79/0x120 [ 11.711251][ T265] ? __send_to_port+0x420/0x420 [ 11.711254][ T265] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.711257][ T265] ? validate_chain+0x38b/0xc20 [ 11.711262][ T265] hvc_console_print+0x292/0x780 [ 11.711264][ T265] ? __lock_acquire+0x518/0xc20 [ 11.711266][ T265] ? __lock_acquire+0x518/0xc20 [ 11.711270][ T265] ? hvc_write+0x3a0/0x3a0 [ 11.711272][ T265] ? console_emit_next_record+0x1df/0x4c0 [ 11.711275][ T265] ? rcu_is_watching+0x16/0xd0 [ 11.711278][ T265] ? lock_acquire+0x13c/0x160 [ 11.711282][ T265] console_emit_next_record+0x22f/0x4c0 [ 11.711286][ T265] ? devkmsg_read+0x4b0/0x4b0 [ 11.711288][ T265] ? console_flush_one_record+0x106/0x710 [ 11.711291][ T265] ? rcu_is_watching+0x16/0xd0 [ 11.711293][ T265] ? lock_acquire+0x13c/0x160 [ 11.711297][ T265] console_flush_one_record+0x46f/0x710 [ 11.711301][ T265] ? console_emit_next_record+0x4c0/0x4c0 [ 11.711303][ T265] ? __lock_acquire+0x518/0xc20 [ 11.711308][ T265] console_unlock+0xee/0x1f0 [ 11.711311][ T265] ? console_flush_one_record+0x710/0x710 [ 11.711313][ T265] ? rcu_is_watching+0x16/0xd0 [ 11.711316][ T265] ? lock_acquire+0x60/0x160 [ 11.711319][ T265] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.711321][ T265] ? vprintk_emit+0x320/0x3e0 [ 11.711324][ T265] vprintk_emit+0x37c/0x3e0 [ 11.711328][ T265] ? wake_up_klogd_work_func+0x90/0x90 [ 11.711334][ T265] ? gre_exit+0x3f0/0x3f0 [gre] [ 11.711337][ T265] _printk+0xc7/0x100 [ 11.711341][ T265] ? snapshot_read.cold+0x21/0x21 [ 11.711347][ T265] ? do_one_initcall+0x113/0x4f0 [ 11.711350][ T265] gre_init+0x15/0x1000 [gre] [ 11.711352][ T265] do_one_initcall+0x124/0x4f0 [ 11.711355][ T265] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 11.711360][ T265] ? do_init_module+0xae/0x8a0 [ 11.711363][ T265] ? kasan_unpoison+0x44/0x70 [ 11.711367][ T265] do_init_module+0x2ad/0x8a0 [ 11.711371][ T265] ? lockdep_hardirqs_on+0x91/0x130 [ 11.711375][ T265] ? free_module+0x470/0x470 [ 11.711379][ T265] ? kfree+0x123/0x590 [ 11.711383][ T265] load_module+0x1424/0x1bb0 [ 11.711390][ T265] ? layout_and_allocate.isra.0+0x680/0x680 [ 11.711395][ T265] ? backing_file_read_iter+0x541/0x790 [ 11.711399][ T265] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.711403][ T265] init_module_from_file+0x11f/0x1a0 [ 11.711406][ T265] ? __do_sys_init_module+0x220/0x220 [ 11.711414][ T265] ? __rwlock_init+0x150/0x150 [ 11.711418][ T265] ? do_raw_spin_unlock+0x59/0x250 [ 11.711421][ T265] idempotent_init_module+0x37b/0x7c0 [ 11.711424][ T265] ? init_module_from_file+0x1a0/0x1a0 [ 11.711431][ T265] ? rcu_is_watching+0x16/0xd0 [ 11.711433][ T265] ? cap_capable+0x1d7/0x3d0 [ 11.711439][ T265] __x64_sys_finit_module+0xdf/0x190 [ 11.711441][ T265] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.711445][ T265] do_syscall_64+0xff/0x530 [ 11.711447][ T265] ? exc_page_fault+0xee/0x100 [ 11.711450][ T265] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.711452][ T265] RIP: 0033:0x7f9d030db37d [ 11.711455][ T265] 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.711457][ T265] RSP: 002b:00007ffc2df23da8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 11.711460][ T265] RAX: ffffffffffffffda RBX: 00005630dffd0a40 RCX: 00007f9d030db37d [ 11.711461][ T265] RDX: 0000000000000000 RSI: 00005630bedc9ffd RDI: 0000000000000001 [ 11.711462][ T265] RBP: 00007ffc2df23e40 R08: 0000000000000000 R09: 0000000000000000 [ 11.711463][ T265] R10: 0000000000000000 R11: 0000000000000246 R12: 00005630bedc9ffd [ 11.711464][ T265] R13: 00005630dffd0a00 R14: 0000000000040000 R15: 0000000000000000 [ 11.711471][ T265] [ 11.751793][ T265] ip_gre: GRE over IPv4 tunneling driver [ 12.442677][ T289] 8021q: 802.1Q VLAN Support v1.8 [ 13.654394][ T328] GACT probability NOT on [ 13.654414][ T328] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.654417][ T328] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 328, name: modprobe [ 13.654419][ T328] preempt_count: 1, expected: 0 [ 13.654420][ T328] RCU nest depth: 0, expected: 0 [ 13.654421][ T328] locks held by modprobe/328: 4, last CPU#2: [ 13.654423][ T328] #0: ffffffff9e769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.654435][ T328] #1: ffffffff9e769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.654439][ T328] #2: ffffffff9e689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.654444][ T328] #3: ffffffff9e689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.654448][ T328] irq event stamp: 18866 [ 13.654449][ T328] hardirqs last enabled at (18865): [] __down_trylock_console_sem+0x86/0xa0 [ 13.654451][ T328] hardirqs last disabled at (18866): [] console_emit_next_record+0x3d4/0x4c0 [ 13.654453][ T328] softirqs last enabled at (17524): [] handle_softirqs+0x67c/0x900 [ 13.654457][ T328] softirqs last disabled at (17519): [] __irq_exit_rcu+0x145/0x1c0 [ 13.654459][ T328] Preemption disabled at: [ 13.654460][ T328] [] vprintk_emit+0x31b/0x3e0 [ 13.654466][ T328] CPU: 2 UID: 0 PID: 328 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.654469][ T328] Tainted: [W]=WARN [ 13.654470][ T328] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.654473][ T328] Call Trace: [ 13.654474][ T328] [ 13.654476][ T328] dump_stack_lvl+0x6f/0xa0 [ 13.654482][ T328] ? vprintk_emit+0x31b/0x3e0 [ 13.654484][ T328] __might_resched.cold+0x1fe/0x2c1 [ 13.654489][ T328] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.654493][ T328] ? __kmalloc_noprof+0xdb/0x760 [ 13.654498][ T328] __kmalloc_noprof+0x443/0x760 [ 13.654500][ T328] ? alloc_buf.isra.0+0x4b/0x260 [ 13.654506][ T328] ? do_raw_spin_unlock+0x59/0x250 [ 13.654509][ T328] alloc_buf.isra.0+0x4b/0x260 [ 13.654512][ T328] put_chars+0x1e1/0x2f0 [ 13.654514][ T328] ? desc_read_finalized_seq+0x79/0x120 [ 13.654517][ T328] ? __send_to_port+0x420/0x420 [ 13.654519][ T328] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.654523][ T328] ? validate_chain+0x38b/0xc20 [ 13.654527][ T328] hvc_console_print+0x292/0x780 [ 13.654530][ T328] ? __lock_acquire+0x518/0xc20 [ 13.654531][ T328] ? __lock_acquire+0x518/0xc20 [ 13.654536][ T328] ? hvc_write+0x3a0/0x3a0 [ 13.654537][ T328] ? console_emit_next_record+0x1df/0x4c0 [ 13.654540][ T328] ? rcu_is_watching+0x16/0xd0 [ 13.654544][ T328] ? lock_acquire+0x13c/0x160 [ 13.654547][ T328] console_emit_next_record+0x22f/0x4c0 [ 13.654551][ T328] ? devkmsg_read+0x4b0/0x4b0 [ 13.654553][ T328] ? console_flush_one_record+0x106/0x710 [ 13.654556][ T328] ? rcu_is_watching+0x16/0xd0 [ 13.654559][ T328] ? lock_acquire+0x13c/0x160 [ 13.654563][ T328] console_flush_one_record+0x46f/0x710 [ 13.654567][ T328] ? console_emit_next_record+0x4c0/0x4c0 [ 13.654569][ T328] ? __lock_acquire+0x518/0xc20 [ 13.654574][ T328] console_unlock+0xee/0x1f0 [ 13.654576][ T328] ? console_flush_one_record+0x710/0x710 [ 13.654578][ T328] ? rcu_is_watching+0x16/0xd0 [ 13.654581][ T328] ? lock_acquire+0x60/0x160 [ 13.654584][ T328] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.654586][ T328] ? vprintk_emit+0x320/0x3e0 [ 13.654589][ T328] vprintk_emit+0x37c/0x3e0 [ 13.654593][ T328] ? wake_up_klogd_work_func+0x90/0x90 [ 13.654599][ T328] ? gre_exit+0x3f0/0x3f0 [gre] [ 13.654602][ T328] _printk+0xc7/0x100 [ 13.654605][ T328] ? snapshot_read.cold+0x21/0x21 [ 13.654611][ T328] ? do_one_initcall+0x113/0x4f0 [ 13.654615][ T328] gact_init_module+0x15/0x1000 [act_gact] [ 13.654617][ T328] do_one_initcall+0x124/0x4f0 [ 13.654620][ T328] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 13.654625][ T328] ? do_init_module+0xae/0x8a0 [ 13.654628][ T328] ? kasan_unpoison+0x44/0x70 [ 13.654632][ T328] do_init_module+0x2ad/0x8a0 [ 13.654636][ T328] ? lockdep_hardirqs_on+0x91/0x130 [ 13.654641][ T328] ? free_module+0x470/0x470 [ 13.654644][ T328] ? kfree+0x123/0x590 [ 13.654648][ T328] load_module+0x1424/0x1bb0 [ 13.654655][ T328] ? layout_and_allocate.isra.0+0x680/0x680 [ 13.654659][ T328] ? __ia32_sys_fsconfig+0x180/0x180 [ 13.654662][ T328] ? find_held_lock+0x2b/0x80 [ 13.654665][ T328] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.654669][ T328] init_module_from_file+0x11f/0x1a0 [ 13.654672][ T328] ? __do_sys_init_module+0x220/0x220 [ 13.654680][ T328] ? __rwlock_init+0x150/0x150 [ 13.654683][ T328] ? do_raw_spin_unlock+0x59/0x250 [ 13.654686][ T328] idempotent_init_module+0x37b/0x7c0 [ 13.654690][ T328] ? init_module_from_file+0x1a0/0x1a0 [ 13.654696][ T328] ? rcu_is_watching+0x16/0xd0 [ 13.654698][ T328] ? cap_capable+0x1d7/0x3d0 [ 13.654705][ T328] __x64_sys_finit_module+0xdf/0x190 [ 13.654707][ T328] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.654711][ T328] do_syscall_64+0xff/0x530 [ 13.654712][ T328] ? exc_page_fault+0xee/0x100 [ 13.654715][ T328] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.654718][ T328] RIP: 0033:0x7f671833a37d [ 13.654721][ T328] 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 [ 13.654723][ T328] RSP: 002b:00007ffc0dbdc568 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 13.654730][ T328] RAX: ffffffffffffffda RBX: 000055ea80f78810 RCX: 00007f671833a37d [ 13.654731][ T328] RDX: 0000000000000000 RSI: 000055ea624fdffd RDI: 0000000000000000 [ 13.654732][ T328] RBP: 00007ffc0dbdc600 R08: 0000000000000000 R09: 0000000000000000 [ 13.654733][ T328] R10: 0000000000000000 R11: 0000000000000246 R12: 000055ea624fdffd [ 13.654734][ T328] R13: 000055ea80f78960 R14: 0000000000040000 R15: 0000000000000000 [ 13.654741][ T328] [ 286.414084][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 286.414123][ C0] [ 286.414124][ C0] ======================================================== [ 286.414126][ C0] WARNING: possible irq lock inversion dependency detected [ 286.414128][ C0] 7.2.0-virtme #1 Tainted: G W [ 286.414129][ C0] -------------------------------------------------------- [ 286.414130][ C0] ping6/12311 just changed the state of lock: [ 286.414131][ C0] ffffffff9e689660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 286.414144][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 286.414145][ C0] (fs_reclaim){+.+.}-{0:0} [ 286.414147][ C0] [ 286.414147][ C0] [ 286.414147][ C0] and interrupts could create inverse lock ordering between them. [ 286.414147][ C0] [ 286.414148][ C0] [ 286.414148][ C0] other info that might help us debug this: [ 286.414148][ C0] Possible interrupt unsafe locking scenario: [ 286.414148][ C0] [ 286.414149][ C0] CPU0 CPU1 [ 286.414149][ C0] ---- ---- [ 286.414149][ C0] lock(fs_reclaim); [ 286.414151][ C0] local_irq_disable(); [ 286.414151][ C0] lock(console_owner); [ 286.414152][ C0] lock(fs_reclaim); [ 286.414153][ C0] [ 286.414153][ C0] lock(console_owner); [ 286.414154][ C0] [ 286.414154][ C0] *** DEADLOCK *** [ 286.414154][ C0] [ 286.414155][ C0] locks held by ping6/12311: 6, last CPU#0: [ 286.414156][ C0] #0: ffffffff9e9ae1b8 (remove_cache_srcu){.+.+}-{0:0}, at: kasan_quarantine_reduce+0x84/0x240 [ 286.414162][ C0] #1: ffffffff9e780b00 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 286.414166][ C0] #2: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 286.414172][ C0] #3: ffffffff9e7ce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 286.414175][ C0] #4: ffffffff9e769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 286.414179][ C0] #5: ffffffff9e769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 286.414182][ C0] [ 286.414182][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 286.414187][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 286.414189][ C0] HARDIRQ-ON-W at: [ 286.414191][ C0] __lock_acquire+0x388/0xc20 [ 286.414194][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414195][ C0] fs_reclaim_acquire+0xd5/0x120 [ 286.414198][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 286.414200][ C0] kthread_create_worker_on_node+0xea/0x210 [ 286.414202][ C0] workqueue_init+0x2a/0x680 [ 286.414206][ C0] kernel_init_freeable+0x2fe/0x630 [ 286.414208][ C0] kernel_init+0x21/0x150 [ 286.414212][ C0] ret_from_fork+0x474/0x6b0 [ 286.414215][ C0] ret_from_fork_asm+0x11/0x20 [ 286.414218][ C0] SOFTIRQ-ON-W at: [ 286.414219][ C0] __lock_acquire+0x388/0xc20 [ 286.414220][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414222][ C0] fs_reclaim_acquire+0xd5/0x120 [ 286.414223][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 286.414224][ C0] kthread_create_worker_on_node+0xea/0x210 [ 286.414225][ C0] workqueue_init+0x2a/0x680 [ 286.414227][ C0] kernel_init_freeable+0x2fe/0x630 [ 286.414228][ C0] kernel_init+0x21/0x150 [ 286.414229][ C0] ret_from_fork+0x474/0x6b0 [ 286.414230][ C0] ret_from_fork_asm+0x11/0x20 [ 286.414231][ C0] INITIAL USE at: [ 286.414232][ C0] __lock_acquire+0x388/0xc20 [ 286.414234][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414235][ C0] fs_reclaim_acquire+0xd5/0x120 [ 286.414236][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 286.414237][ C0] kthread_create_worker_on_node+0xea/0x210 [ 286.414239][ C0] workqueue_init+0x2a/0x680 [ 286.414240][ C0] kernel_init_freeable+0x2fe/0x630 [ 286.414241][ C0] kernel_init+0x21/0x150 [ 286.414242][ C0] ret_from_fork+0x474/0x6b0 [ 286.414243][ C0] ret_from_fork_asm+0x11/0x20 [ 286.414244][ C0] } [ 286.414245][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 286.414249][ C0] ... acquired at: [ 286.414250][ C0] __lock_acquire+0x518/0xc20 [ 286.414251][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414253][ C0] fs_reclaim_acquire+0xd5/0x120 [ 286.414253][ C0] __kmalloc_noprof+0xd3/0x760 [ 286.414255][ C0] alloc_buf.isra.0+0x4b/0x260 [ 286.414258][ C0] put_chars+0x1e1/0x2f0 [ 286.414259][ C0] hvc_console_print+0x292/0x780 [ 286.414261][ C0] console_emit_next_record+0x22f/0x4c0 [ 286.414263][ C0] console_flush_one_record+0x46f/0x710 [ 286.414264][ C0] console_unlock+0xee/0x1f0 [ 286.414266][ C0] vprintk_emit+0x37c/0x3e0 [ 286.414267][ C0] _printk+0xc7/0x100 [ 286.414270][ C0] sock_register+0x12d/0x290 [ 286.414273][ C0] inet6_init+0x1a6/0x3e0 [ 286.414276][ C0] do_one_initcall+0x124/0x4f0 [ 286.414278][ C0] kernel_init_freeable+0x596/0x630 [ 286.414278][ C0] kernel_init+0x21/0x150 [ 286.414280][ C0] ret_from_fork+0x474/0x6b0 [ 286.414281][ C0] ret_from_fork_asm+0x11/0x20 [ 286.414282][ C0] [ 286.414283][ C0] -> (console_owner){..-.}-{0:0} { [ 286.414284][ C0] IN-SOFTIRQ-W at: [ 286.414285][ C0] __lock_acquire+0x388/0xc20 [ 286.414287][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414288][ C0] console_lock_spinning_enable+0x5c/0x60 [ 286.414290][ C0] console_emit_next_record+0x1d1/0x4c0 [ 286.414291][ C0] console_flush_one_record+0x46f/0x710 [ 286.414293][ C0] console_unlock+0xee/0x1f0 [ 286.414294][ C0] vprintk_emit+0x37c/0x3e0 [ 286.414296][ C0] _printk+0xc7/0x100 [ 286.414297][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 286.414299][ C0] call_timer_fn+0x160/0x4d0 [ 286.414301][ C0] __run_timers+0x68f/0xaa0 [ 286.414302][ C0] run_timer_softirq+0xf0/0x160 [ 286.414304][ C0] handle_softirqs+0x1d3/0x900 [ 286.414306][ C0] __irq_exit_rcu+0x145/0x1c0 [ 286.414308][ C0] irq_exit_rcu+0xe/0x30 [ 286.414309][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 286.414311][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 286.414313][ C0] lock_acquire.part.0+0x112/0x280 [ 286.414314][ C0] unwind_next_frame+0x192/0x1ea0 [ 286.414315][ C0] __unwind_start+0x42e/0x900 [ 286.414316][ C0] arch_stack_walk+0xb3/0x130 [ 286.414318][ C0] stack_trace_save+0x93/0xc0 [ 286.414320][ C0] set_track_prepare+0x62/0x90 [ 286.414321][ C0] free_to_partial_list+0x1c6/0x310 [ 286.414323][ C0] qlist_free_all+0x5c/0x110 [ 286.414324][ C0] kasan_quarantine_reduce+0x196/0x240 [ 286.414325][ C0] __kasan_slab_alloc+0x4b/0x70 [ 286.414327][ C0] __kmalloc_cache_noprof+0x23e/0x620 [ 286.414329][ C0] ovl_open+0x1ef/0x2f0 [ 286.414332][ C0] do_dentry_open+0x408/0x1310 [ 286.414334][ C0] vfs_open+0x7a/0x440 [ 286.414336][ C0] do_open+0x646/0xdd0 [ 286.414338][ C0] path_openat+0x1d0/0x3b0 [ 286.414340][ C0] do_file_open+0x209/0x480 [ 286.414341][ C0] do_sys_openat2+0xe0/0x170 [ 286.414343][ C0] __x64_sys_openat+0x10e/0x210 [ 286.414344][ C0] do_syscall_64+0xff/0x530 [ 286.414345][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 286.414347][ C0] INITIAL USE at: [ 286.414348][ C0] } [ 286.414348][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 286.414351][ C0] ... acquired at: [ 286.414351][ C0] mark_lock+0x1d7/0xa00 [ 286.414353][ C0] mark_usage+0x42/0x170 [ 286.414354][ C0] __lock_acquire+0x388/0xc20 [ 286.414355][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414357][ C0] console_lock_spinning_enable+0x5c/0x60 [ 286.414358][ C0] console_emit_next_record+0x1d1/0x4c0 [ 286.414360][ C0] console_flush_one_record+0x46f/0x710 [ 286.414361][ C0] console_unlock+0xee/0x1f0 [ 286.414363][ C0] vprintk_emit+0x37c/0x3e0 [ 286.414364][ C0] _printk+0xc7/0x100 [ 286.414365][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 286.414367][ C0] call_timer_fn+0x160/0x4d0 [ 286.414368][ C0] __run_timers+0x68f/0xaa0 [ 286.414369][ C0] run_timer_softirq+0xf0/0x160 [ 286.414371][ C0] handle_softirqs+0x1d3/0x900 [ 286.414372][ C0] __irq_exit_rcu+0x145/0x1c0 [ 286.414373][ C0] irq_exit_rcu+0xe/0x30 [ 286.414374][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 286.414376][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 286.414377][ C0] lock_acquire.part.0+0x112/0x280 [ 286.414378][ C0] unwind_next_frame+0x192/0x1ea0 [ 286.414379][ C0] __unwind_start+0x42e/0x900 [ 286.414380][ C0] arch_stack_walk+0xb3/0x130 [ 286.414382][ C0] stack_trace_save+0x93/0xc0 [ 286.414383][ C0] set_track_prepare+0x62/0x90 [ 286.414384][ C0] free_to_partial_list+0x1c6/0x310 [ 286.414385][ C0] qlist_free_all+0x5c/0x110 [ 286.414386][ C0] kasan_quarantine_reduce+0x196/0x240 [ 286.414387][ C0] __kasan_slab_alloc+0x4b/0x70 [ 286.414388][ C0] __kmalloc_cache_noprof+0x23e/0x620 [ 286.414390][ C0] ovl_open+0x1ef/0x2f0 [ 286.414391][ C0] do_dentry_open+0x408/0x1310 [ 286.414392][ C0] vfs_open+0x7a/0x440 [ 286.414393][ C0] do_open+0x646/0xdd0 [ 286.414394][ C0] path_openat+0x1d0/0x3b0 [ 286.414396][ C0] do_file_open+0x209/0x480 [ 286.414397][ C0] do_sys_openat2+0xe0/0x170 [ 286.414398][ C0] __x64_sys_openat+0x10e/0x210 [ 286.414400][ C0] do_syscall_64+0xff/0x530 [ 286.414401][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 286.414402][ C0] [ 286.414402][ C0] [ 286.414402][ C0] stack backtrace: [ 286.414405][ C0] CPU: 0 UID: 0 PID: 12311 Comm: ping6 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 286.414408][ C0] Tainted: [W]=WARN [ 286.414409][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 286.414410][ C0] Call Trace: [ 286.414411][ C0] [ 286.414413][ C0] dump_stack_lvl+0x6f/0xa0 [ 286.414418][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 286.414420][ C0] mark_lock_irq+0x989/0x9c0 [ 286.414422][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 286.414425][ C0] mark_lock+0x1d7/0xa00 [ 286.414426][ C0] mark_usage+0x42/0x170 [ 286.414428][ C0] __lock_acquire+0x388/0xc20 [ 286.414430][ C0] lock_acquire.part.0+0xd4/0x280 [ 286.414432][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 286.414434][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414437][ C0] ? lock_acquire+0x13c/0x160 [ 286.414439][ C0] console_lock_spinning_enable+0x5c/0x60 [ 286.414440][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 286.414442][ C0] console_emit_next_record+0x1d1/0x4c0 [ 286.414444][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 286.414446][ C0] ? console_flush_one_record+0x106/0x710 [ 286.414448][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414449][ C0] ? lock_acquire+0x13c/0x160 [ 286.414451][ C0] console_flush_one_record+0x46f/0x710 [ 286.414453][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 286.414455][ C0] ? __lock_acquire+0x518/0xc20 [ 286.414457][ C0] console_unlock+0xee/0x1f0 [ 286.414459][ C0] ? console_flush_one_record+0x710/0x710 [ 286.414461][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414462][ C0] ? lock_acquire+0x60/0x160 [ 286.414464][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 286.414466][ C0] ? vprintk_emit+0x320/0x3e0 [ 286.414468][ C0] vprintk_emit+0x37c/0x3e0 [ 286.414470][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 286.414472][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 286.414474][ C0] _printk+0xc7/0x100 [ 286.414476][ C0] ? snapshot_read.cold+0x21/0x21 [ 286.414478][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 286.414479][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 286.414482][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 286.414483][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 286.414485][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 286.414486][ C0] call_timer_fn+0x160/0x4d0 [ 286.414488][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 286.414490][ C0] ? debug_object_active_state+0x430/0x430 [ 286.414493][ C0] ? find_held_lock+0x2b/0x80 [ 286.414495][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 286.414496][ C0] ? mark_held_locks+0x40/0x70 [ 286.414498][ C0] __run_timers+0x68f/0xaa0 [ 286.414500][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 286.414502][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 286.414504][ C0] ? __lock_acquire+0x518/0xc20 [ 286.414507][ C0] ? __rwlock_init+0x150/0x150 [ 286.414509][ C0] run_timer_softirq+0xf0/0x160 [ 286.414511][ C0] ? __run_timers+0xaa0/0xaa0 [ 286.414513][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414514][ C0] handle_softirqs+0x1d3/0x900 [ 286.414516][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 286.414518][ C0] ? _local_bh_enable+0xc0/0xc0 [ 286.414520][ C0] __irq_exit_rcu+0x145/0x1c0 [ 286.414521][ C0] irq_exit_rcu+0xe/0x30 [ 286.414522][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 286.414524][ C0] [ 286.414525][ C0] [ 286.414525][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 286.414527][ C0] RIP: 0010:lock_acquire.part.0+0x112/0x280 [ 286.414529][ C0] Code: ff 65 0f c1 05 ff a7 38 04 83 f8 01 4c 8b 14 24 0f 85 35 01 00 00 9c 58 f6 c4 02 0f 85 52 01 00 00 4d 85 d2 0f 85 1b 01 00 00 <48> 8b 44 24 30 65 48 2b 05 d1 62 38 04 0f 85 48 01 00 00 48 83 c4 [ 286.414531][ C0] RSP: 0018:ffa000002465f4a0 EFLAGS: 00000206 [ 286.414534][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: 5eaeee35fd653602 [ 286.414535][ C0] RDX: 0000000000000000 RSI: ffffffff9e221b20 RDI: ffffffff9dc8d8e0 [ 286.414536][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100001df2d1f8 [ 286.414537][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffff9e780b00 [ 286.414538][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 286.414540][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414542][ C0] ? lock_acquire+0x13c/0x160 [ 286.414544][ C0] unwind_next_frame+0x192/0x1ea0 [ 286.414545][ C0] ? unwind_next_frame+0x17e/0x1ea0 [ 286.414546][ C0] ? __unwind_start+0x357/0x900 [ 286.414548][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 286.414550][ C0] ? get_stack_info_noinstr+0x1a/0x120 [ 286.414552][ C0] __unwind_start+0x42e/0x900 [ 286.414553][ C0] ? write_profile+0xf0/0xf0 [ 286.414555][ C0] arch_stack_walk+0xb3/0x130 [ 286.414557][ C0] ? __unwind_start+0x357/0x900 [ 286.414559][ C0] stack_trace_save+0x93/0xc0 [ 286.414560][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 286.414563][ C0] set_track_prepare+0x62/0x90 [ 286.414565][ C0] free_to_partial_list+0x1c6/0x310 [ 286.414566][ C0] ? qlist_free_all+0x57/0x110 [ 286.414568][ C0] qlist_free_all+0x5c/0x110 [ 286.414570][ C0] kasan_quarantine_reduce+0x196/0x240 [ 286.414571][ C0] __kasan_slab_alloc+0x4b/0x70 [ 286.414573][ C0] __kmalloc_cache_noprof+0x23e/0x620 [ 286.414575][ C0] ? ovl_open+0x1ef/0x2f0 [ 286.414577][ C0] ovl_open+0x1ef/0x2f0 [ 286.414579][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 286.414581][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 286.414582][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 286.414584][ C0] do_dentry_open+0x408/0x1310 [ 286.414586][ C0] vfs_open+0x7a/0x440 [ 286.414588][ C0] ? may_open+0xe6/0x330 [ 286.414590][ C0] do_open+0x646/0xdd0 [ 286.414591][ C0] ? open_last_lookups+0x7e3/0x1030 [ 286.414593][ C0] path_openat+0x1d0/0x3b0 [ 286.414594][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 286.414596][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 286.414598][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 286.414601][ C0] do_file_open+0x209/0x480 [ 286.414602][ C0] ? path_openat+0x3b0/0x3b0 [ 286.414604][ C0] ? __lock_acquire+0x518/0xc20 [ 286.414606][ C0] ? page_get_link_raw+0x70/0x70 [ 286.414608][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 286.414611][ C0] ? alloc_fd+0x33a/0x600 [ 286.414614][ C0] do_sys_openat2+0xe0/0x170 [ 286.414616][ C0] ? build_open_flags+0x6e0/0x6e0 [ 286.414619][ C0] __x64_sys_openat+0x10e/0x210 [ 286.414621][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 286.414622][ C0] ? fpregs_restore_userregs+0x14b/0x300 [ 286.414624][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414625][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 286.414628][ C0] do_syscall_64+0xff/0x530 [ 286.414629][ C0] ? exc_page_fault+0xee/0x100 [ 286.414631][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 286.414632][ C0] RIP: 0033:0x7f98833536e5 [ 286.414635][ C0] Code: 48 89 e5 48 89 55 e0 41 83 e2 40 75 28 89 f2 f7 d2 81 e2 00 00 41 00 74 1c 89 f2 bf 9c ff ff ff 48 89 c6 b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 23 5d c3 90 48 8d 4d 10 c7 45 b8 10 00 00 00 [ 286.414636][ C0] RSP: 002b:00007ffd3d4c6130 EFLAGS: 00000206 ORIG_RAX: 0000000000000101 [ 286.414638][ C0] RAX: ffffffffffffffda RBX: 00007ffd3d4c63c0 RCX: 00007f98833536e5 [ 286.414638][ C0] RDX: 0000000000080000 RSI: 00007ffd3d4c61b0 RDI: 00000000ffffff9c [ 286.414639][ C0] RBP: 00007ffd3d4c6130 R08: 00007ffd3d4c63a7 R09: 0000000000000000 [ 286.414640][ C0] R10: 0000000000000000 R11: 0000000000000206 R12: 00007ffd3d4c61b0 [ 286.414641][ C0] R13: 00007ffd3d4c61b0 R14: 00007f9883325000 R15: 0000000000000000 [ 286.414643][ C0] [ 286.414647][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 286.414648][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12311, name: ping6 [ 286.414650][ C0] preempt_count: 102, expected: 0 [ 286.414650][ C0] RCU nest depth: 1, expected: 0 [ 286.414651][ C0] INFO: lockdep is turned off. [ 286.414652][ C0] irq event stamp: 8005 [ 286.414652][ C0] hardirqs last enabled at (8004): [] __down_trylock_console_sem+0x86/0xa0 [ 286.414655][ C0] hardirqs last disabled at (8005): [] console_emit_next_record+0x3d4/0x4c0 [ 286.414656][ C0] softirqs last enabled at (7162): [] handle_softirqs+0x67c/0x900 [ 286.414658][ C0] softirqs last disabled at (7989): [] __irq_exit_rcu+0x145/0x1c0 [ 286.414659][ C0] Preemption disabled at: [ 286.414660][ C0] [] handle_softirqs+0xf6/0x900 [ 286.414662][ C0] CPU: 0 UID: 0 PID: 12311 Comm: ping6 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 286.414664][ C0] Tainted: [W]=WARN [ 286.414665][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 286.414665][ C0] Call Trace: [ 286.414666][ C0] [ 286.414667][ C0] dump_stack_lvl+0x6f/0xa0 [ 286.414669][ C0] ? handle_softirqs+0xf6/0x900 [ 286.414670][ C0] __might_resched.cold+0x1fe/0x2c1 [ 286.414673][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 286.414676][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414678][ C0] __kmalloc_noprof+0x443/0x760 [ 286.414680][ C0] ? __rwlock_init+0x150/0x150 [ 286.414681][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 286.414683][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 286.414685][ C0] alloc_buf.isra.0+0x4b/0x260 [ 286.414687][ C0] put_chars+0x1e1/0x2f0 [ 286.414688][ C0] ? __send_to_port+0x420/0x420 [ 286.414690][ C0] ? mark_lock+0x1d7/0xa00 [ 286.414692][ C0] hvc_console_print+0x292/0x780 [ 286.414694][ C0] ? hvc_write+0x3a0/0x3a0 [ 286.414696][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414697][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414700][ C0] console_emit_next_record+0x22f/0x4c0 [ 286.414702][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 286.414703][ C0] ? console_flush_one_record+0x106/0x710 [ 286.414706][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414707][ C0] ? lock_acquire+0x13c/0x160 [ 286.414709][ C0] console_flush_one_record+0x46f/0x710 [ 286.414711][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 286.414713][ C0] ? __lock_acquire+0x518/0xc20 [ 286.414715][ C0] console_unlock+0xee/0x1f0 [ 286.414717][ C0] ? console_flush_one_record+0x710/0x710 [ 286.414719][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414720][ C0] ? lock_acquire+0x60/0x160 [ 286.414722][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 286.414724][ C0] ? vprintk_emit+0x320/0x3e0 [ 286.414726][ C0] vprintk_emit+0x37c/0x3e0 [ 286.414728][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 286.414730][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 286.414732][ C0] _printk+0xc7/0x100 [ 286.414734][ C0] ? snapshot_read.cold+0x21/0x21 [ 286.414736][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 286.414737][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 286.414740][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 286.414741][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 286.414743][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 286.414744][ C0] call_timer_fn+0x160/0x4d0 [ 286.414746][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 286.414748][ C0] ? debug_object_active_state+0x430/0x430 [ 286.414749][ C0] ? find_held_lock+0x2b/0x80 [ 286.414753][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 286.414755][ C0] ? mark_held_locks+0x40/0x70 [ 286.414757][ C0] __run_timers+0x68f/0xaa0 [ 286.414758][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 286.414761][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 286.414763][ C0] ? __lock_acquire+0x518/0xc20 [ 286.414766][ C0] ? __rwlock_init+0x150/0x150 [ 286.414768][ C0] run_timer_softirq+0xf0/0x160 [ 286.414770][ C0] ? __run_timers+0xaa0/0xaa0 [ 286.414772][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414774][ C0] handle_softirqs+0x1d3/0x900 [ 286.414775][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 286.414777][ C0] ? _local_bh_enable+0xc0/0xc0 [ 286.414779][ C0] __irq_exit_rcu+0x145/0x1c0 [ 286.414780][ C0] irq_exit_rcu+0xe/0x30 [ 286.414782][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 286.414783][ C0] [ 286.414784][ C0] [ 286.414785][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 286.414786][ C0] RIP: 0010:lock_acquire.part.0+0x112/0x280 [ 286.414788][ C0] Code: ff 65 0f c1 05 ff a7 38 04 83 f8 01 4c 8b 14 24 0f 85 35 01 00 00 9c 58 f6 c4 02 0f 85 52 01 00 00 4d 85 d2 0f 85 1b 01 00 00 <48> 8b 44 24 30 65 48 2b 05 d1 62 38 04 0f 85 48 01 00 00 48 83 c4 [ 286.414789][ C0] RSP: 0018:ffa000002465f4a0 EFLAGS: 00000206 [ 286.414790][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: 5eaeee35fd653602 [ 286.414791][ C0] RDX: 0000000000000000 RSI: ffffffff9e221b20 RDI: ffffffff9dc8d8e0 [ 286.414792][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100001df2d1f8 [ 286.414792][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffff9e780b00 [ 286.414793][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 286.414795][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414797][ C0] ? lock_acquire+0x13c/0x160 [ 286.414799][ C0] unwind_next_frame+0x192/0x1ea0 [ 286.414800][ C0] ? unwind_next_frame+0x17e/0x1ea0 [ 286.414801][ C0] ? __unwind_start+0x357/0x900 [ 286.414803][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 286.414805][ C0] ? get_stack_info_noinstr+0x1a/0x120 [ 286.414807][ C0] __unwind_start+0x42e/0x900 [ 286.414808][ C0] ? write_profile+0xf0/0xf0 [ 286.414810][ C0] arch_stack_walk+0xb3/0x130 [ 286.414812][ C0] ? __unwind_start+0x357/0x900 [ 286.414814][ C0] stack_trace_save+0x93/0xc0 [ 286.414815][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 286.414817][ C0] set_track_prepare+0x62/0x90 [ 286.414820][ C0] free_to_partial_list+0x1c6/0x310 [ 286.414821][ C0] ? qlist_free_all+0x57/0x110 [ 286.414823][ C0] qlist_free_all+0x5c/0x110 [ 286.414824][ C0] kasan_quarantine_reduce+0x196/0x240 [ 286.414826][ C0] __kasan_slab_alloc+0x4b/0x70 [ 286.414828][ C0] __kmalloc_cache_noprof+0x23e/0x620 [ 286.414830][ C0] ? ovl_open+0x1ef/0x2f0 [ 286.414832][ C0] ovl_open+0x1ef/0x2f0 [ 286.414834][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 286.414835][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 286.414837][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 286.414839][ C0] do_dentry_open+0x408/0x1310 [ 286.414841][ C0] vfs_open+0x7a/0x440 [ 286.414843][ C0] ? may_open+0xe6/0x330 [ 286.414844][ C0] do_open+0x646/0xdd0 [ 286.414846][ C0] ? open_last_lookups+0x7e3/0x1030 [ 286.414847][ C0] path_openat+0x1d0/0x3b0 [ 286.414849][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 286.414850][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 286.414852][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 286.414855][ C0] do_file_open+0x209/0x480 [ 286.414856][ C0] ? path_openat+0x3b0/0x3b0 [ 286.414858][ C0] ? __lock_acquire+0x518/0xc20 [ 286.414860][ C0] ? page_get_link_raw+0x70/0x70 [ 286.414862][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 286.414864][ C0] ? alloc_fd+0x33a/0x600 [ 286.414866][ C0] do_sys_openat2+0xe0/0x170 [ 286.414868][ C0] ? build_open_flags+0x6e0/0x6e0 [ 286.414871][ C0] __x64_sys_openat+0x10e/0x210 [ 286.414873][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 286.414875][ C0] ? fpregs_restore_userregs+0x14b/0x300 [ 286.414876][ C0] ? rcu_is_watching+0x16/0xd0 [ 286.414878][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 286.414879][ C0] do_syscall_64+0xff/0x530 [ 286.414880][ C0] ? exc_page_fault+0xee/0x100 [ 286.414882][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 286.414883][ C0] RIP: 0033:0x7f98833536e5 [ 286.414884][ C0] Code: 48 89 e5 48 89 55 e0 41 83 e2 40 75 28 89 f2 f7 d2 81 e2 00 00 41 00 74 1c 89 f2 bf 9c ff ff ff 48 89 c6 b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 23 5d c3 90 48 8d 4d 10 c7 45 b8 10 00 00 00 [ 286.414885][ C0] RSP: 002b:00007ffd3d4c6130 EFLAGS: 00000206 ORIG_RAX: 0000000000000101 [ 286.414886][ C0] RAX: ffffffffffffffda RBX: 00007ffd3d4c63c0 RCX: 00007f98833536e5 [ 286.414887][ C0] RDX: 0000000000080000 RSI: 00007ffd3d4c61b0 RDI: 00000000ffffff9c [ 286.414888][ C0] RBP: 00007ffd3d4c6130 R08: 00007ffd3d4c63a7 R09: 0000000000000000 [ 286.414889][ C0] R10: 0000000000000000 R11: 0000000000000206 R12: 00007ffd3d4c61b0 [ 286.414889][ C0] R13: 00007ffd3d4c61b0 R14: 00007f9883325000 R15: 0000000000000000 [ 286.414892][ C0]