[ 8.724951][ T188] ip (188) used greatest stack depth: 24112 bytes left [ 8.724965][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.724967][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 8.724968][ T188] preempt_count: 2, expected: 0 [ 8.724969][ T188] RCU nest depth: 0, expected: 0 [ 8.724970][ T188] locks held by ip/188: 5, last CPU#3: [ 8.724972][ T188] #0: ffffffff98c027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 8.724984][ T188] #1: ffffffff98d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.724989][ T188] #2: ffffffff98d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.724992][ T188] #3: ffffffff98c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.724996][ T188] #4: ffffffff98c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 8.724999][ T188] irq event stamp: 25644 [ 8.725000][ T188] hardirqs last enabled at (25643): [] __down_trylock_console_sem+0x86/0xa0 [ 8.725002][ T188] hardirqs last disabled at (25644): [] console_emit_next_record+0x3d4/0x4c0 [ 8.725004][ T188] softirqs last enabled at (25638): [] handle_softirqs+0x67c/0x900 [ 8.725006][ T188] softirqs last disabled at (25633): [] __irq_exit_rcu+0x145/0x1c0 [ 8.725008][ T188] Preemption disabled at: [ 8.725008][ T188] [<0000000000000000>] 0x0 [ 8.725014][ T188] CPU: 3 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.725018][ T188] Tainted: [W]=WARN [ 8.725019][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.725020][ T188] Call Trace: [ 8.725022][ T188] [ 8.725023][ T188] dump_stack_lvl+0x6f/0xa0 [ 8.725029][ T188] __might_resched.cold+0x1fe/0x2c1 [ 8.725033][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.725037][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 8.725042][ T188] __kmalloc_noprof+0x443/0x760 [ 8.725044][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 8.725050][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 8.725052][ T188] alloc_buf.isra.0+0x4b/0x260 [ 8.725055][ T188] put_chars+0x1e1/0x2f0 [ 8.725058][ T188] ? __send_to_port+0x420/0x420 [ 8.725059][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.725064][ T188] ? validate_chain+0x38b/0xc20 [ 8.725068][ T188] hvc_console_print+0x292/0x780 [ 8.725074][ T188] ? hvc_write+0x3a0/0x3a0 [ 8.725076][ T188] ? rcu_is_watching+0x16/0xd0 [ 8.725079][ T188] ? lock_acquire+0x13c/0x160 [ 8.725082][ T188] console_emit_next_record+0x22f/0x4c0 [ 8.725086][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 8.725088][ T188] ? console_flush_one_record+0x106/0x710 [ 8.725091][ T188] ? rcu_is_watching+0x16/0xd0 [ 8.725093][ T188] ? lock_acquire+0x13c/0x160 [ 8.725097][ T188] console_flush_one_record+0x46f/0x710 [ 8.725101][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 8.725103][ T188] ? __lock_acquire+0x518/0xc20 [ 8.725108][ T188] console_unlock+0xee/0x1f0 [ 8.725111][ T188] ? console_flush_one_record+0x710/0x710 [ 8.725112][ T188] ? rcu_is_watching+0x16/0xd0 [ 8.725114][ T188] ? lock_acquire+0x60/0x160 [ 8.725118][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.725120][ T188] ? vprintk_emit+0x320/0x3e0 [ 8.725123][ T188] vprintk_emit+0x37c/0x3e0 [ 8.725127][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 8.725130][ T188] ? __lock_acquire+0x518/0xc20 [ 8.725133][ T188] _printk+0xc7/0x100 [ 8.725137][ T188] ? snapshot_read.cold+0x21/0x21 [ 8.725140][ T188] ? do_raw_spin_lock+0x131/0x280 [ 8.725142][ T188] ? __rwlock_init+0x150/0x150 [ 8.725146][ T188] ? do_raw_spin_lock+0x131/0x280 [ 8.725148][ T188] do_exit.cold+0x82/0x9c [ 8.725152][ T188] ? exit_notify+0x890/0x890 [ 8.725153][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 8.725156][ T188] ? rcu_is_watching+0x16/0xd0 [ 8.725160][ T188] do_group_exit+0xb8/0x370 [ 8.725163][ T188] __x64_sys_exit_group+0x3c/0x50 [ 8.725164][ T188] x64_sys_call+0x1567/0x1570 [ 8.725166][ T188] do_syscall_64+0xff/0x530 [ 8.725169][ T188] ? exc_page_fault+0xee/0x100 [ 8.725172][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.725174][ T188] RIP: 0033:0x7f1ad2bac1b8 [ 8.725176][ T188] Code: Unable to access opcode bytes at 0x7f1ad2bac18e. [ 8.725177][ T188] RSP: 002b:00007ffdfabe40d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 8.725180][ T188] RAX: ffffffffffffffda RBX: 00007f1ad2cdcf88 RCX: 00007f1ad2bac1b8 [ 8.725181][ T188] RDX: 00007f1ad28f6fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 8.725182][ T188] RBP: 00007ffdfabe4130 R08: 0000000000000000 R09: 0000000000008010 [ 8.725183][ T188] R10: 00007ffdfabe3ef0 R11: 0000000000000246 R12: 0000000000000001 [ 8.725183][ T188] R13: 0000000000000000 R14: 00007f1ad2cdb680 R15: 00007f1ad2cdcfa0 [ 8.725190][ T188] [ 15.138716][ T261] veth2: entered promiscuous mode [ 15.138780][ T261] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.138782][ T261] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 261, name: tcpdump [ 15.138784][ T261] preempt_count: 1, expected: 0 [ 15.138785][ T261] RCU nest depth: 0, expected: 0 [ 15.138786][ T261] locks held by tcpdump/261: 5, last CPU#2: [ 15.138788][ T261] #0: ffffffff994d2c40 (rtnl_mutex){+.+.}-{4:4}, at: packet_mc_add+0x28/0x890 [ 15.138799][ T261] #1: ffffffff98d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.138805][ T261] #2: ffffffff98d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.138808][ T261] #3: ffffffff98c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.138812][ T261] #4: ffffffff98c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.138816][ T261] irq event stamp: 32726 [ 15.138816][ T261] hardirqs last enabled at (32725): [] __down_trylock_console_sem+0x86/0xa0 [ 15.138819][ T261] hardirqs last disabled at (32726): [] console_emit_next_record+0x3d4/0x4c0 [ 15.138821][ T261] softirqs last enabled at (32698): [] packet_do_bind+0x4b2/0xc80 [ 15.138823][ T261] softirqs last disabled at (32696): [] release_sock+0x21/0x240 [ 15.138827][ T261] Preemption disabled at: [ 15.138827][ T261] [] vprintk_emit+0x31b/0x3e0 [ 15.138833][ T261] CPU: 2 UID: 0 PID: 261 Comm: tcpdump Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.138836][ T261] Tainted: [W]=WARN [ 15.138837][ T261] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.138839][ T261] Call Trace: [ 15.138841][ T261] [ 15.138842][ T261] dump_stack_lvl+0x6f/0xa0 [ 15.138848][ T261] ? vprintk_emit+0x31b/0x3e0 [ 15.138850][ T261] __might_resched.cold+0x1fe/0x2c1 [ 15.138854][ T261] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.138858][ T261] ? __kmalloc_noprof+0xdb/0x760 [ 15.138863][ T261] __kmalloc_noprof+0x443/0x760 [ 15.138866][ T261] ? alloc_buf.isra.0+0x4b/0x260 [ 15.138871][ T261] ? do_raw_spin_unlock+0x59/0x250 [ 15.138874][ T261] alloc_buf.isra.0+0x4b/0x260 [ 15.138877][ T261] put_chars+0x1e1/0x2f0 [ 15.138879][ T261] ? __send_to_port+0x420/0x420 [ 15.138883][ T261] ? validate_chain+0x34a/0xc20 [ 15.138887][ T261] hvc_console_print+0x292/0x780 [ 15.138890][ T261] ? mark_usage+0x61/0x170 [ 15.138891][ T261] ? __lock_acquire+0x518/0xc20 [ 15.138893][ T261] ? __lock_acquire+0x518/0xc20 [ 15.138897][ T261] ? hvc_write+0x3a0/0x3a0 [ 15.138899][ T261] ? console_emit_next_record+0x1df/0x4c0 [ 15.138902][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.138905][ T261] ? lock_acquire+0x13c/0x160 [ 15.138909][ T261] console_emit_next_record+0x22f/0x4c0 [ 15.138913][ T261] ? devkmsg_read+0x4b0/0x4b0 [ 15.138914][ T261] ? console_flush_one_record+0x106/0x710 [ 15.138917][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.138920][ T261] ? lock_acquire+0x13c/0x160 [ 15.138923][ T261] console_flush_one_record+0x46f/0x710 [ 15.138927][ T261] ? console_emit_next_record+0x4c0/0x4c0 [ 15.138929][ T261] ? __lock_acquire+0x518/0xc20 [ 15.138934][ T261] console_unlock+0xee/0x1f0 [ 15.138936][ T261] ? console_flush_one_record+0x710/0x710 [ 15.138938][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.138940][ T261] ? lock_acquire+0x60/0x160 [ 15.138947][ T261] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.138948][ T261] ? vprintk_emit+0x320/0x3e0 [ 15.138952][ T261] vprintk_emit+0x37c/0x3e0 [ 15.138955][ T261] ? wake_up_klogd_work_func+0x90/0x90 [ 15.138957][ T261] ? kasan_save_stack+0x3d/0x50 [ 15.138960][ T261] ? kasan_save_track+0x14/0x30 [ 15.138962][ T261] ? __kasan_kmalloc+0x7b/0x90 [ 15.138963][ T261] ? __kmalloc_cache_noprof+0x29a/0x620 [ 15.138965][ T261] ? packet_mc_add+0x13b/0x890 [ 15.138966][ T261] ? packet_setsockopt+0x1953/0x21e0 [ 15.138968][ T261] ? do_sock_setsockopt+0x115/0x490 [ 15.138972][ T261] _printk+0xc7/0x100 [ 15.138976][ T261] ? snapshot_read.cold+0x21/0x21 [ 15.138980][ T261] ? netdev_name+0x43/0x60 [ 15.138983][ T261] ? __netdev_printk+0x17f/0x1d0 [ 15.138986][ T261] netdev_info+0xe2/0x116 [ 15.138988][ T261] ? netdev_notice+0x120/0x120 [ 15.138995][ T261] ? mutex_is_locked+0x1c/0x50 [ 15.138999][ T261] __dev_set_promiscuity.cold+0x95/0x104 [ 15.139003][ T261] netif_set_promiscuity+0x4c/0xc0 [ 15.139007][ T261] dev_set_promiscuity+0x9d/0x150 [ 15.139008][ T261] ? __asan_memset+0x27/0x50 [ 15.139011][ T261] packet_mc_add+0x54e/0x890 [ 15.139013][ T261] ? __might_fault+0x97/0x140 [ 15.139017][ T261] ? __might_fault+0x97/0x140 [ 15.139019][ T261] ? _copy_from_user+0x42/0x90 [ 15.139023][ T261] packet_setsockopt+0x1953/0x21e0 [ 15.139026][ T261] ? packet_bind+0x170/0x170 [ 15.139030][ T261] ? do_sock_getsockopt+0x6f6/0xb20 [ 15.139034][ T261] ? sock_poll+0x6b0/0x6b0 [ 15.139038][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.139040][ T261] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.139043][ T261] ? __local_bh_enable_ip+0xaa/0x150 [ 15.139047][ T261] do_sock_setsockopt+0x115/0x490 [ 15.139050][ T261] ? put_user_ifreq+0x90/0x90 [ 15.139052][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.139058][ T261] __sys_setsockopt+0xe4/0x150 [ 15.139063][ T261] __x64_sys_setsockopt+0xbd/0x180 [ 15.139065][ T261] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.139067][ T261] ? lockdep_hardirqs_on+0x91/0x130 [ 15.139070][ T261] ? do_syscall_64+0xa6/0x530 [ 15.139071][ T261] do_syscall_64+0xff/0x530 [ 15.139072][ T261] ? exc_page_fault+0xee/0x100 [ 15.139075][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.139077][ T261] RIP: 0033:0x7fcb8e17b2de [ 15.139081][ T261] Code: 55 48 63 c9 48 63 ff 45 89 c9 48 89 e5 48 83 ec 08 6a 2c e8 94 72 f7 ff c9 c3 66 90 f3 0f 1e fa 49 89 ca b8 36 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 0a c3 66 0f 1f 84 00 00 00 00 00 48 8b 15 e1 [ 15.139083][ T261] RSP: 002b:00007ffe44d83778 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 15.139085][ T261] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fcb8e17b2de [ 15.139087][ T261] RDX: 0000000000000001 RSI: 0000000000000107 RDI: 0000000000000005 [ 15.139087][ T261] RBP: 00007ffe44d838b0 R08: 0000000000000010 R09: 000056141afb0530 [ 15.139088][ T261] R10: 00007ffe44d837f0 R11: 0000000000000246 R12: 0000000000000001 [ 15.139089][ T261] R13: 000056141afb0918 R14: 000056141afb0690 R15: 0000000000000000 [ 15.139095][ T261] [ 32.635996][ T288] 8021q: 802.1Q VLAN Support v1.8 [ 32.636013][ T288] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 32.636015][ T288] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 288, name: modprobe [ 32.636017][ T288] preempt_count: 1, expected: 0 [ 32.636018][ T288] RCU nest depth: 0, expected: 0 [ 32.636019][ T288] locks held by modprobe/288: 4, last CPU#3: [ 32.636022][ T288] #0: ffffffff98d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 32.636034][ T288] #1: ffffffff98d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 32.636038][ T288] #2: ffffffff98c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 32.636041][ T288] #3: ffffffff98c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 32.636045][ T288] irq event stamp: 36008 [ 32.636046][ T288] hardirqs last enabled at (36007): [] __down_trylock_console_sem+0x86/0xa0 [ 32.636048][ T288] hardirqs last disabled at (36008): [] console_emit_next_record+0x3d4/0x4c0 [ 32.636050][ T288] softirqs last enabled at (31600): [] handle_softirqs+0x67c/0x900 [ 32.636053][ T288] softirqs last disabled at (31379): [] __irq_exit_rcu+0x145/0x1c0 [ 32.636055][ T288] Preemption disabled at: [ 32.636056][ T288] [] vprintk_emit+0x31b/0x3e0 [ 32.636062][ T288] CPU: 3 UID: 0 PID: 288 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 32.636065][ T288] Tainted: [W]=WARN [ 32.636066][ T288] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 32.636067][ T288] Call Trace: [ 32.636069][ T288] [ 32.636070][ T288] dump_stack_lvl+0x6f/0xa0 [ 32.636075][ T288] ? vprintk_emit+0x31b/0x3e0 [ 32.636077][ T288] __might_resched.cold+0x1fe/0x2c1 [ 32.636082][ T288] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 32.636086][ T288] ? __kmalloc_noprof+0xdb/0x760 [ 32.636090][ T288] __kmalloc_noprof+0x443/0x760 [ 32.636093][ T288] ? alloc_buf.isra.0+0x4b/0x260 [ 32.636098][ T288] ? do_raw_spin_unlock+0x59/0x250 [ 32.636101][ T288] alloc_buf.isra.0+0x4b/0x260 [ 32.636104][ T288] put_chars+0x1e1/0x2f0 [ 32.636106][ T288] ? __send_to_port+0x420/0x420 [ 32.636108][ T288] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 32.636112][ T288] ? rcu_read_lock_any_held+0x3c/0x90 [ 32.636114][ T288] ? validate_chain+0x38b/0xc20 [ 32.636119][ T288] hvc_console_print+0x292/0x780 [ 32.636121][ T288] ? __lock_acquire+0x518/0xc20 [ 32.636126][ T288] ? hvc_write+0x3a0/0x3a0 [ 32.636128][ T288] ? rcu_is_watching+0x16/0xd0 [ 32.636133][ T288] console_emit_next_record+0x22f/0x4c0 [ 32.636137][ T288] ? devkmsg_read+0x4b0/0x4b0 [ 32.636138][ T288] ? console_flush_one_record+0x106/0x710 [ 32.636141][ T288] ? rcu_is_watching+0x16/0xd0 [ 32.636143][ T288] ? lock_acquire+0x13c/0x160 [ 32.636147][ T288] console_flush_one_record+0x46f/0x710 [ 32.636151][ T288] ? console_emit_next_record+0x4c0/0x4c0 [ 32.636153][ T288] ? __lock_acquire+0x518/0xc20 [ 32.636157][ T288] console_unlock+0xee/0x1f0 [ 32.636160][ T288] ? console_flush_one_record+0x710/0x710 [ 32.636162][ T288] ? rcu_is_watching+0x16/0xd0 [ 32.636164][ T288] ? lock_acquire+0x60/0x160 [ 32.636168][ T288] ? __down_trylock_console_sem+0x5e/0xa0 [ 32.636169][ T288] ? vprintk_emit+0x320/0x3e0 [ 32.636172][ T288] vprintk_emit+0x37c/0x3e0 [ 32.636176][ T288] ? wake_up_klogd_work_func+0x90/0x90 [ 32.636181][ T288] ? vlan_netlink_fini+0x960/0x960 [8021q] [ 32.636185][ T288] _printk+0xc7/0x100 [ 32.636188][ T288] ? snapshot_read.cold+0x21/0x21 [ 32.636194][ T288] ? do_one_initcall+0x113/0x4f0 [ 32.636197][ T288] vlan_proto_init+0x24/0x90 [8021q] [ 32.636200][ T288] do_one_initcall+0x124/0x4f0 [ 32.636202][ T288] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 32.636207][ T288] ? do_init_module+0xae/0x8a0 [ 32.636210][ T288] ? kasan_unpoison+0x44/0x70 [ 32.636214][ T288] do_init_module+0x2ad/0x8a0 [ 32.636217][ T288] ? lockdep_hardirqs_on+0x91/0x130 [ 32.636221][ T288] ? free_module+0x470/0x470 [ 32.636224][ T288] ? kfree+0x123/0x590 [ 32.636228][ T288] load_module+0x1424/0x1bb0 [ 32.636235][ T288] ? layout_and_allocate.isra.0+0x680/0x680 [ 32.636239][ T288] ? __ia32_sys_fsconfig+0x180/0x180 [ 32.636241][ T288] ? ovl_real_file+0x172/0x1e0 [ 32.636245][ T288] ? backing_file_read_iter+0x541/0x790 [ 32.636248][ T288] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 32.636252][ T288] init_module_from_file+0x11f/0x1a0 [ 32.636255][ T288] ? __do_sys_init_module+0x220/0x220 [ 32.636263][ T288] ? __rwlock_init+0x150/0x150 [ 32.636266][ T288] ? do_raw_spin_unlock+0x59/0x250 [ 32.636269][ T288] idempotent_init_module+0x37b/0x7c0 [ 32.636272][ T288] ? init_module_from_file+0x1a0/0x1a0 [ 32.636278][ T288] ? rcu_is_watching+0x16/0xd0 [ 32.636281][ T288] ? cap_capable+0x1d7/0x3d0 [ 32.636286][ T288] __x64_sys_finit_module+0xdf/0x190 [ 32.636288][ T288] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 32.636292][ T288] do_syscall_64+0xff/0x530 [ 32.636294][ T288] ? exc_page_fault+0xee/0x100 [ 32.636296][ T288] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 32.636298][ T288] RIP: 0033:0x7ff9cd73737d [ 32.636301][ T288] 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 [ 32.636303][ T288] RSP: 002b:00007ffc71aaf898 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 32.636306][ T288] RAX: ffffffffffffffda RBX: 0000556425467810 RCX: 00007ff9cd73737d [ 32.636307][ T288] RDX: 0000000000000000 RSI: 0000556407c65ffd RDI: 0000000000000000 [ 32.636308][ T288] RBP: 00007ffc71aaf930 R08: 0000000000000000 R09: 0000000000000000 [ 32.636309][ T288] R10: 0000000000000000 R11: 0000000000000246 R12: 0000556407c65ffd [ 32.636310][ T288] R13: 0000556425467960 R14: 0000000000040000 R15: 0000000000000000 [ 32.636316][ T288] [ 53.602963][ T328] ip (328) used greatest stack depth: 24024 bytes left [ 53.602982][ T328] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 53.602985][ T328] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 328, name: ip [ 53.602986][ T328] preempt_count: 2, expected: 0 [ 53.602987][ T328] RCU nest depth: 0, expected: 0 [ 53.602988][ T328] locks held by ip/328: 5, last CPU#0: [ 53.602990][ T328] #0: ffffffff98c027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 53.603002][ T328] #1: ffffffff98d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 53.603008][ T328] #2: ffffffff98d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 53.603012][ T328] #3: ffffffff98c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 53.603016][ T328] #4: ffffffff98c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 53.603020][ T328] irq event stamp: 18030 [ 53.603020][ T328] hardirqs last enabled at (18029): [] __down_trylock_console_sem+0x86/0xa0 [ 53.603023][ T328] hardirqs last disabled at (18030): [] console_emit_next_record+0x3d4/0x4c0 [ 53.603025][ T328] softirqs last enabled at (16746): [] netlink_release+0x17b/0xcf0 [ 53.603029][ T328] softirqs last disabled at (16744): [] netlink_release+0xd2/0xcf0 [ 53.603031][ T328] Preemption disabled at: [ 53.603032][ T328] [<0000000000000000>] 0x0 [ 53.603043][ T328] CPU: 0 UID: 0 PID: 328 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 53.603046][ T328] Tainted: [W]=WARN [ 53.603047][ T328] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 53.603049][ T328] Call Trace: [ 53.603050][ T328] [ 53.603052][ T328] dump_stack_lvl+0x6f/0xa0 [ 53.603058][ T328] __might_resched.cold+0x1fe/0x2c1 [ 53.603062][ T328] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 53.603067][ T328] ? __kmalloc_noprof+0xdb/0x760 [ 53.603072][ T328] __kmalloc_noprof+0x443/0x760 [ 53.603074][ T328] ? alloc_buf.isra.0+0x4b/0x260 [ 53.603079][ T328] ? do_raw_spin_unlock+0x59/0x250 [ 53.603082][ T328] alloc_buf.isra.0+0x4b/0x260 [ 53.603085][ T328] put_chars+0x1e1/0x2f0 [ 53.603088][ T328] ? __send_to_port+0x420/0x420 [ 53.603090][ T328] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 53.603094][ T328] ? validate_chain+0x38b/0xc20 [ 53.603099][ T328] hvc_console_print+0x292/0x780 [ 53.603105][ T328] ? hvc_write+0x3a0/0x3a0 [ 53.603107][ T328] ? rcu_is_watching+0x16/0xd0 [ 53.603110][ T328] ? lock_acquire+0x13c/0x160 [ 53.603114][ T328] console_emit_next_record+0x22f/0x4c0 [ 53.603118][ T328] ? devkmsg_read+0x4b0/0x4b0 [ 53.603119][ T328] ? console_flush_one_record+0x106/0x710 [ 53.603122][ T328] ? rcu_is_watching+0x16/0xd0 [ 53.603125][ T328] ? lock_acquire+0x13c/0x160 [ 53.603129][ T328] console_flush_one_record+0x46f/0x710 [ 53.603133][ T328] ? console_emit_next_record+0x4c0/0x4c0 [ 53.603134][ T328] ? __lock_acquire+0x518/0xc20 [ 53.603139][ T328] console_unlock+0xee/0x1f0 [ 53.603142][ T328] ? console_flush_one_record+0x710/0x710 [ 53.603144][ T328] ? rcu_is_watching+0x16/0xd0 [ 53.603146][ T328] ? lock_acquire+0x60/0x160 [ 53.603150][ T328] ? __down_trylock_console_sem+0x5e/0xa0 [ 53.603152][ T328] ? vprintk_emit+0x320/0x3e0 [ 53.603155][ T328] vprintk_emit+0x37c/0x3e0 [ 53.603159][ T328] ? wake_up_klogd_work_func+0x90/0x90 [ 53.603162][ T328] ? __lock_acquire+0x518/0xc20 [ 53.603166][ T328] _printk+0xc7/0x100 [ 53.603169][ T328] ? snapshot_read.cold+0x21/0x21 [ 53.603172][ T328] ? do_raw_spin_lock+0x131/0x280 [ 53.603175][ T328] ? __rwlock_init+0x150/0x150 [ 53.603178][ T328] ? do_raw_spin_lock+0x131/0x280 [ 53.603181][ T328] do_exit.cold+0x82/0x9c [ 53.603185][ T328] ? exit_notify+0x890/0x890 [ 53.603187][ T328] ? __lock_release.isra.0+0x69/0x1a0 [ 53.603189][ T328] ? rcu_is_watching+0x16/0xd0 [ 53.603193][ T328] do_group_exit+0xb8/0x370 [ 53.603196][ T328] __x64_sys_exit_group+0x3c/0x50 [ 53.603198][ T328] x64_sys_call+0x1567/0x1570 [ 53.603200][ T328] do_syscall_64+0xff/0x530 [ 53.603203][ T328] ? exc_page_fault+0xee/0x100 [ 53.603206][ T328] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 53.603209][ T328] RIP: 0033:0x7f03d0ef71b8 [ 53.603211][ T328] Code: Unable to access opcode bytes at 0x7f03d0ef718e. [ 53.603212][ T328] RSP: 002b:00007ffc904fd428 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 53.603215][ T328] RAX: ffffffffffffffda RBX: 00007f03d1027f88 RCX: 00007f03d0ef71b8 [ 53.603216][ T328] RDX: 00007f03d0c41fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 53.603217][ T328] RBP: 00007ffc904fd480 R08: 0000000000000000 R09: 0000000000000050 [ 53.603218][ T328] R10: 00007ffc904fd240 R11: 0000000000000246 R12: 0000000000000001 [ 53.603219][ T328] R13: 0000000000000000 R14: 00007f03d1026680 R15: 00007f03d1027fa0 [ 53.603226][ T328] [ 351.043548][ T261] veth2: left promiscuous mode [ 351.043655][ T261] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 351.043658][ T261] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 261, name: tcpdump [ 351.043660][ T261] preempt_count: 1, expected: 0 [ 351.043660][ T261] RCU nest depth: 0, expected: 0 [ 351.043661][ T261] locks held by tcpdump/261: 6, last CPU#3: [ 351.043664][ T261] #0: ff1100000d130278 (&sb->s_type->i_mutex_key#10){+.+.}-{4:4}, at: __sock_release+0x8f/0x280 [ 351.043676][ T261] #1: ffffffff994d2c40 (rtnl_mutex){+.+.}-{4:4}, at: packet_release+0x3fb/0xc40 [ 351.043682][ T261] #2: ffffffff98d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 351.043688][ T261] #3: ffffffff98d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 351.043692][ T261] #4: ffffffff98c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 351.043695][ T261] #5: ffffffff98c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 351.043699][ T261] irq event stamp: 50408 [ 351.043700][ T261] hardirqs last enabled at (50407): [] __down_trylock_console_sem+0x86/0xa0 [ 351.043703][ T261] hardirqs last disabled at (50408): [] console_emit_next_record+0x3d4/0x4c0 [ 351.043705][ T261] softirqs last enabled at (50248): [] packet_setsockopt+0xcdf/0x21e0 [ 351.043707][ T261] softirqs last disabled at (50246): [] release_sock+0x21/0x240 [ 351.043711][ T261] Preemption disabled at: [ 351.043711][ T261] [] vprintk_emit+0x31b/0x3e0 [ 351.043717][ T261] CPU: 3 UID: 72 PID: 261 Comm: tcpdump Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 351.043721][ T261] Tainted: [W]=WARN [ 351.043722][ T261] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 351.043724][ T261] Call Trace: [ 351.043726][ T261] [ 351.043727][ T261] dump_stack_lvl+0x6f/0xa0 [ 351.043733][ T261] ? vprintk_emit+0x31b/0x3e0 [ 351.043735][ T261] __might_resched.cold+0x1fe/0x2c1 [ 351.043740][ T261] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 351.043744][ T261] ? __kmalloc_noprof+0xdb/0x760 [ 351.043749][ T261] __kmalloc_noprof+0x443/0x760 [ 351.043752][ T261] ? alloc_buf.isra.0+0x4b/0x260 [ 351.043757][ T261] ? do_raw_spin_unlock+0x59/0x250 [ 351.043760][ T261] alloc_buf.isra.0+0x4b/0x260 [ 351.043763][ T261] put_chars+0x1e1/0x2f0 [ 351.043766][ T261] ? __send_to_port+0x420/0x420 [ 351.043767][ T261] ? check_prev_add+0xef/0xe90 [ 351.043772][ T261] ? validate_chain+0x34a/0xc20 [ 351.043776][ T261] hvc_console_print+0x292/0x780 [ 351.043778][ T261] ? mark_usage+0x61/0x170 [ 351.043780][ T261] ? __lock_acquire+0x518/0xc20 [ 351.043782][ T261] ? __lock_acquire+0x518/0xc20 [ 351.043786][ T261] ? hvc_write+0x3a0/0x3a0 [ 351.043788][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 351.043792][ T261] ? lock_acquire+0x13c/0x160 [ 351.043796][ T261] console_emit_next_record+0x22f/0x4c0 [ 351.043800][ T261] ? devkmsg_read+0x4b0/0x4b0 [ 351.043802][ T261] ? console_flush_one_record+0x106/0x710 [ 351.043805][ T261] ? rcu_is_watching+0x16/0xd0 [ 351.043809][ T261] ? lock_acquire+0x13c/0x160 [ 351.043814][ T261] console_flush_one_record+0x46f/0x710 [ 351.043818][ T261] ? console_emit_next_record+0x4c0/0x4c0 [ 351.043820][ T261] ? __lock_acquire+0x518/0xc20 [ 351.043825][ T261] console_unlock+0xee/0x1f0 [ 351.043828][ T261] ? console_flush_one_record+0x710/0x710 [ 351.043830][ T261] ? rcu_is_watching+0x16/0xd0 [ 351.043833][ T261] ? lock_acquire+0x60/0x160 [ 351.043836][ T261] ? __down_trylock_console_sem+0x5e/0xa0 [ 351.043838][ T261] ? vprintk_emit+0x320/0x3e0 [ 351.043842][ T261] vprintk_emit+0x37c/0x3e0 [ 351.043845][ T261] ? wake_up_klogd_work_func+0x90/0x90 [ 351.043849][ T261] ? __lock_acquire+0x518/0xc20 [ 351.043853][ T261] _printk+0xc7/0x100 [ 351.043857][ T261] ? snapshot_read.cold+0x21/0x21 [ 351.043859][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 351.043862][ T261] ? rcu_is_watching+0x16/0xd0 [ 351.043864][ T261] ? netdev_name+0x43/0x60 [ 351.043868][ T261] ? __netdev_printk+0x17f/0x1d0 [ 351.043871][ T261] netdev_info+0xe2/0x116 [ 351.043874][ T261] ? netdev_notice+0x120/0x120 [ 351.043875][ T261] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 351.043878][ T261] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 351.043882][ T261] ? ww_mutex_lock+0x160/0x160 [ 351.043884][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 351.043886][ T261] ? ref_tracker_alloc+0x430/0x430 [ 351.043890][ T261] ? packet_release+0x363/0xc40 [ 351.043892][ T261] ? __fput+0x363/0xac0 [ 351.043895][ T261] ? mutex_is_locked+0x1c/0x50 [ 351.043899][ T261] __dev_set_promiscuity.cold+0x95/0x104 [ 351.043903][ T261] netif_set_promiscuity+0x4c/0xc0 [ 351.043908][ T261] dev_set_promiscuity+0x9d/0x150 [ 351.043912][ T261] packet_release+0x4cb/0xc40 [ 351.043916][ T261] ? packet_set_ring+0x1740/0x1740 [ 351.043920][ T261] ? rwsem_down_write_slowpath+0xbe0/0xbe0 [ 351.043925][ T261] __sock_release+0xb8/0x280 [ 351.043928][ T261] sock_close+0x18/0x20 [ 351.043930][ T261] __fput+0x363/0xac0 [ 351.043935][ T261] fput_close_sync+0xde/0x1b0 [ 351.043937][ T261] ? alloc_file_clone+0xe0/0xe0 [ 351.043939][ T261] ? do_raw_spin_unlock+0x59/0x250 [ 351.043947][ T261] __x64_sys_close+0x8b/0xf0 [ 351.043951][ T261] do_syscall_64+0xff/0x530 [ 351.043953][ T261] ? exc_page_fault+0xee/0x100 [ 351.043956][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 351.043959][ T261] RIP: 0033:0x7fcb8e0f254e [ 351.043963][ T261] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 351.043964][ T261] RSP: 002b:00007ffe44d838e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000003 [ 351.043967][ T261] RAX: ffffffffffffffda RBX: 000056141afb0690 RCX: 00007fcb8e0f254e [ 351.043969][ T261] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005 [ 351.043970][ T261] RBP: 00007ffe44d838f0 R08: 0000000000000000 R09: 0000000000000000 [ 351.043970][ T261] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000000 [ 351.043971][ T261] R13: 00000000fffffffe R14: 00005613f1857760 R15: 0000000000000001 [ 351.043980][ T261] [ 358.336372][ T1454] br0: port 1(veth1) entered blocking state [ 358.336388][ T1454] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 358.336390][ T1454] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1454, name: ip [ 358.336392][ T1454] preempt_count: 1, expected: 0 [ 358.336393][ T1454] RCU nest depth: 0, expected: 0 [ 358.336394][ T1454] locks held by ip/1454: 5, last CPU#0: [ 358.336396][ T1454] #0: ffffffff994d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 358.336408][ T1454] #1: ffffffff98d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 358.336415][ T1454] #2: ffffffff98d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 358.336418][ T1454] #3: ffffffff98c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 358.336422][ T1454] #4: ffffffff98c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 358.336426][ T1454] irq event stamp: 20604 [ 358.336427][ T1454] hardirqs last enabled at (20603): [] __down_trylock_console_sem+0x86/0xa0 [ 358.336430][ T1454] hardirqs last disabled at (20604): [] console_emit_next_record+0x3d4/0x4c0 [ 358.336432][ T1454] softirqs last enabled at (20516): [] __alloc_skb+0x4c2/0x5f0 [ 358.336435][ T1454] softirqs last disabled at (20514): [] __alloc_skb+0x4c2/0x5f0 [ 358.336438][ T1454] Preemption disabled at: [ 358.336438][ T1454] [] vprintk_emit+0x31b/0x3e0 [ 358.336444][ T1454] CPU: 0 UID: 0 PID: 1454 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 358.336448][ T1454] Tainted: [W]=WARN [ 358.336449][ T1454] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 358.336450][ T1454] Call Trace: [ 358.336452][ T1454] [ 358.336454][ T1454] dump_stack_lvl+0x6f/0xa0 [ 358.336459][ T1454] ? vprintk_emit+0x31b/0x3e0 [ 358.336462][ T1454] __might_resched.cold+0x1fe/0x2c1 [ 358.336466][ T1454] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 358.336471][ T1454] ? __kmalloc_noprof+0xdb/0x760 [ 358.336476][ T1454] __kmalloc_noprof+0x443/0x760 [ 358.336478][ T1454] ? alloc_buf.isra.0+0x4b/0x260 [ 358.336484][ T1454] ? do_raw_spin_unlock+0x59/0x250 [ 358.336487][ T1454] alloc_buf.isra.0+0x4b/0x260 [ 358.336490][ T1454] put_chars+0x1e1/0x2f0 [ 358.336492][ T1454] ? prb_final_commit+0x50/0x50 [ 358.336494][ T1454] ? __send_to_port+0x420/0x420 [ 358.336497][ T1454] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 358.336502][ T1454] ? rcu_read_lock_any_held+0x3c/0x90 [ 358.336504][ T1454] ? validate_chain+0x38b/0xc20 [ 358.336508][ T1454] hvc_console_print+0x292/0x780 [ 358.336511][ T1454] ? mark_usage+0x61/0x170 [ 358.336513][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336514][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336518][ T1454] ? hvc_write+0x3a0/0x3a0 [ 358.336521][ T1454] ? console_emit_next_record+0x1df/0x4c0 [ 358.336524][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336526][ T1454] ? lock_acquire+0x13c/0x160 [ 358.336531][ T1454] console_emit_next_record+0x22f/0x4c0 [ 358.336535][ T1454] ? devkmsg_read+0x4b0/0x4b0 [ 358.336537][ T1454] ? console_flush_one_record+0x106/0x710 [ 358.336540][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336542][ T1454] ? lock_acquire+0x13c/0x160 [ 358.336546][ T1454] console_flush_one_record+0x46f/0x710 [ 358.336550][ T1454] ? console_emit_next_record+0x4c0/0x4c0 [ 358.336552][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336558][ T1454] console_unlock+0xee/0x1f0 [ 358.336561][ T1454] ? console_flush_one_record+0x710/0x710 [ 358.336563][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336565][ T1454] ? lock_acquire+0x60/0x160 [ 358.336569][ T1454] ? __down_trylock_console_sem+0x5e/0xa0 [ 358.336571][ T1454] ? vprintk_emit+0x320/0x3e0 [ 358.336575][ T1454] vprintk_emit+0x37c/0x3e0 [ 358.336579][ T1454] ? wake_up_klogd_work_func+0x90/0x90 [ 358.336581][ T1454] ? __lock_release.isra.0+0x69/0x1a0 [ 358.336583][ T1454] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 358.336587][ T1454] ? mark_held_locks+0x40/0x70 [ 358.336591][ T1454] _printk+0xc7/0x100 [ 358.336595][ T1454] ? snapshot_read.cold+0x21/0x21 [ 358.336599][ T1454] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 358.336612][ T1454] ? do_setlink.isra.0+0xa31/0x2750 [ 358.336614][ T1454] ? rtnl_newlink+0x9f1/0x11c0 [ 358.336615][ T1454] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 358.336620][ T1454] br_set_state+0x22f/0x430 [bridge] [ 358.336631][ T1454] br_init_port+0xc4/0x200 [bridge] [ 358.336641][ T1454] new_nbp+0x39c/0x580 [bridge] [ 358.336656][ T1454] br_add_if+0x212/0x1320 [bridge] [ 358.336663][ T1454] ? is_bpf_text_address+0x72/0x110 [ 358.336667][ T1454] ? kernel_text_address+0x149/0x170 [ 358.336670][ T1454] ? __kernel_text_address+0x12/0x30 [ 358.336674][ T1454] do_set_master+0x357/0x580 [ 358.336679][ T1454] do_setlink.isra.0+0xa31/0x2750 [ 358.336682][ T1454] ? stack_trace_save+0x93/0xc0 [ 358.336686][ T1454] ? rtnl_link_get_size+0x350/0x350 [ 358.336687][ T1454] ? rcu_read_lock_any_held+0x66/0x90 [ 358.336689][ T1454] ? stack_depot_save_flags+0x38e/0x790 [ 358.336692][ T1454] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 358.336695][ T1454] ? rcu_read_lock_any_held+0x3c/0x90 [ 358.336697][ T1454] ? validate_chain+0x38b/0xc20 [ 358.336700][ T1454] ? kasan_save_stack+0x3d/0x50 [ 358.336702][ T1454] ? kasan_save_stack+0x2f/0x50 [ 358.336704][ T1454] ? kasan_save_track+0x14/0x30 [ 358.336707][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336709][ T1454] ? netlink_seq_next+0xe/0x60 [ 358.336713][ T1454] ? ___sys_sendmsg+0xb0/0x1d0 [ 358.336718][ T1454] ? lock_acquire.part.0+0xd4/0x280 [ 358.336720][ T1454] ? rtnl_newlink+0x9a8/0x11c0 [ 358.336722][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336725][ T1454] ? lock_acquire+0x13c/0x160 [ 358.336726][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336728][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336731][ T1454] ? trace_contention_end+0xb3/0x180 [ 358.336735][ T1454] ? __mutex_lock+0x1db/0x1ea0 [ 358.336736][ T1454] ? __mutex_lock+0x9a3/0x1ea0 [ 358.336738][ T1454] ? rtnl_newlink+0x9a8/0x11c0 [ 358.336742][ T1454] ? ww_mutex_lock+0x160/0x160 [ 358.336743][ T1454] ? nla_get_range_signed+0x3d0/0x3d0 [ 358.336749][ T1454] ? __rtnl_newlink+0x3fa/0xa50 [ 358.336754][ T1454] rtnl_newlink+0x9f1/0x11c0 [ 358.336759][ T1454] ? rtnl_bridge_getlink+0x850/0x850 [ 358.336761][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336766][ T1454] ? lock_acquire.part.0+0xd4/0x280 [ 358.336768][ T1454] ? find_held_lock+0x2b/0x80 [ 358.336770][ T1454] ? rtnl_bridge_getlink+0x850/0x850 [ 358.336772][ T1454] ? __lock_release.isra.0+0x69/0x1a0 [ 358.336775][ T1454] ? rtnl_bridge_getlink+0x850/0x850 [ 358.336777][ T1454] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 358.336781][ T1454] ? rtnl_link_fill+0x920/0x920 [ 358.336782][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336787][ T1454] ? lock_acquire.part.0+0xd4/0x280 [ 358.336789][ T1454] ? find_held_lock+0x2b/0x80 [ 358.336792][ T1454] netlink_rcv_skb+0x14e/0x3a0 [ 358.336794][ T1454] ? rtnl_link_fill+0x920/0x920 [ 358.336797][ T1454] ? netlink_ack+0xcf0/0xcf0 [ 358.336803][ T1454] ? netlink_deliver_tap+0xc5/0x330 [ 358.336805][ T1454] ? netlink_deliver_tap+0x13c/0x330 [ 358.336809][ T1454] netlink_unicast+0x486/0x750 [ 358.336813][ T1454] ? netlink_attachskb+0x810/0x810 [ 358.336815][ T1454] ? __lock_acquire+0x518/0xc20 [ 358.336820][ T1454] netlink_sendmsg+0x735/0xc60 [ 358.336824][ T1454] ? netlink_unicast+0x750/0x750 [ 358.336827][ T1454] ? __might_fault+0x97/0x140 [ 358.336833][ T1454] ____sys_sendmsg+0x415/0x880 [ 358.336835][ T1454] ? copy_msghdr_from_user+0x279/0x420 [ 358.336837][ T1454] ? get_timestamp.constprop.0+0x390/0x390 [ 358.336840][ T1454] ? move_addr_to_kernel+0x40/0x40 [ 358.336851][ T1454] ___sys_sendmsg+0x14e/0x1d0 [ 358.336855][ T1454] ? copy_msghdr_from_user+0x420/0x420 [ 358.336871][ T1454] __sys_sendmsg+0x12c/0x1d0 [ 358.336873][ T1454] ? __sys_sendmsg_sock+0x20/0x20 [ 358.336880][ T1454] ? rcu_is_watching+0x16/0xd0 [ 358.336884][ T1454] do_syscall_64+0xff/0x530 [ 358.336886][ T1454] ? exc_page_fault+0xee/0x100 [ 358.336889][ T1454] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 358.336891][ T1454] RIP: 0033:0x7f29101b654e [ 358.336895][ T1454] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 358.336896][ T1454] RSP: 002b:00007ffe1c6548c0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 358.336899][ T1454] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f29101b654e [ 358.336900][ T1454] RDX: 0000000000000000 RSI: 00007ffe1c654970 RDI: 0000000000000005 [ 358.336901][ T1454] RBP: 00007ffe1c6548d0 R08: 0000000000000000 R09: 0000000000000000 [ 358.336902][ T1454] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91a524 [ 358.336903][ T1454] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe1c655030 [ 358.336910][ T1454] [ 358.391404][ T1454] br0: port 1(veth1) entered disabled state [ 358.391858][ T1454] veth1: entered allmulticast mode [ 358.393432][ T1454] veth1: entered promiscuous mode [ 358.394825][ T1454] br0: port 1(veth1) entered blocking state [ 358.395244][ T1454] br0: port 1(veth1) entered forwarding state [ 358.401905][ T1454] ip (1454) used greatest stack depth: 23336 bytes left [ 358.422702][ T1455] br1: port 1(veth3) entered blocking state [ 358.423032][ T1455] br1: port 1(veth3) entered disabled state [ 358.423337][ T1455] veth3: entered allmulticast mode [ 358.424894][ T1455] veth3: entered promiscuous mode [ 358.425707][ T1455] br1: port 1(veth3) entered blocking state [ 358.426014][ T1455] br1: port 1(veth3) entered forwarding state [ 358.671865][ T1470] veth2: entered promiscuous mode [ 373.628048][ C0] [ 373.628068][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 373.628071][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 373.628073][ C0] preempt_count: 104, expected: 0 [ 373.628075][ C0] RCU nest depth: 0, expected: 0 [ 373.628076][ C0] INFO: lockdep is turned off. [ 373.628078][ C0] irq event stamp: 5005316 [ 373.628079][ C0] hardirqs last enabled at (5005316): [] _raw_spin_unlock_irq+0x28/0x50 [ 373.628089][ C0] hardirqs last disabled at (5005315): [] _raw_spin_lock_irq+0x4a/0x50 [ 373.628091][ C0] softirqs last enabled at (5005282): [] handle_softirqs+0x67c/0x900 [ 373.628096][ C0] softirqs last disabled at (5005313): [] __irq_exit_rcu+0x145/0x1c0 [ 373.628098][ C0] Preemption disabled at: [ 373.628099][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 373.628107][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 373.628112][ C0] Tainted: [W]=WARN [ 373.628114][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 373.628116][ C0] Call Trace: [ 373.628118][ C0] [ 373.628120][ C0] dump_stack_lvl+0x6f/0xa0 [ 373.628126][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 373.628128][ C0] __might_resched.cold+0x1fe/0x2c1 [ 373.628132][ C0] ? kasan_save_track+0x14/0x30 [ 373.628136][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 373.628140][ C0] ? __asan_memcpy+0x3c/0x60 [ 373.628142][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628147][ C0] __kmalloc_noprof+0x443/0x760 [ 373.628151][ C0] ? __rwlock_init+0x150/0x150 [ 373.628154][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 373.628159][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 373.628161][ C0] alloc_buf.isra.0+0x4b/0x260 [ 373.628164][ C0] put_chars+0x1e1/0x2f0 [ 373.628166][ C0] ? __send_to_port+0x420/0x420 [ 373.628169][ C0] ? console_prepend_replay+0x20/0x20 [ 373.628173][ C0] hvc_console_print+0x292/0x780 [ 373.628178][ C0] ? hvc_write+0x3a0/0x3a0 [ 373.628180][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628183][ C0] ? lock_acquire+0x13c/0x160 [ 373.628186][ C0] console_emit_next_record+0x22f/0x4c0 [ 373.628189][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 373.628192][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628194][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628197][ C0] ? lock_acquire+0x13c/0x160 [ 373.628200][ C0] ? console_flush_one_record+0x111/0x710 [ 373.628202][ C0] console_flush_one_record+0x46f/0x710 [ 373.628206][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 373.628210][ C0] console_unlock+0xee/0x1f0 [ 373.628212][ C0] ? lock_acquire+0x13c/0x160 [ 373.628215][ C0] ? console_flush_one_record+0x710/0x710 [ 373.628217][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628219][ C0] ? lock_release+0x184/0x1f0 [ 373.628221][ C0] ? lock_acquire+0x60/0x160 [ 373.628224][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 373.628227][ C0] ? vprintk_emit+0x320/0x3e0 [ 373.628230][ C0] vprintk_emit+0x37c/0x3e0 [ 373.628233][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 373.628235][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628238][ C0] ? lock_release+0x184/0x1f0 [ 373.628240][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 373.628246][ C0] _printk+0xc7/0x100 [ 373.628250][ C0] ? snapshot_read.cold+0x21/0x21 [ 373.628252][ C0] ? arch_stack_walk+0xd7/0x130 [ 373.628257][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 373.628260][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 373.628263][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 373.628266][ C0] mark_lock_irq+0x989/0x9c0 [ 373.628271][ C0] mark_lock+0x1d7/0xa00 [ 373.628274][ C0] mark_usage+0x42/0x170 [ 373.628276][ C0] __lock_acquire+0x388/0xc20 [ 373.628280][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.628282][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.628300][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628302][ C0] ? lock_acquire+0x13c/0x160 [ 373.628305][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 373.628317][ C0] _raw_spin_lock+0x33/0x40 [ 373.628319][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.628331][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.628343][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 373.628355][ C0] call_timer_fn+0x160/0x4d0 [ 373.628359][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 373.628361][ C0] ? debug_object_active_state+0x430/0x430 [ 373.628366][ C0] ? find_held_lock+0x2b/0x80 [ 373.628368][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 373.628371][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628374][ C0] __run_timers+0x68f/0xaa0 [ 373.628377][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 373.628389][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 373.628392][ C0] ? __lock_acquire+0x518/0xc20 [ 373.628398][ C0] ? __rwlock_init+0x150/0x150 [ 373.628401][ C0] run_timer_softirq+0xf0/0x160 [ 373.628410][ C0] ? __run_timers+0xaa0/0xaa0 [ 373.628413][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 373.628416][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628418][ C0] handle_softirqs+0x1d3/0x900 [ 373.628421][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 373.628424][ C0] ? _local_bh_enable+0xc0/0xc0 [ 373.628427][ C0] __irq_exit_rcu+0x145/0x1c0 [ 373.628429][ C0] irq_exit_rcu+0xe/0x30 [ 373.628431][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 373.628435][ C0] [ 373.628436][ C0] [ 373.628437][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 373.628439][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 373.628442][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 373.628446][ C0] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 373.628448][ C0] RSP: 0018:ffffffff98a07cf8 EFLAGS: 00000296 [ 373.628452][ C0] RAX: 00000000004c5fff RBX: ffffffff98a1c600 RCX: ffffffff95af0307 [ 373.628454][ C0] RDX: ffffffff98a1c600 RSI: ffffffff98838af6 RDI: ffffffff9828d8e0 [ 373.628455][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 373.628457][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3140fa2 [ 373.628458][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 373.628461][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 373.628465][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 373.628467][ C0] default_idle+0x9/0x10 [ 373.628470][ C0] default_idle_call+0x6e/0xb0 [ 373.628472][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 373.628475][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 373.628477][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 373.628480][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 373.628482][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.628485][ C0] do_idle+0xd8/0x190 [ 373.628487][ C0] cpu_startup_entry+0x53/0x70 [ 373.628490][ C0] rest_init+0x279/0x280 [ 373.628492][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 373.628496][ C0] ? rest_init+0x280/0x280 [ 373.628499][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 373.628503][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 373.628507][ C0] ? acpi_enable+0x1e4/0x330 [ 373.628510][ C0] start_kernel+0x3af/0x3b0 [ 373.628513][ C0] x86_64_start_reservations+0x24/0x30 [ 373.628515][ C0] x86_64_start_kernel+0x12b/0x130 [ 373.628518][ C0] common_startup_64+0x13e/0x148 [ 373.628524][ C0] [ 373.652119][ C0] ======================================================== [ 373.652394][ C0] WARNING: possible irq lock inversion dependency detected [ 373.652673][ C0] 7.2.0-virtme #1 Tainted: G W [ 373.652855][ C0] -------------------------------------------------------- [ 373.653070][ C0] swapper/0/0 just changed the state of lock: [ 373.653308][ C0] ff1100000c952e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.653662][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 373.653877][ C0] (fs_reclaim){+.+.}-{0:0} [ 373.653880][ C0] [ 373.653880][ C0] [ 373.653880][ C0] and interrupts could create inverse lock ordering between them. [ 373.653880][ C0] [ 373.654457][ C0] [ 373.654457][ C0] other info that might help us debug this: [ 373.654721][ C0] Chain exists of: [ 373.654721][ C0] &br->lock --> console_owner --> fs_reclaim [ 373.654721][ C0] [ 373.655103][ C0] Possible interrupt unsafe locking scenario: [ 373.655103][ C0] [ 373.655375][ C0] CPU0 CPU1 [ 373.655530][ C0] ---- ---- [ 373.655726][ C0] lock(fs_reclaim); [ 373.655836][ C0] local_irq_disable(); [ 373.656070][ C0] lock(&br->lock); [ 373.656251][ C0] lock(console_owner); [ 373.656497][ C0] [ 373.656614][ C0] lock(&br->lock); [ 373.656785][ C0] [ 373.656785][ C0] *** DEADLOCK *** [ 373.656785][ C0] [ 373.657052][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 373.657231][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 373.657581][ C0] [ 373.657581][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 373.657886][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 373.658094][ C0] HARDIRQ-ON-W at: [ 373.658206][ C0] __lock_acquire+0x388/0xc20 [ 373.658452][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.658632][ C0] fs_reclaim_acquire+0xd5/0x120 [ 373.658874][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 373.659146][ C0] kthread_create_worker_on_node+0xea/0x210 [ 373.659365][ C0] workqueue_init+0x2a/0x680 [ 373.659614][ C0] kernel_init_freeable+0x2fe/0x630 [ 373.659881][ C0] kernel_init+0x21/0x150 [ 373.660061][ C0] ret_from_fork+0x474/0x6b0 [ 373.660303][ C0] ret_from_fork_asm+0x11/0x20 [ 373.660550][ C0] SOFTIRQ-ON-W at: [ 373.660662][ C0] __lock_acquire+0x388/0xc20 [ 373.660900][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.661080][ C0] fs_reclaim_acquire+0xd5/0x120 [ 373.661324][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 373.661605][ C0] kthread_create_worker_on_node+0xea/0x210 [ 373.661818][ C0] workqueue_init+0x2a/0x680 [ 373.662061][ C0] kernel_init_freeable+0x2fe/0x630 [ 373.662336][ C0] kernel_init+0x21/0x150 [ 373.662525][ C0] ret_from_fork+0x474/0x6b0 [ 373.662766][ C0] ret_from_fork_asm+0x11/0x20 [ 373.663001][ C0] INITIAL USE at: [ 373.663111][ C0] __lock_acquire+0x388/0xc20 [ 373.663349][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.663528][ C0] fs_reclaim_acquire+0xd5/0x120 [ 373.663761][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 373.664028][ C0] kthread_create_worker_on_node+0xea/0x210 [ 373.664240][ C0] workqueue_init+0x2a/0x680 [ 373.664482][ C0] kernel_init_freeable+0x2fe/0x630 [ 373.664716][ C0] kernel_init+0x21/0x150 [ 373.664899][ C0] ret_from_fork+0x474/0x6b0 [ 373.665139][ C0] ret_from_fork_asm+0x11/0x20 [ 373.665324][ C0] } [ 373.665456][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 373.665676][ C0] ... acquired at: [ 373.665843][ C0] __lock_acquire+0x518/0xc20 [ 373.666000][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.666202][ C0] fs_reclaim_acquire+0xd5/0x120 [ 373.666343][ C0] __kmalloc_noprof+0xd3/0x760 [ 373.666547][ C0] alloc_buf.isra.0+0x4b/0x260 [ 373.666691][ C0] put_chars+0x1e1/0x2f0 [ 373.666894][ C0] hvc_console_print+0x292/0x780 [ 373.667034][ C0] console_emit_next_record+0x22f/0x4c0 [ 373.667236][ C0] console_flush_one_record+0x46f/0x710 [ 373.667376][ C0] console_unlock+0xee/0x1f0 [ 373.667581][ C0] vprintk_emit+0x37c/0x3e0 [ 373.667721][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 373.667925][ C0] dev_printk_emit+0xb9/0xee [ 373.668068][ C0] _dev_info+0xe2/0x116 [ 373.668238][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 373.668420][ C0] cmos_do_probe+0x73b/0x98a [ 373.668622][ C0] platform_probe+0xfe/0x1f0 [ 373.668764][ C0] call_driver_probe+0x61/0x1c0 [ 373.668970][ C0] really_probe+0x199/0x760 [ 373.669115][ C0] __driver_probe_device+0x24f/0x440 [ 373.669314][ C0] driver_probe_device+0x4a/0xf0 [ 373.669461][ C0] __driver_attach+0x1b8/0x540 [ 373.669656][ C0] bus_for_each_dev+0x130/0x1e0 [ 373.669800][ C0] bus_add_driver+0x2c8/0x530 [ 373.670001][ C0] driver_register+0x1a3/0x390 [ 373.670144][ C0] __platform_driver_probe+0x13f/0x270 [ 373.670340][ C0] cmos_init+0x31/0x40 [ 373.670457][ C0] do_one_initcall+0x124/0x4f0 [ 373.670656][ C0] kernel_init_freeable+0x596/0x630 [ 373.670802][ C0] kernel_init+0x21/0x150 [ 373.671008][ C0] ret_from_fork+0x474/0x6b0 [ 373.671152][ C0] ret_from_fork_asm+0x11/0x20 [ 373.671345][ C0] [ 373.671426][ C0] -> (console_owner){....}-{0:0} { [ 373.671575][ C0] INITIAL USE at: [ 373.671734][ C0] } [ 373.671805][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 373.672072][ C0] ... acquired at: [ 373.672176][ C0] __lock_acquire+0x518/0xc20 [ 373.672376][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.672521][ C0] console_lock_spinning_enable+0x5c/0x60 [ 373.672754][ C0] console_emit_next_record+0x1d1/0x4c0 [ 373.672895][ C0] console_flush_one_record+0x46f/0x710 [ 373.673093][ C0] console_unlock+0xee/0x1f0 [ 373.673238][ C0] vprintk_emit+0x37c/0x3e0 [ 373.673439][ C0] _printk+0xc7/0x100 [ 373.673550][ C0] br_set_state+0x22f/0x430 [bridge] [ 373.673755][ C0] br_init_port+0xc4/0x200 [bridge] [ 373.673901][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 373.674136][ C0] br_add_if+0xde2/0x1320 [bridge] [ 373.674284][ C0] do_set_master+0x357/0x580 [ 373.674486][ C0] do_setlink.isra.0+0xa31/0x2750 [ 373.674629][ C0] rtnl_newlink+0x9f1/0x11c0 [ 373.674820][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 373.674960][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 373.675159][ C0] netlink_unicast+0x486/0x750 [ 373.675301][ C0] netlink_sendmsg+0x735/0xc60 [ 373.675497][ C0] ____sys_sendmsg+0x415/0x880 [ 373.675640][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 373.675835][ C0] __sys_sendmsg+0x12c/0x1d0 [ 373.675978][ C0] do_syscall_64+0xff/0x530 [ 373.676172][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 373.676349][ C0] [ 373.676478][ C0] -> (&br->lock){+.-.}-{3:3} { [ 373.676623][ C0] HARDIRQ-ON-W at: [ 373.676780][ C0] __lock_acquire+0x388/0xc20 [ 373.676960][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.677194][ C0] _raw_spin_lock_bh+0x38/0x50 [ 373.677379][ C0] br_stp_enable_bridge+0x23/0x340 [bridge] [ 373.677668][ C0] br_dev_open+0x94/0x120 [bridge] [ 373.677931][ C0] __dev_open+0x2f3/0x610 [ 373.678119][ C0] __dev_change_flags+0x4a4/0x660 [ 373.678360][ C0] netif_change_flags+0x87/0x190 [ 373.678608][ C0] do_setlink.isra.0+0x1cc3/0x2750 [ 373.678788][ C0] rtnl_newlink+0x9f1/0x11c0 [ 373.679024][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 373.679260][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 373.679441][ C0] netlink_unicast+0x486/0x750 [ 373.679672][ C0] netlink_sendmsg+0x735/0xc60 [ 373.679852][ C0] ____sys_sendmsg+0x415/0x880 [ 373.680082][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 373.680309][ C0] __sys_sendmsg+0x12c/0x1d0 [ 373.680490][ C0] do_syscall_64+0xff/0x530 [ 373.680725][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 373.680996][ C0] IN-SOFTIRQ-W at: [ 373.681108][ C0] __lock_acquire+0x388/0xc20 [ 373.681340][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.681525][ C0] _raw_spin_lock+0x33/0x40 [ 373.681758][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.682032][ C0] call_timer_fn+0x160/0x4d0 [ 373.682215][ C0] __run_timers+0x68f/0xaa0 [ 373.682452][ C0] run_timer_softirq+0xf0/0x160 [ 373.682683][ C0] handle_softirqs+0x1d3/0x900 [ 373.682858][ C0] __irq_exit_rcu+0x145/0x1c0 [ 373.683032][ C0] irq_exit_rcu+0xe/0x30 [ 373.683212][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 373.683483][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 373.683747][ C0] pv_native_safe_halt+0xf/0x10 [ 373.683979][ C0] default_idle+0x9/0x10 [ 373.684162][ C0] default_idle_call+0x6e/0xb0 [ 373.684390][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 373.684673][ C0] do_idle+0xd8/0x190 [ 373.684820][ C0] cpu_startup_entry+0x53/0x70 [ 373.685054][ C0] rest_init+0x279/0x280 [ 373.685235][ C0] start_kernel+0x3af/0x3b0 [ 373.685482][ C0] x86_64_start_reservations+0x24/0x30 [ 373.685725][ C0] x86_64_start_kernel+0x12b/0x130 [ 373.685907][ C0] common_startup_64+0x13e/0x148 [ 373.686149][ C0] INITIAL USE at: [ 373.686261][ C0] __lock_acquire+0x388/0xc20 [ 373.686502][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.686689][ C0] _raw_spin_lock_bh+0x38/0x50 [ 373.686926][ C0] br_stp_enable_bridge+0x23/0x340 [bridge] [ 373.687211][ C0] br_dev_open+0x94/0x120 [bridge] [ 373.687410][ C0] __dev_open+0x2f3/0x610 [ 373.687645][ C0] __dev_change_flags+0x4a4/0x660 [ 373.687880][ C0] netif_change_flags+0x87/0x190 [ 373.688067][ C0] do_setlink.isra.0+0x1cc3/0x2750 [ 373.688310][ C0] rtnl_newlink+0x9f1/0x11c0 [ 373.688556][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 373.688740][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 373.688981][ C0] netlink_unicast+0x486/0x750 [ 373.689171][ C0] netlink_sendmsg+0x735/0xc60 [ 373.689413][ C0] ____sys_sendmsg+0x415/0x880 [ 373.689655][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 373.689845][ C0] __sys_sendmsg+0x12c/0x1d0 [ 373.690086][ C0] do_syscall_64+0xff/0x530 [ 373.690323][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 373.690550][ C0] } [ 373.690682][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 373.690909][ C0] ... acquired at: [ 373.691077][ C0] mark_lock+0x1d7/0xa00 [ 373.691222][ C0] mark_usage+0x42/0x170 [ 373.691431][ C0] __lock_acquire+0x388/0xc20 [ 373.691577][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.691785][ C0] _raw_spin_lock+0x33/0x40 [ 373.691936][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.692177][ C0] call_timer_fn+0x160/0x4d0 [ 373.692328][ C0] __run_timers+0x68f/0xaa0 [ 373.692536][ C0] run_timer_softirq+0xf0/0x160 [ 373.692682][ C0] handle_softirqs+0x1d3/0x900 [ 373.692879][ C0] __irq_exit_rcu+0x145/0x1c0 [ 373.693027][ C0] irq_exit_rcu+0xe/0x30 [ 373.693230][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 373.693415][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 373.693665][ C0] pv_native_safe_halt+0xf/0x10 [ 373.693871][ C0] default_idle+0x9/0x10 [ 373.694020][ C0] default_idle_call+0x6e/0xb0 [ 373.694222][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 373.694415][ C0] do_idle+0xd8/0x190 [ 373.694584][ C0] cpu_startup_entry+0x53/0x70 [ 373.694733][ C0] rest_init+0x279/0x280 [ 373.694938][ C0] start_kernel+0x3af/0x3b0 [ 373.695090][ C0] x86_64_start_reservations+0x24/0x30 [ 373.695298][ C0] x86_64_start_kernel+0x12b/0x130 [ 373.695447][ C0] common_startup_64+0x13e/0x148 [ 373.695644][ C0] [ 373.695718][ C0] [ 373.695718][ C0] stack backtrace: [ 373.695906][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 373.695910][ C0] Tainted: [W]=WARN [ 373.695911][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 373.695912][ C0] Call Trace: [ 373.695914][ C0] [ 373.695915][ C0] dump_stack_lvl+0x6f/0xa0 [ 373.695919][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 373.695923][ C0] mark_lock_irq+0x989/0x9c0 [ 373.695926][ C0] mark_lock+0x1d7/0xa00 [ 373.695928][ C0] mark_usage+0x42/0x170 [ 373.695930][ C0] __lock_acquire+0x388/0xc20 [ 373.695932][ C0] lock_acquire.part.0+0xd4/0x280 [ 373.695934][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.695942][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.695945][ C0] ? lock_acquire+0x13c/0x160 [ 373.695947][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 373.695953][ C0] _raw_spin_lock+0x33/0x40 [ 373.695956][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.695962][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 373.695968][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 373.695975][ C0] call_timer_fn+0x160/0x4d0 [ 373.695977][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 373.695979][ C0] ? debug_object_active_state+0x430/0x430 [ 373.695983][ C0] ? find_held_lock+0x2b/0x80 [ 373.695985][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 373.695987][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.695989][ C0] __run_timers+0x68f/0xaa0 [ 373.695991][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 373.695998][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 373.696000][ C0] ? __lock_acquire+0x518/0xc20 [ 373.696004][ C0] ? __rwlock_init+0x150/0x150 [ 373.696007][ C0] run_timer_softirq+0xf0/0x160 [ 373.696009][ C0] ? __run_timers+0xaa0/0xaa0 [ 373.696011][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 373.696013][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.696015][ C0] handle_softirqs+0x1d3/0x900 [ 373.696017][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 373.696019][ C0] ? _local_bh_enable+0xc0/0xc0 [ 373.696021][ C0] __irq_exit_rcu+0x145/0x1c0 [ 373.696022][ C0] irq_exit_rcu+0xe/0x30 [ 373.696024][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 373.696026][ C0] [ 373.696026][ C0] [ 373.696027][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 373.696029][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 373.696031][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 373.696033][ C0] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 373.696035][ C0] RSP: 0018:ffffffff98a07cf8 EFLAGS: 00000296 [ 373.696038][ C0] RAX: 00000000004c5fff RBX: ffffffff98a1c600 RCX: ffffffff95af0307 [ 373.696039][ C0] RDX: ffffffff98a1c600 RSI: ffffffff98838af6 RDI: ffffffff9828d8e0 [ 373.696040][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 373.696041][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3140fa2 [ 373.696042][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 373.696043][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 373.696046][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 373.696047][ C0] default_idle+0x9/0x10 [ 373.696049][ C0] default_idle_call+0x6e/0xb0 [ 373.696051][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 373.696052][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 373.696054][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 373.696056][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 373.696057][ C0] ? rcu_is_watching+0x16/0xd0 [ 373.696060][ C0] do_idle+0xd8/0x190 [ 373.696061][ C0] cpu_startup_entry+0x53/0x70 [ 373.696063][ C0] rest_init+0x279/0x280 [ 373.696065][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 373.696067][ C0] ? rest_init+0x280/0x280 [ 373.696069][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 373.696073][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 373.696076][ C0] ? acpi_enable+0x1e4/0x330 [ 373.696078][ C0] start_kernel+0x3af/0x3b0 [ 373.696080][ C0] x86_64_start_reservations+0x24/0x30 [ 373.696082][ C0] x86_64_start_kernel+0x12b/0x130 [ 373.696084][ C0] common_startup_64+0x13e/0x148 [ 373.696087][ C0] WAIT TIMEOUT stderr Ctrl-C stderr Ctrl-C stderr [ 673.879516][ T1470] veth2: left promiscuous mode [ 673.879534][ T1470] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 673.879537][ T1470] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1470, name: tcpdump [ 673.879539][ T1470] preempt_count: 1, expected: 0 [ 673.879540][ T1470] RCU nest depth: 0, expected: 0 [ 673.879541][ T1470] INFO: lockdep is turned off. [ 673.879542][ T1470] irq event stamp: 47804 [ 673.879543][ T1470] hardirqs last enabled at (47803): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 673.879552][ T1470] hardirqs last disabled at (47804): [] __schedule+0x1246/0x20e0 [ 673.879554][ T1470] softirqs last enabled at (47800): [] packet_poll+0x2c7/0x520 [ 673.879558][ T1470] softirqs last disabled at (47798): [] packet_poll+0x1cd/0x520 [ 673.879560][ T1470] Preemption disabled at: [ 673.879561][ T1470] [] vprintk_emit+0x31b/0x3e0 [ 673.879570][ T1470] CPU: 0 UID: 72 PID: 1470 Comm: tcpdump Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 673.879574][ T1470] Tainted: [W]=WARN [ 673.879575][ T1470] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 673.879577][ T1470] Call Trace: [ 673.879579][ T1470] [ 673.879580][ T1470] dump_stack_lvl+0x6f/0xa0 [ 673.879586][ T1470] ? vprintk_emit+0x31b/0x3e0 [ 673.879588][ T1470] __might_resched.cold+0x1fe/0x2c1 [ 673.879592][ T1470] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 673.879596][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879600][ T1470] __kmalloc_noprof+0x443/0x760 [ 673.879603][ T1470] ? __rwlock_init+0x150/0x150 [ 673.879606][ T1470] ? alloc_buf.isra.0+0x4b/0x260 [ 673.879610][ T1470] ? do_raw_spin_unlock+0x59/0x250 [ 673.879612][ T1470] alloc_buf.isra.0+0x4b/0x260 [ 673.879614][ T1470] put_chars+0x1e1/0x2f0 [ 673.879616][ T1470] ? __send_to_port+0x420/0x420 [ 673.879618][ T1470] ? printk_get_next_message+0x240/0x650 [ 673.879622][ T1470] hvc_console_print+0x292/0x780 [ 673.879634][ T1470] ? hvc_write+0x3a0/0x3a0 [ 673.879636][ T1470] ? __rwlock_init+0x150/0x150 [ 673.879638][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879640][ T1470] ? lock_acquire+0x13c/0x160 [ 673.879642][ T1470] console_emit_next_record+0x22f/0x4c0 [ 673.879646][ T1470] ? devkmsg_read+0x4b0/0x4b0 [ 673.879648][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879650][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879651][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879653][ T1470] ? lock_acquire+0x13c/0x160 [ 673.879655][ T1470] ? console_flush_one_record+0x111/0x710 [ 673.879657][ T1470] console_flush_one_record+0x46f/0x710 [ 673.879660][ T1470] ? console_emit_next_record+0x4c0/0x4c0 [ 673.879662][ T1470] console_unlock+0xee/0x1f0 [ 673.879664][ T1470] ? lock_acquire+0x13c/0x160 [ 673.879666][ T1470] ? console_flush_one_record+0x710/0x710 [ 673.879668][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879670][ T1470] ? lock_release+0x184/0x1f0 [ 673.879671][ T1470] ? lock_acquire+0x60/0x160 [ 673.879673][ T1470] ? __rcu_read_lock+0x30/0x70 [ 673.879675][ T1470] ? __down_trylock_console_sem+0x5e/0xa0 [ 673.879677][ T1470] ? vprintk_emit+0x320/0x3e0 [ 673.879680][ T1470] vprintk_emit+0x37c/0x3e0 [ 673.879682][ T1470] ? wake_up_klogd_work_func+0x90/0x90 [ 673.879684][ T1470] ? is_bpf_text_address+0x3e/0x110 [ 673.879688][ T1470] ? kernel_text_address+0x149/0x170 [ 673.879690][ T1470] ? __kernel_text_address+0x12/0x30 [ 673.879692][ T1470] _printk+0xc7/0x100 [ 673.879696][ T1470] ? snapshot_read.cold+0x21/0x21 [ 673.879698][ T1470] ? rcu_is_watching+0x16/0xd0 [ 673.879700][ T1470] ? netdev_name+0x43/0x60 [ 673.879703][ T1470] ? __netdev_printk+0x17f/0x1d0 [ 673.879705][ T1470] netdev_info+0xe2/0x116 [ 673.879707][ T1470] ? netdev_notice+0x120/0x120 [ 673.879708][ T1470] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 673.879711][ T1470] ? ww_mutex_lock+0x160/0x160 [ 673.879713][ T1470] ? ref_tracker_alloc+0x430/0x430 [ 673.879716][ T1470] ? packet_release+0x363/0xc40 [ 673.879718][ T1470] ? __fput+0x363/0xac0 [ 673.879720][ T1470] ? mutex_is_locked+0x1c/0x50 [ 673.879723][ T1470] __dev_set_promiscuity.cold+0x95/0x104 [ 673.879727][ T1470] netif_set_promiscuity+0x4c/0xc0 [ 673.879731][ T1470] dev_set_promiscuity+0x9d/0x150 [ 673.879733][ T1470] packet_release+0x4cb/0xc40 [ 673.879736][ T1470] ? packet_set_ring+0x1740/0x1740 [ 673.879738][ T1470] ? rwsem_down_write_slowpath+0xbe0/0xbe0 [ 673.879740][ T1470] __sock_release+0xb8/0x280 [ 673.879744][ T1470] sock_close+0x18/0x20 [ 673.879746][ T1470] __fput+0x363/0xac0 [ 673.879748][ T1470] fput_close_sync+0xde/0x1b0 [ 673.879749][ T1470] ? alloc_file_clone+0xe0/0xe0 [ 673.879751][ T1470] ? lock_release+0x184/0x1f0 [ 673.879752][ T1470] ? do_raw_spin_unlock+0x59/0x250 [ 673.879755][ T1470] __x64_sys_close+0x8b/0xf0 [ 673.879757][ T1470] do_syscall_64+0xff/0x530 [ 673.879760][ T1470] ? exc_page_fault+0xee/0x100 [ 673.879762][ T1470] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 673.879764][ T1470] RIP: 0033:0x7f824924754e [ 673.879768][ T1470] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 673.879770][ T1470] RSP: 002b:00007ffda0b90000 EFLAGS: 00000202 ORIG_RAX: 0000000000000003 [ 673.879773][ T1470] RAX: ffffffffffffffda RBX: 00005608f2d02690 RCX: 00007f824924754e [ 673.879775][ T1470] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005 [ 673.879776][ T1470] RBP: 00007ffda0b90010 R08: 0000000000000000 R09: 0000000000000000 [ 673.879777][ T1470] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000000 [ 673.879778][ T1470] R13: 00000000fffffffe R14: 00005608d48e4760 R15: 0000000000000001 [ 673.879780][ T1470] WAIT TIMEOUT stderr