[ 9.649413][ T187] ip (187) used greatest stack depth: 24384 bytes left [ 9.649430][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.649431][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 9.649433][ T187] preempt_count: 2, expected: 0 [ 9.649434][ T187] RCU nest depth: 0, expected: 0 [ 9.649435][ T187] locks held by ip/187: 5, last CPU#2: [ 9.649437][ T187] #0: ffffffff980027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.649450][ T187] #1: ffffffff98169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.649456][ T187] #2: ffffffff98169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.649460][ T187] #3: ffffffff98089660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.649464][ T187] #4: ffffffff98089560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.649468][ T187] irq event stamp: 37634 [ 9.649469][ T187] hardirqs last enabled at (37633): [] __down_trylock_console_sem+0x86/0xa0 [ 9.649472][ T187] hardirqs last disabled at (37634): [] console_emit_next_record+0x3d4/0x4c0 [ 9.649474][ T187] softirqs last enabled at (36418): [] handle_softirqs+0x67c/0x900 [ 9.649475][ T187] softirqs last disabled at (36411): [] __irq_exit_rcu+0x145/0x1c0 [ 9.649478][ T187] Preemption disabled at: [ 9.649478][ T187] [<0000000000000000>] 0x0 [ 9.649485][ T187] CPU: 2 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.649489][ T187] Tainted: [W]=WARN [ 9.649490][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.649492][ T187] Call Trace: [ 9.649493][ T187] [ 9.649495][ T187] dump_stack_lvl+0x6f/0xa0 [ 9.649501][ T187] __might_resched.cold+0x1fe/0x2c1 [ 9.649505][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.649515][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 9.649520][ T187] __kmalloc_noprof+0x443/0x760 [ 9.649522][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 9.649528][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 9.649531][ T187] alloc_buf.isra.0+0x4b/0x260 [ 9.649535][ T187] put_chars+0x1e1/0x2f0 [ 9.649538][ T187] ? __send_to_port+0x420/0x420 [ 9.649539][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.649544][ T187] ? validate_chain+0x38b/0xc20 [ 9.649549][ T187] hvc_console_print+0x292/0x780 [ 9.649555][ T187] ? hvc_write+0x3a0/0x3a0 [ 9.649557][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.649560][ T187] ? lock_acquire+0x13c/0x160 [ 9.649564][ T187] console_emit_next_record+0x22f/0x4c0 [ 9.649568][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 9.649570][ T187] ? console_flush_one_record+0x106/0x710 [ 9.649573][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.649575][ T187] ? lock_acquire+0x13c/0x160 [ 9.649579][ T187] console_flush_one_record+0x46f/0x710 [ 9.649583][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 9.649585][ T187] ? __lock_acquire+0x518/0xc20 [ 9.649590][ T187] console_unlock+0xee/0x1f0 [ 9.649593][ T187] ? console_flush_one_record+0x710/0x710 [ 9.649594][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.649597][ T187] ? lock_acquire+0x60/0x160 [ 9.649600][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.649602][ T187] ? vprintk_emit+0x320/0x3e0 [ 9.649605][ T187] vprintk_emit+0x37c/0x3e0 [ 9.649609][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 9.649612][ T187] ? __lock_acquire+0x518/0xc20 [ 9.649616][ T187] _printk+0xc7/0x100 [ 9.649620][ T187] ? snapshot_read.cold+0x21/0x21 [ 9.649623][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.649625][ T187] ? __rwlock_init+0x150/0x150 [ 9.649629][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.649632][ T187] do_exit.cold+0x82/0x9c [ 9.649635][ T187] ? exit_notify+0x890/0x890 [ 9.649637][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 9.649640][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.649644][ T187] do_group_exit+0xb8/0x370 [ 9.649647][ T187] __x64_sys_exit_group+0x3c/0x50 [ 9.649648][ T187] x64_sys_call+0x1567/0x1570 [ 9.649650][ T187] do_syscall_64+0xff/0x530 [ 9.649654][ T187] ? exc_page_fault+0xee/0x100 [ 9.649657][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.649659][ T187] RIP: 0033:0x7f62be2911b8 [ 9.649661][ T187] Code: Unable to access opcode bytes at 0x7f62be29118e. [ 9.649662][ T187] RSP: 002b:00007ffc743b0188 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.649665][ T187] RAX: ffffffffffffffda RBX: 00007f62be3c1f88 RCX: 00007f62be2911b8 [ 9.649666][ T187] RDX: 00007f62bdfdbfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.649667][ T187] RBP: 00007ffc743b01e0 R08: 0000000000000000 R09: 0000000000008010 [ 9.649668][ T187] R10: 00007ffc743affa0 R11: 0000000000000246 R12: 0000000000000001 [ 9.649669][ T187] R13: 0000000000000000 R14: 00007f62be3c0680 R15: 00007f62be3c1fa0 [ 9.649676][ T187] [ 9.798447][ T193] ip (193) used greatest stack depth: 23936 bytes left [ 11.012194][ T237] br0: port 1(veth1) entered blocking state [ 11.012301][ T237] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.012304][ T237] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 237, name: ip [ 11.012305][ T237] preempt_count: 1, expected: 0 [ 11.012307][ T237] RCU nest depth: 0, expected: 0 [ 11.012308][ T237] locks held by ip/237: 5, last CPU#0: [ 11.012311][ T237] #0: ffffffff988d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.012326][ T237] #1: ffffffff98169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.012334][ T237] #2: ffffffff98169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.012340][ T237] #3: ffffffff98089660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.012346][ T237] #4: ffffffff98089560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.012352][ T237] irq event stamp: 19156 [ 11.012353][ T237] hardirqs last enabled at (19155): [] __down_trylock_console_sem+0x86/0xa0 [ 11.012357][ T237] hardirqs last disabled at (19156): [] console_emit_next_record+0x3d4/0x4c0 [ 11.012359][ T237] softirqs last enabled at (19066): [] __alloc_skb+0x4c2/0x5f0 [ 11.012364][ T237] softirqs last disabled at (19064): [] __alloc_skb+0x4c2/0x5f0 [ 11.012367][ T237] Preemption disabled at: [ 11.012368][ T237] [] vprintk_emit+0x31b/0x3e0 [ 11.012375][ T237] CPU: 0 UID: 0 PID: 237 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.012380][ T237] Tainted: [W]=WARN [ 11.012381][ T237] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.012383][ T237] Call Trace: [ 11.012385][ T237] [ 11.012387][ T237] dump_stack_lvl+0x6f/0xa0 [ 11.012394][ T237] ? vprintk_emit+0x31b/0x3e0 [ 11.012398][ T237] __might_resched.cold+0x1fe/0x2c1 [ 11.012405][ T237] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.012410][ T237] ? __kmalloc_noprof+0xdb/0x760 [ 11.012418][ T237] __kmalloc_noprof+0x443/0x760 [ 11.012421][ T237] ? alloc_buf.isra.0+0x4b/0x260 [ 11.012429][ T237] ? do_raw_spin_unlock+0x59/0x250 [ 11.012433][ T237] alloc_buf.isra.0+0x4b/0x260 [ 11.012438][ T237] put_chars+0x1e1/0x2f0 [ 11.012442][ T237] ? __send_to_port+0x420/0x420 [ 11.012450][ T237] ? validate_chain+0x34a/0xc20 [ 11.012456][ T237] hvc_console_print+0x292/0x780 [ 11.012460][ T237] ? mark_usage+0x61/0x170 [ 11.012462][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012465][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012471][ T237] ? hvc_write+0x3a0/0x3a0 [ 11.012475][ T237] ? console_emit_next_record+0x1df/0x4c0 [ 11.012480][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.012485][ T237] ? lock_acquire+0x13c/0x160 [ 11.012492][ T237] console_emit_next_record+0x22f/0x4c0 [ 11.012498][ T237] ? devkmsg_read+0x4b0/0x4b0 [ 11.012500][ T237] ? console_flush_one_record+0x106/0x710 [ 11.012505][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.012509][ T237] ? lock_acquire+0x13c/0x160 [ 11.012521][ T237] console_flush_one_record+0x46f/0x710 [ 11.012528][ T237] ? console_emit_next_record+0x4c0/0x4c0 [ 11.012531][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012540][ T237] console_unlock+0xee/0x1f0 [ 11.012544][ T237] ? console_flush_one_record+0x710/0x710 [ 11.012546][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.012550][ T237] ? lock_acquire+0x60/0x160 [ 11.012556][ T237] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.012559][ T237] ? vprintk_emit+0x320/0x3e0 [ 11.012564][ T237] vprintk_emit+0x37c/0x3e0 [ 11.012570][ T237] ? wake_up_klogd_work_func+0x90/0x90 [ 11.012574][ T237] ? __lock_release.isra.0+0x69/0x1a0 [ 11.012576][ T237] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.012582][ T237] ? mark_held_locks+0x40/0x70 [ 11.012588][ T237] _printk+0xc7/0x100 [ 11.012593][ T237] ? snapshot_read.cold+0x21/0x21 [ 11.012599][ T237] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.012616][ T237] ? do_setlink.isra.0+0xa31/0x2750 [ 11.012618][ T237] ? rtnl_newlink+0x9f1/0x11c0 [ 11.012620][ T237] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.012628][ T237] br_set_state+0x22f/0x430 [bridge] [ 11.012645][ T237] br_init_port+0xc4/0x200 [bridge] [ 11.012658][ T237] new_nbp+0x39c/0x580 [bridge] [ 11.012673][ T237] br_add_if+0x212/0x1320 [bridge] [ 11.012685][ T237] ? is_bpf_text_address+0x72/0x110 [ 11.012690][ T237] ? kernel_text_address+0x149/0x170 [ 11.012694][ T237] ? __kernel_text_address+0x12/0x30 [ 11.012701][ T237] do_set_master+0x357/0x580 [ 11.012708][ T237] do_setlink.isra.0+0xa31/0x2750 [ 11.012713][ T237] ? stack_trace_save+0x93/0xc0 [ 11.012717][ T237] ? rtnl_link_get_size+0x350/0x350 [ 11.012719][ T237] ? rcu_read_lock_any_held+0x66/0x90 [ 11.012723][ T237] ? stack_depot_save_flags+0x38e/0x790 [ 11.012727][ T237] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.012731][ T237] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.012734][ T237] ? validate_chain+0x38b/0xc20 [ 11.012738][ T237] ? kasan_save_stack+0x3d/0x50 [ 11.012742][ T237] ? kasan_save_stack+0x2f/0x50 [ 11.012744][ T237] ? kasan_save_track+0x14/0x30 [ 11.012749][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012751][ T237] ? netlink_seq_next+0xe/0x60 [ 11.012755][ T237] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.012762][ T237] ? lock_acquire.part.0+0xd4/0x280 [ 11.012765][ T237] ? rtnl_newlink+0x9a8/0x11c0 [ 11.012770][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.012773][ T237] ? lock_acquire+0x13c/0x160 [ 11.012776][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.012778][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.012781][ T237] ? trace_contention_end+0xb3/0x180 [ 11.012787][ T237] ? __mutex_lock+0x1db/0x1ea0 [ 11.012789][ T237] ? __mutex_lock+0x9a3/0x1ea0 [ 11.012793][ T237] ? rtnl_newlink+0x9a8/0x11c0 [ 11.012798][ T237] ? ww_mutex_lock+0x160/0x160 [ 11.012801][ T237] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.012810][ T237] ? __rtnl_newlink+0x3fa/0xa50 [ 11.012819][ T237] rtnl_newlink+0x9f1/0x11c0 [ 11.012828][ T237] ? rtnl_bridge_getlink+0x850/0x850 [ 11.012831][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012840][ T237] ? lock_acquire.part.0+0xd4/0x280 [ 11.012843][ T237] ? find_held_lock+0x2b/0x80 [ 11.012846][ T237] ? rtnl_bridge_getlink+0x850/0x850 [ 11.012849][ T237] ? __lock_release.isra.0+0x69/0x1a0 [ 11.012856][ T237] ? rtnl_bridge_getlink+0x850/0x850 [ 11.012859][ T237] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.012865][ T237] ? rtnl_link_fill+0x920/0x920 [ 11.012867][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012874][ T237] ? lock_acquire.part.0+0xd4/0x280 [ 11.012877][ T237] ? find_held_lock+0x2b/0x80 [ 11.012883][ T237] netlink_rcv_skb+0x14e/0x3a0 [ 11.012886][ T237] ? rtnl_link_fill+0x920/0x920 [ 11.012891][ T237] ? netlink_ack+0xcf0/0xcf0 [ 11.012902][ T237] ? netlink_deliver_tap+0xc5/0x330 [ 11.012904][ T237] ? netlink_deliver_tap+0x13c/0x330 [ 11.012912][ T237] netlink_unicast+0x486/0x750 [ 11.012919][ T237] ? netlink_attachskb+0x810/0x810 [ 11.012923][ T237] ? __lock_acquire+0x518/0xc20 [ 11.012930][ T237] netlink_sendmsg+0x735/0xc60 [ 11.012937][ T237] ? netlink_unicast+0x750/0x750 [ 11.012944][ T237] ? __might_fault+0x97/0x140 [ 11.012952][ T237] ____sys_sendmsg+0x415/0x880 [ 11.012956][ T237] ? copy_msghdr_from_user+0x279/0x420 [ 11.012959][ T237] ? get_timestamp.constprop.0+0x390/0x390 [ 11.012962][ T237] ? move_addr_to_kernel+0x40/0x40 [ 11.012975][ T237] ___sys_sendmsg+0x14e/0x1d0 [ 11.012980][ T237] ? copy_msghdr_from_user+0x420/0x420 [ 11.013010][ T237] __sys_sendmsg+0x12c/0x1d0 [ 11.013014][ T237] ? __sys_sendmsg_sock+0x20/0x20 [ 11.013026][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.013032][ T237] do_syscall_64+0xff/0x530 [ 11.013035][ T237] ? exc_page_fault+0xee/0x100 [ 11.013040][ T237] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.013043][ T237] RIP: 0033:0x7f7d5b0a954e [ 11.013048][ T237] 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 [ 11.013050][ T237] RSP: 002b:00007fffbe565ac0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.013055][ T237] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f7d5b0a954e [ 11.013057][ T237] RDX: 0000000000000000 RSI: 00007fffbe565b70 RDI: 0000000000000005 [ 11.013059][ T237] RBP: 00007fffbe565ad0 R08: 0000000000000000 R09: 0000000000000000 [ 11.013060][ T237] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91abf1 [ 11.013061][ T237] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffbe566230 [ 11.013074][ T237] [ 11.092405][ T237] br0: port 1(veth1) entered disabled state [ 11.093074][ T237] veth1: entered allmulticast mode [ 11.096169][ T237] veth1: entered promiscuous mode [ 11.115218][ T237] ip (237) used greatest stack depth: 23336 bytes left [ 11.140387][ T239] br0: port 2(veth2) entered blocking state [ 11.140717][ T239] br0: port 2(veth2) entered disabled state [ 11.141028][ T239] veth2: entered allmulticast mode [ 11.142530][ T239] veth2: entered promiscuous mode [ 11.200016][ T189] br0: port 1(veth1) entered blocking state [ 11.200468][ T189] br0: port 1(veth1) entered forwarding state [ 21.387162][ T39] br0: port 2(veth2) entered blocking state [ 21.387185][ T39] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 21.387188][ T39] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 39, name: kworker/u20:0 [ 21.387190][ T39] preempt_count: 202, expected: 0 [ 21.387191][ T39] RCU nest depth: 0, expected: 0 [ 21.387193][ T39] locks held by kworker/u20:0/39: 8, last CPU#3: [ 21.387196][ T39] #0: ff1100000114a940 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 21.387212][ T39] #1: ffa00000002bfd10 ((linkwatch_work).work){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 21.387217][ T39] #2: ffffffff988d2c40 (rtnl_mutex){+.+.}-{4:4}, at: linkwatch_event+0x4e/0xb0 [ 21.387225][ T39] #3: ff1100000f44ae58 (&br->lock){+...}-{3:3}, at: br_port_carrier_check+0x161/0x430 [bridge] [ 21.387246][ T39] #4: ffffffff98169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 21.387254][ T39] #5: ffffffff98169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 21.387259][ T39] #6: ffffffff98089660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 21.387265][ T39] #7: ffffffff98089560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 21.387271][ T39] irq event stamp: 62351 [ 21.387272][ T39] hardirqs last enabled at (62350): [] __down_trylock_console_sem+0x86/0xa0 [ 21.387276][ T39] hardirqs last disabled at (62351): [] console_emit_next_record+0x3d4/0x4c0 [ 21.387279][ T39] softirqs last enabled at (62312): [] inet6_fill_ifla6_attrs+0x1b3e/0x21e0 [ 21.387284][ T39] softirqs last disabled at (62346): [] br_port_carrier_check+0x161/0x430 [bridge] [ 21.387297][ T39] Preemption disabled at: [ 21.387298][ T39] [<0000000000000000>] 0x0 [ 21.387306][ T39] CPU: 3 UID: 0 PID: 39 Comm: kworker/u20:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 21.387310][ T39] Tainted: [W]=WARN [ 21.387312][ T39] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 21.387314][ T39] Workqueue: events_unbound linkwatch_event [ 21.387318][ T39] Call Trace: [ 21.387320][ T39] [ 21.387323][ T39] dump_stack_lvl+0x6f/0xa0 [ 21.387331][ T39] __might_resched.cold+0x1fe/0x2c1 [ 21.387337][ T39] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 21.387343][ T39] ? __kmalloc_noprof+0xdb/0x760 [ 21.387350][ T39] __kmalloc_noprof+0x443/0x760 [ 21.387354][ T39] ? alloc_buf.isra.0+0x4b/0x260 [ 21.387363][ T39] ? do_raw_spin_unlock+0x59/0x250 [ 21.387367][ T39] alloc_buf.isra.0+0x4b/0x260 [ 21.387372][ T39] put_chars+0x1e1/0x2f0 [ 21.387376][ T39] ? __send_to_port+0x420/0x420 [ 21.387381][ T39] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 21.387387][ T39] ? rcu_read_lock_any_held+0x3c/0x90 [ 21.387390][ T39] ? validate_chain+0x38b/0xc20 [ 21.387397][ T39] hvc_console_print+0x292/0x780 [ 21.387400][ T39] ? mark_usage+0x61/0x170 [ 21.387403][ T39] ? __lock_acquire+0x518/0xc20 [ 21.387405][ T39] ? __lock_acquire+0x518/0xc20 [ 21.387412][ T39] ? hvc_write+0x3a0/0x3a0 [ 21.387414][ T39] ? lock_acquire.part.0+0xd4/0x280 [ 21.387422][ T39] ? lock_acquire+0x13c/0x160 [ 21.387429][ T39] console_emit_next_record+0x22f/0x4c0 [ 21.387436][ T39] ? devkmsg_read+0x4b0/0x4b0 [ 21.387438][ T39] ? console_flush_one_record+0x106/0x710 [ 21.387444][ T39] ? rcu_is_watching+0x16/0xd0 [ 21.387448][ T39] ? lock_acquire+0x13c/0x160 [ 21.387455][ T39] console_flush_one_record+0x46f/0x710 [ 21.387461][ T39] ? console_emit_next_record+0x4c0/0x4c0 [ 21.387464][ T39] ? __lock_acquire+0x518/0xc20 [ 21.387473][ T39] console_unlock+0xee/0x1f0 [ 21.387478][ T39] ? console_flush_one_record+0x710/0x710 [ 21.387480][ T39] ? rcu_is_watching+0x16/0xd0 [ 21.387484][ T39] ? lock_acquire+0x60/0x160 [ 21.387490][ T39] ? __down_trylock_console_sem+0x5e/0xa0 [ 21.387493][ T39] ? vprintk_emit+0x320/0x3e0 [ 21.387498][ T39] vprintk_emit+0x37c/0x3e0 [ 21.387505][ T39] ? wake_up_klogd_work_func+0x90/0x90 [ 21.387509][ T39] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 21.387513][ T39] ? rcu_read_lock_any_held+0x3c/0x90 [ 21.387520][ T39] _printk+0xc7/0x100 [ 21.387525][ T39] ? snapshot_read.cold+0x21/0x21 [ 21.387534][ T39] ? lock_acquire.part.0+0xd4/0x280 [ 21.387541][ T39] br_set_state+0x22f/0x430 [bridge] [ 21.387563][ T39] br_init_port+0xc4/0x200 [bridge] [ 21.387578][ T39] br_stp_enable_port+0x12/0x50 [bridge] [ 21.387590][ T39] br_port_carrier_check+0x220/0x430 [bridge] [ 21.387605][ T39] br_device_event+0x52d/0x8f0 [bridge] [ 21.387620][ T39] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 21.387623][ T39] ? nlmsg_notify+0x62/0x250 [ 21.387634][ T39] notifier_call_chain+0xae/0x300 [ 21.387644][ T39] netif_state_change+0x139/0x340 [ 21.387651][ T39] ? call_netdevice_notifiers_info+0xd0/0xd0 [ 21.387659][ T39] ? linkwatch_do_dev+0xae/0xd0 [ 21.387663][ T39] __linkwatch_run_queue+0x34c/0x750 [ 21.387668][ T39] ? linkwatch_do_dev+0xd0/0xd0 [ 21.387672][ T39] ? lock_acquire.part.0+0xd4/0x280 [ 21.387680][ T39] linkwatch_event+0x7f/0xb0 [ 21.387684][ T39] ? __linkwatch_run_queue+0x750/0x750 [ 21.387689][ T39] process_one_work+0xe3e/0x1560 [ 21.387701][ T39] ? __queue_delayed_work+0x470/0x470 [ 21.387706][ T39] ? lock_acquire.part.0+0xd4/0x280 [ 21.387720][ T39] worker_thread+0x4f1/0xd60 [ 21.387733][ T39] ? rescuer_thread+0x1340/0x1340 [ 21.387736][ T39] kthread+0x367/0x460 [ 21.387739][ T39] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 21.387743][ T39] ? kthread_affine_preferred+0x4c0/0x4c0 [ 21.387747][ T39] ret_from_fork+0x474/0x6b0 [ 21.387753][ T39] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 21.387759][ T39] ? __switch_to+0x5a3/0xe00 [ 21.387763][ T39] ? kthread_affine_preferred+0x4c0/0x4c0 [ 21.387768][ T39] ret_from_fork_asm+0x11/0x20 [ 21.387783][ T39] [ 21.434906][ T39] br0: port 2(veth2) entered forwarding state [ 26.485898][ C2] [ 26.485914][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.485917][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 26.485919][ C2] preempt_count: 104, expected: 0 [ 26.485920][ C2] RCU nest depth: 0, expected: 0 [ 26.485921][ C2] INFO: lockdep is turned off. [ 26.485922][ C2] irq event stamp: 637172 [ 26.485922][ C2] hardirqs last enabled at (637172): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.485931][ C2] hardirqs last disabled at (637171): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.485933][ C2] softirqs last enabled at (637156): [] handle_softirqs+0x67c/0x900 [ 26.485937][ C2] softirqs last disabled at (637169): [] __irq_exit_rcu+0x145/0x1c0 [ 26.485939][ C2] Preemption disabled at: [ 26.485940][ C2] [<0000000000000000>] 0x0 [ 26.485947][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.485951][ C2] Tainted: [W]=WARN [ 26.485952][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.485954][ C2] Call Trace: [ 26.485956][ C2] [ 26.485957][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.485963][ C2] __might_resched.cold+0x1fe/0x2c1 [ 26.485967][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.485971][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.485974][ C2] ? __asan_memcpy+0x3c/0x60 [ 26.485977][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.485979][ C2] __kmalloc_noprof+0x443/0x760 [ 26.485983][ C2] ? __rwlock_init+0x150/0x150 [ 26.485985][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 26.485989][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 26.485991][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.485993][ C2] put_chars+0x1e1/0x2f0 [ 26.485996][ C2] ? __send_to_port+0x420/0x420 [ 26.485998][ C2] ? console_prepend_replay+0x20/0x20 [ 26.486001][ C2] hvc_console_print+0x292/0x780 [ 26.486005][ C2] ? hvc_write+0x3a0/0x3a0 [ 26.486007][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486009][ C2] ? lock_acquire+0x13c/0x160 [ 26.486011][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.486014][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 26.486017][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486019][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486021][ C2] ? lock_acquire+0x13c/0x160 [ 26.486023][ C2] ? console_flush_one_record+0x111/0x710 [ 26.486025][ C2] console_flush_one_record+0x46f/0x710 [ 26.486027][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 26.486030][ C2] console_unlock+0xee/0x1f0 [ 26.486032][ C2] ? lock_acquire+0x13c/0x160 [ 26.486034][ C2] ? console_flush_one_record+0x710/0x710 [ 26.486036][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486038][ C2] ? lock_release+0x184/0x1f0 [ 26.486039][ C2] ? lock_acquire+0x60/0x160 [ 26.486041][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.486044][ C2] ? vprintk_emit+0x320/0x3e0 [ 26.486046][ C2] vprintk_emit+0x37c/0x3e0 [ 26.486049][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 26.486051][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.486066][ C2] ? lock_release+0x184/0x1f0 [ 26.486068][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.486076][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.486083][ C2] ? is_module_text_address+0x154/0x250 [ 26.486087][ C2] _printk+0xc7/0x100 [ 26.486091][ C2] ? snapshot_read.cold+0x21/0x21 [ 26.486093][ C2] ? arch_stack_walk+0xd7/0x130 [ 26.486097][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.486100][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.486102][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.486104][ C2] mark_lock_irq+0x989/0x9c0 [ 26.486108][ C2] mark_lock+0x1d7/0xa00 [ 26.486110][ C2] mark_usage+0x42/0x170 [ 26.486112][ C2] __lock_acquire+0x388/0xc20 [ 26.486114][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.486116][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.486124][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486126][ C2] ? lock_acquire+0x13c/0x160 [ 26.486128][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.486136][ C2] _raw_spin_lock+0x33/0x40 [ 26.486137][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.486145][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.486153][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.486161][ C2] call_timer_fn+0x160/0x4d0 [ 26.486164][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.486166][ C2] ? debug_object_active_state+0x430/0x430 [ 26.486170][ C2] ? find_held_lock+0x2b/0x80 [ 26.486172][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.486174][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486177][ C2] __run_timers+0x68f/0xaa0 [ 26.486179][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.486188][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.486189][ C2] ? __lock_acquire+0x518/0xc20 [ 26.486192][ C2] ? __rwlock_init+0x150/0x150 [ 26.486195][ C2] run_timer_softirq+0xf0/0x160 [ 26.486197][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.486199][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.486202][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486204][ C2] handle_softirqs+0x1d3/0x900 [ 26.486206][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.486208][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.486210][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.486212][ C2] irq_exit_rcu+0xe/0x30 [ 26.486213][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.486216][ C2] [ 26.486217][ C2] [ 26.486218][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.486220][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.486222][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.486225][ C2] 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 [ 26.486227][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.486230][ C2] RAX: 000000000009b8ef RBX: ff11000001bec6c0 RCX: ffffffff94ef0307 [ 26.486232][ C2] RDX: ff11000001bec6c0 RSI: ffffffff97c38af6 RDI: ffffffff9768d8e0 [ 26.486232][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.486233][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.486234][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.486236][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.486239][ C2] default_idle+0x9/0x10 [ 26.486241][ C2] default_idle_call+0x6e/0xb0 [ 26.486243][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.486244][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.486246][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.486248][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486250][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.486252][ C2] do_idle+0xd8/0x190 [ 26.486254][ C2] cpu_startup_entry+0x53/0x70 [ 26.486256][ C2] start_secondary+0x204/0x2b0 [ 26.486258][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.486260][ C2] common_startup_64+0x13e/0x148 [ 26.486264][ C2] [ 26.506029][ C2] ======================================================== [ 26.506296][ C2] WARNING: possible irq lock inversion dependency detected [ 26.506527][ C2] 7.2.0-virtme #1 Tainted: G W [ 26.506773][ C2] -------------------------------------------------------- [ 26.507015][ C2] swapper/2/0 just changed the state of lock: [ 26.507366][ C2] ff1100000f44ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.507701][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.507940][ C2] (fs_reclaim){+.+.}-{0:0} [ 26.507943][ C2] [ 26.507943][ C2] [ 26.507943][ C2] and interrupts could create inverse lock ordering between them. [ 26.507943][ C2] [ 26.508558][ C2] [ 26.508558][ C2] other info that might help us debug this: [ 26.508788][ C2] Chain exists of: [ 26.508788][ C2] &br->lock --> console_owner --> fs_reclaim [ 26.508788][ C2] [ 26.509137][ C2] Possible interrupt unsafe locking scenario: [ 26.509137][ C2] [ 26.509407][ C2] CPU0 CPU1 [ 26.509607][ C2] ---- ---- [ 26.509802][ C2] lock(fs_reclaim); [ 26.509964][ C2] local_irq_disable(); [ 26.510148][ C2] lock(&br->lock); [ 26.510328][ C2] lock(console_owner); [ 26.510511][ C2] [ 26.510624][ C2] lock(&br->lock); [ 26.510734][ C2] [ 26.510734][ C2] *** DEADLOCK *** [ 26.510734][ C2] [ 26.510949][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 26.511130][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.511420][ C2] [ 26.511420][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 26.511668][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 26.511816][ C2] HARDIRQ-ON-W at: [ 26.511927][ C2] __lock_acquire+0x388/0xc20 [ 26.512160][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.512338][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.512566][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.512788][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.513000][ C2] workqueue_init+0x2a/0x680 [ 26.513187][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.513451][ C2] kernel_init+0x21/0x150 [ 26.513632][ C2] ret_from_fork+0x474/0x6b0 [ 26.513809][ C2] ret_from_fork_asm+0x11/0x20 [ 26.513985][ C2] SOFTIRQ-ON-W at: [ 26.514092][ C2] __lock_acquire+0x388/0xc20 [ 26.514315][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.514491][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.514671][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.514930][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.515141][ C2] workqueue_init+0x2a/0x680 [ 26.515319][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.515589][ C2] kernel_init+0x21/0x150 [ 26.515765][ C2] ret_from_fork+0x474/0x6b0 [ 26.515946][ C2] ret_from_fork_asm+0x11/0x20 [ 26.516171][ C2] INITIAL USE at: [ 26.516278][ C2] __lock_acquire+0x388/0xc20 [ 26.516458][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.516637][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.516860][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.517116][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.517324][ C2] workqueue_init+0x2a/0x680 [ 26.517545][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.517773][ C2] kernel_init+0x21/0x150 [ 26.517945][ C2] ret_from_fork+0x474/0x6b0 [ 26.518163][ C2] ret_from_fork_asm+0x11/0x20 [ 26.518381][ C2] } [ 26.518458][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.518720][ C2] ... acquired at: [ 26.518824][ C2] __lock_acquire+0x518/0xc20 [ 26.518965][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.519154][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.519289][ C2] __kmalloc_noprof+0xd3/0x760 [ 26.519472][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.519618][ C2] put_chars+0x1e1/0x2f0 [ 26.519804][ C2] hvc_console_print+0x292/0x780 [ 26.519942][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.520126][ C2] console_flush_one_record+0x46f/0x710 [ 26.520262][ C2] console_unlock+0xee/0x1f0 [ 26.520446][ C2] vprintk_emit+0x37c/0x3e0 [ 26.520588][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 26.520772][ C2] dev_printk_emit+0xb9/0xee [ 26.520910][ C2] _dev_info+0xe2/0x116 [ 26.521060][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 26.521230][ C2] cmos_do_probe+0x73b/0x98a [ 26.521412][ C2] platform_probe+0xfe/0x1f0 [ 26.521551][ C2] call_driver_probe+0x61/0x1c0 [ 26.521741][ C2] really_probe+0x199/0x760 [ 26.521879][ C2] __driver_probe_device+0x24f/0x440 [ 26.522063][ C2] driver_probe_device+0x4a/0xf0 [ 26.522203][ C2] __driver_attach+0x1b8/0x540 [ 26.522387][ C2] bus_for_each_dev+0x130/0x1e0 [ 26.522526][ C2] bus_add_driver+0x2c8/0x530 [ 26.522711][ C2] driver_register+0x1a3/0x390 [ 26.522849][ C2] __platform_driver_probe+0x13f/0x270 [ 26.523032][ C2] cmos_init+0x31/0x40 [ 26.523137][ C2] do_one_initcall+0x124/0x4f0 [ 26.523318][ C2] kernel_init_freeable+0x596/0x630 [ 26.523457][ C2] kernel_init+0x21/0x150 [ 26.523645][ C2] ret_from_fork+0x474/0x6b0 [ 26.523780][ C2] ret_from_fork_asm+0x11/0x20 [ 26.523916][ C2] [ 26.523986][ C2] -> (console_owner){....}-{0:0} { [ 26.524175][ C2] INITIAL USE at: [ 26.524282][ C2] } [ 26.524350][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.524602][ C2] ... acquired at: [ 26.524703][ C2] __lock_acquire+0x518/0xc20 [ 26.524889][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.525026][ C2] console_lock_spinning_enable+0x5c/0x60 [ 26.525242][ C2] console_emit_next_record+0x1d1/0x4c0 [ 26.525378][ C2] console_flush_one_record+0x46f/0x710 [ 26.525562][ C2] console_unlock+0xee/0x1f0 [ 26.525700][ C2] vprintk_emit+0x37c/0x3e0 [ 26.525882][ C2] _printk+0xc7/0x100 [ 26.525987][ C2] br_set_state+0x22f/0x430 [bridge] [ 26.526181][ C2] br_init_port+0xc4/0x200 [bridge] [ 26.526321][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 26.526542][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 26.526771][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 26.526915][ C2] notifier_call_chain+0xae/0x300 [ 26.527099][ C2] netif_state_change+0x139/0x340 [ 26.527236][ C2] __linkwatch_run_queue+0x34c/0x750 [ 26.527420][ C2] linkwatch_event+0x7f/0xb0 [ 26.527558][ C2] process_one_work+0xe3e/0x1560 [ 26.527745][ C2] worker_thread+0x4f1/0xd60 [ 26.527887][ C2] kthread+0x367/0x460 [ 26.527990][ C2] ret_from_fork+0x474/0x6b0 [ 26.528173][ C2] ret_from_fork_asm+0x11/0x20 [ 26.528311][ C2] [ 26.528426][ C2] -> (&br->lock){+.-.}-{3:3} { [ 26.528565][ C2] HARDIRQ-ON-W at: [ 26.528740][ C2] __lock_acquire+0x388/0xc20 [ 26.528915][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.533448][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.533628][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 26.533856][ C2] do_set_master+0x357/0x580 [ 26.534077][ C2] do_setlink.isra.0+0xa31/0x2750 [ 26.534244][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.534472][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.534695][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.534870][ C2] netlink_unicast+0x486/0x750 [ 26.535090][ C2] netlink_sendmsg+0x735/0xc60 [ 26.535263][ C2] ____sys_sendmsg+0x415/0x880 [ 26.535483][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.535708][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.535882][ C2] do_syscall_64+0xff/0x530 [ 26.536101][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.536352][ C2] IN-SOFTIRQ-W at: [ 26.536457][ C2] __lock_acquire+0x388/0xc20 [ 26.536680][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.536854][ C2] _raw_spin_lock+0x33/0x40 [ 26.537071][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.537331][ C2] call_timer_fn+0x160/0x4d0 [ 26.537506][ C2] __run_timers+0x68f/0xaa0 [ 26.537731][ C2] run_timer_softirq+0xf0/0x160 [ 26.537952][ C2] handle_softirqs+0x1d3/0x900 [ 26.538124][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.538345][ C2] irq_exit_rcu+0xe/0x30 [ 26.538516][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.538775][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.539032][ C2] pv_native_safe_halt+0xf/0x10 [ 26.539254][ C2] default_idle+0x9/0x10 [ 26.539426][ C2] default_idle_call+0x6e/0xb0 [ 26.539646][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.539897][ C2] do_idle+0xd8/0x190 [ 26.540034][ C2] cpu_startup_entry+0x53/0x70 [ 26.540252][ C2] start_secondary+0x204/0x2b0 [ 26.540427][ C2] common_startup_64+0x13e/0x148 [ 26.540651][ C2] INITIAL USE at: [ 26.540752][ C2] __lock_acquire+0x388/0xc20 [ 26.540974][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.541192][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.541364][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 26.541604][ C2] do_set_master+0x357/0x580 [ 26.541775][ C2] do_setlink.isra.0+0xa31/0x2750 [ 26.542000][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.542222][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.542391][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.542618][ C2] netlink_unicast+0x486/0x750 [ 26.542840][ C2] netlink_sendmsg+0x735/0xc60 [ 26.543009][ C2] ____sys_sendmsg+0x415/0x880 [ 26.543224][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.543395][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.543620][ C2] do_syscall_64+0xff/0x530 [ 26.543839][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.544048][ C2] } [ 26.544165][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.544372][ C2] ... acquired at: [ 26.544520][ C2] mark_lock+0x1d7/0xa00 [ 26.544662][ C2] mark_usage+0x42/0x170 [ 26.544841][ C2] __lock_acquire+0x388/0xc20 [ 26.544977][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.545157][ C2] _raw_spin_lock+0x33/0x40 [ 26.545290][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.545509][ C2] call_timer_fn+0x160/0x4d0 [ 26.545651][ C2] __run_timers+0x68f/0xaa0 [ 26.545833][ C2] run_timer_softirq+0xf0/0x160 [ 26.545970][ C2] handle_softirqs+0x1d3/0x900 [ 26.546150][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.546286][ C2] irq_exit_rcu+0xe/0x30 [ 26.546472][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.546687][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.546854][ C2] pv_native_safe_halt+0xf/0x10 [ 26.547034][ C2] default_idle+0x9/0x10 [ 26.547168][ C2] default_idle_call+0x6e/0xb0 [ 26.547347][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.547515][ C2] do_idle+0xd8/0x190 [ 26.547665][ C2] cpu_startup_entry+0x53/0x70 [ 26.547799][ C2] start_secondary+0x204/0x2b0 [ 26.547978][ C2] common_startup_64+0x13e/0x148 [ 26.548113][ C2] [ 26.548181][ C2] [ 26.548181][ C2] stack backtrace: [ 26.548398][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.548403][ C2] Tainted: [W]=WARN [ 26.548404][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.548405][ C2] Call Trace: [ 26.548407][ C2] [ 26.548408][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.548412][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.548416][ C2] mark_lock_irq+0x989/0x9c0 [ 26.548419][ C2] mark_lock+0x1d7/0xa00 [ 26.548420][ C2] mark_usage+0x42/0x170 [ 26.548422][ C2] __lock_acquire+0x388/0xc20 [ 26.548424][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.548426][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548433][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.548437][ C2] ? lock_acquire+0x13c/0x160 [ 26.548438][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.548445][ C2] _raw_spin_lock+0x33/0x40 [ 26.548446][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548452][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548459][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.548466][ C2] call_timer_fn+0x160/0x4d0 [ 26.548468][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.548470][ C2] ? debug_object_active_state+0x430/0x430 [ 26.548474][ C2] ? find_held_lock+0x2b/0x80 [ 26.548476][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.548477][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.548480][ C2] __run_timers+0x68f/0xaa0 [ 26.548481][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.548488][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.548490][ C2] ? __lock_acquire+0x518/0xc20 [ 26.548492][ C2] ? __rwlock_init+0x150/0x150 [ 26.548495][ C2] run_timer_softirq+0xf0/0x160 [ 26.548497][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.548498][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.548501][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.548502][ C2] handle_softirqs+0x1d3/0x900 [ 26.548504][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.548506][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.548508][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.548509][ C2] irq_exit_rcu+0xe/0x30 [ 26.548511][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.548512][ C2] [ 26.548513][ C2] [ 26.548513][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.548515][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.548517][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.548519][ C2] 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 [ 26.548521][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.548523][ C2] RAX: 000000000009b8ef RBX: ff11000001bec6c0 RCX: ffffffff94ef0307 [ 26.548524][ C2] RDX: ff11000001bec6c0 RSI: ffffffff97c38af6 RDI: ffffffff9768d8e0 [ 26.548525][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.548526][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.548527][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.548529][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.548531][ C2] default_idle+0x9/0x10 [ 26.548532][ C2] default_idle_call+0x6e/0xb0 [ 26.548534][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.548536][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.548537][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.548539][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.548540][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.548543][ C2] do_idle+0xd8/0x190 [ 26.548544][ C2] cpu_startup_entry+0x53/0x70 [ 26.548545][ C2] start_secondary+0x204/0x2b0 [ 26.548547][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.548549][ C2] common_startup_64+0x13e/0x148 [ 26.548552][ C2] [ 26.658860][ T257] GACT probability NOT on [ 27.795789][ T266] br0: port 2(veth2) entered disabled state [ 27.795806][ T266] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.795808][ T266] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 266, name: ip [ 27.795810][ T266] preempt_count: 202, expected: 0 [ 27.795811][ T266] RCU nest depth: 0, expected: 0 [ 27.795812][ T266] INFO: lockdep is turned off. [ 27.795813][ T266] irq event stamp: 0 [ 27.795814][ T266] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 27.795819][ T266] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 27.795825][ T266] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 27.795827][ T266] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 27.795829][ T266] Preemption disabled at: [ 27.795829][ T266] [<0000000000000000>] 0x0 [ 27.795834][ T266] CPU: 2 UID: 0 PID: 266 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.795840][ T266] Tainted: [W]=WARN [ 27.795841][ T266] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.795842][ T266] Call Trace: [ 27.795844][ T266] [ 27.795845][ T266] dump_stack_lvl+0x6f/0xa0 [ 27.795851][ T266] __might_resched.cold+0x1fe/0x2c1 [ 27.795855][ T266] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.795858][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795862][ T266] __kmalloc_noprof+0x443/0x760 [ 27.795866][ T266] ? __rwlock_init+0x150/0x150 [ 27.795869][ T266] ? alloc_buf.isra.0+0x4b/0x260 [ 27.795873][ T266] ? do_raw_spin_unlock+0x59/0x250 [ 27.795875][ T266] alloc_buf.isra.0+0x4b/0x260 [ 27.795877][ T266] put_chars+0x1e1/0x2f0 [ 27.795879][ T266] ? __send_to_port+0x420/0x420 [ 27.795880][ T266] ? printk_get_next_message+0x240/0x650 [ 27.795884][ T266] hvc_console_print+0x292/0x780 [ 27.795887][ T266] ? hvc_write+0x3a0/0x3a0 [ 27.795889][ T266] ? __rwlock_init+0x150/0x150 [ 27.795890][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795892][ T266] ? lock_acquire+0x13c/0x160 [ 27.795894][ T266] console_emit_next_record+0x22f/0x4c0 [ 27.795897][ T266] ? devkmsg_read+0x4b0/0x4b0 [ 27.795899][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795900][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795902][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795903][ T266] ? lock_acquire+0x13c/0x160 [ 27.795905][ T266] ? console_flush_one_record+0x111/0x710 [ 27.795907][ T266] console_flush_one_record+0x46f/0x710 [ 27.795909][ T266] ? console_emit_next_record+0x4c0/0x4c0 [ 27.795912][ T266] console_unlock+0xee/0x1f0 [ 27.795913][ T266] ? lock_acquire+0x13c/0x160 [ 27.795915][ T266] ? console_flush_one_record+0x710/0x710 [ 27.795916][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795918][ T266] ? lock_release+0x184/0x1f0 [ 27.795919][ T266] ? lock_acquire+0x60/0x160 [ 27.795921][ T266] ? __rcu_read_lock+0x30/0x70 [ 27.795923][ T266] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.795925][ T266] ? vprintk_emit+0x320/0x3e0 [ 27.795927][ T266] vprintk_emit+0x37c/0x3e0 [ 27.795929][ T266] ? wake_up_klogd_work_func+0x90/0x90 [ 27.795931][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.795932][ T266] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.795935][ T266] ? trace_hardirqs_on+0x36/0x40 [ 27.795936][ T266] _printk+0xc7/0x100 [ 27.795939][ T266] ? snapshot_read.cold+0x21/0x21 [ 27.795942][ T266] ? ipv6_mc_down+0x17e/0x2d0 [ 27.795946][ T266] br_set_state+0x22f/0x430 [bridge] [ 27.795959][ T266] br_stp_disable_port+0x11f/0x390 [bridge] [ 27.795967][ T266] br_device_event+0x7b5/0x8f0 [bridge] [ 27.795976][ T266] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 27.795979][ T266] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 27.795981][ T266] ? addrconf_notify+0x2c8/0xf30 [ 27.795985][ T266] notifier_call_chain+0xae/0x300 [ 27.795988][ T266] __dev_notify_flags+0x23c/0x3c0 [ 27.795991][ T266] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.795993][ T266] ? netif_change_name+0x8c0/0x8c0 [ 27.795995][ T266] ? netif_set_allmulti+0x250/0x250 [ 27.795997][ T266] ? __kernel_text_address+0x12/0x30 [ 27.795999][ T266] ? write_profile+0xf0/0xf0 [ 27.796001][ T266] ? unwind_get_return_address+0x67/0xd0 [ 27.796004][ T266] netif_change_flags+0xfe/0x190 [ 27.796006][ T266] do_setlink.isra.0+0x1cc3/0x2750 [ 27.796009][ T266] ? stack_trace_save+0x93/0xc0 [ 27.796010][ T266] ? rtnl_link_get_size+0x350/0x350 [ 27.796011][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796013][ T266] ? stack_depot_save_flags+0x1bc/0x790 [ 27.796017][ T266] ? kasan_save_stack+0x3d/0x50 [ 27.796020][ T266] ? kasan_save_stack+0x2f/0x50 [ 27.796021][ T266] ? kasan_save_track+0x14/0x30 [ 27.796023][ T266] ? __kasan_kmalloc+0x7b/0x90 [ 27.796024][ T266] ? __kmalloc_cache_noprof+0x29a/0x620 [ 27.796026][ T266] ? rtnl_newlink+0x124/0x11c0 [ 27.796027][ T266] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.796028][ T266] ? netlink_rcv_skb+0x14e/0x3a0 [ 27.796031][ T266] ? netlink_unicast+0x486/0x750 [ 27.796032][ T266] ? netlink_sendmsg+0x735/0xc60 [ 27.796033][ T266] ? ____sys_sendmsg+0x415/0x880 [ 27.796036][ T266] ? ___sys_sendmsg+0x14e/0x1d0 [ 27.796037][ T266] ? __sys_sendmsg+0x12c/0x1d0 [ 27.796039][ T266] ? do_syscall_64+0xff/0x530 [ 27.796041][ T266] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.796044][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796045][ T266] ? lock_acquire+0x13c/0x160 [ 27.796047][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796048][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796050][ T266] ? trace_contention_end+0xb3/0x180 [ 27.796053][ T266] ? __mutex_lock+0x1db/0x1ea0 [ 27.796054][ T266] ? __mutex_lock+0x9a3/0x1ea0 [ 27.796056][ T266] ? rtnl_newlink+0x9a8/0x11c0 [ 27.796057][ T266] ? ww_mutex_lock+0x160/0x160 [ 27.796059][ T266] ? __rtnl_newlink+0x3fa/0xa50 [ 27.796061][ T266] rtnl_newlink+0x9f1/0x11c0 [ 27.796063][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796065][ T266] ? rtnl_bridge_getlink+0x850/0x850 [ 27.796066][ T266] ? lock_acquire+0x13c/0x160 [ 27.796067][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796069][ T266] ? is_bpf_text_address+0x3e/0x110 [ 27.796072][ T266] ? is_bpf_text_address+0x72/0x110 [ 27.796074][ T266] ? kernel_text_address+0x149/0x170 [ 27.796075][ T266] ? __kernel_text_address+0x12/0x30 [ 27.796077][ T266] ? write_profile+0xf0/0xf0 [ 27.796078][ T266] ? unwind_get_return_address+0x67/0xd0 [ 27.796079][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796081][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796082][ T266] ? cap_capable+0x1d7/0x3d0 [ 27.796086][ T266] ? lock_acquire+0x13c/0x160 [ 27.796087][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796089][ T266] ? rtnl_bridge_getlink+0x850/0x850 [ 27.796090][ T266] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.796092][ T266] ? rtnl_link_fill+0x920/0x920 [ 27.796093][ T266] ? filter_irq_stacks+0xd0/0xd0 [ 27.796094][ T266] ? stack_depot_save_flags+0x1bc/0x790 [ 27.796096][ T266] netlink_rcv_skb+0x14e/0x3a0 [ 27.796098][ T266] ? rtnl_link_fill+0x920/0x920 [ 27.796099][ T266] ? lock_release+0x184/0x1f0 [ 27.796100][ T266] ? netlink_ack+0xcf0/0xcf0 [ 27.796102][ T266] ? __netlink_lookup+0xb9/0x6b0 [ 27.796104][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796106][ T266] ? lock_release+0x184/0x1f0 [ 27.796108][ T266] netlink_unicast+0x486/0x750 [ 27.796109][ T266] ? netlink_attachskb+0x810/0x810 [ 27.796111][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796113][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796114][ T266] ? lock_acquire+0x13c/0x160 [ 27.796116][ T266] netlink_sendmsg+0x735/0xc60 [ 27.796118][ T266] ? netlink_unicast+0x750/0x750 [ 27.796120][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796121][ T266] ? lock_release+0x184/0x1f0 [ 27.796123][ T266] ____sys_sendmsg+0x415/0x880 [ 27.796125][ T266] ? copy_msghdr_from_user+0x279/0x420 [ 27.796126][ T266] ? get_timestamp.constprop.0+0x390/0x390 [ 27.796128][ T266] ? move_addr_to_kernel+0x40/0x40 [ 27.796129][ T266] ? filter_irq_stacks+0xd0/0xd0 [ 27.796131][ T266] ___sys_sendmsg+0x14e/0x1d0 [ 27.796133][ T266] ? copy_msghdr_from_user+0x420/0x420 [ 27.796138][ T266] __sys_sendmsg+0x12c/0x1d0 [ 27.796140][ T266] ? __sys_sendmsg_sock+0x20/0x20 [ 27.796142][ T266] ? rcu_is_watching+0x16/0xd0 [ 27.796144][ T266] do_syscall_64+0xff/0x530 [ 27.796145][ T266] ? exc_page_fault+0xee/0x100 [ 27.796148][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.796149][ T266] RIP: 0033:0x7f20af88e54e [ 27.796151][ T266] 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 [ 27.796153][ T266] RSP: 002b:00007ffde772cfc0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 27.796156][ T266] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f20af88e54e [ 27.796158][ T266] RDX: 0000000000000000 RSI: 00007ffde772d070 RDI: 0000000000000005 [ 27.796158][ T266] RBP: 00007ffde772cfd0 R08: 0000000000000000 R09: 0000000000000000 [ 27.796159][ T266] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91ac02 [ 27.796160][ T266] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffde772d730 [ 27.796162][ T266] [ 27.835508][ T266] ip (266) used greatest stack depth: 23016 bytes left [ 27.883143][ T36] br0: port 2(veth2) entered blocking state [ 27.883354][ T36] br0: port 2(veth2) entered forwarding state [ 34.130824][ T290] br0: port 1(veth1) entered disabled state [ 34.130854][ T290] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 34.130857][ T290] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 290, name: ip [ 34.130859][ T290] preempt_count: 202, expected: 0 [ 34.130861][ T290] RCU nest depth: 0, expected: 0 [ 34.130862][ T290] INFO: lockdep is turned off. [ 34.130864][ T290] irq event stamp: 0 [ 34.130865][ T290] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 34.130871][ T290] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 34.130879][ T290] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 34.130882][ T290] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 34.130884][ T290] Preemption disabled at: [ 34.130885][ T290] [<0000000000000000>] 0x0 [ 34.130892][ T290] CPU: 2 UID: 0 PID: 290 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 34.130897][ T290] Tainted: [W]=WARN [ 34.130898][ T290] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 34.130901][ T290] Call Trace: [ 34.130903][ T290] [ 34.130904][ T290] dump_stack_lvl+0x6f/0xa0 [ 34.130912][ T290] __might_resched.cold+0x1fe/0x2c1 [ 34.130917][ T290] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 34.130921][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.130926][ T290] __kmalloc_noprof+0x443/0x760 [ 34.130930][ T290] ? __rwlock_init+0x150/0x150 [ 34.130934][ T290] ? alloc_buf.isra.0+0x4b/0x260 [ 34.130939][ T290] ? do_raw_spin_unlock+0x59/0x250 [ 34.130941][ T290] alloc_buf.isra.0+0x4b/0x260 [ 34.130944][ T290] put_chars+0x1e1/0x2f0 [ 34.130947][ T290] ? __send_to_port+0x420/0x420 [ 34.130949][ T290] ? printk_get_next_message+0x240/0x650 [ 34.130954][ T290] hvc_console_print+0x292/0x780 [ 34.130959][ T290] ? hvc_write+0x3a0/0x3a0 [ 34.130962][ T290] ? __rwlock_init+0x150/0x150 [ 34.130964][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.130967][ T290] ? lock_acquire+0x13c/0x160 [ 34.130970][ T290] console_emit_next_record+0x22f/0x4c0 [ 34.130974][ T290] ? devkmsg_read+0x4b0/0x4b0 [ 34.130977][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.130979][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.130982][ T290] ? lock_acquire+0x13c/0x160 [ 34.130985][ T290] ? console_flush_one_record+0x111/0x710 [ 34.130987][ T290] console_flush_one_record+0x46f/0x710 [ 34.130991][ T290] ? console_emit_next_record+0x4c0/0x4c0 [ 34.130993][ T290] ? _raw_spin_unlock+0x2d/0x50 [ 34.130997][ T290] ? irqentry_exit+0x21c/0x790 [ 34.131002][ T290] console_unlock+0xee/0x1f0 [ 34.131005][ T290] ? vprintk_emit+0x320/0x3e0 [ 34.131007][ T290] ? console_flush_one_record+0x710/0x710 [ 34.131009][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131012][ T290] ? trace_irq_enable.constprop.0+0x30/0x160 [ 34.131015][ T290] ? lock_acquire+0x60/0x160 [ 34.131018][ T290] ? __down_trylock_console_sem+0x5e/0xa0 [ 34.131021][ T290] ? vprintk_emit+0x320/0x3e0 [ 34.131024][ T290] vprintk_emit+0x37c/0x3e0 [ 34.131028][ T290] ? wake_up_klogd_work_func+0x90/0x90 [ 34.131030][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131033][ T290] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.131035][ T290] ? trace_hardirqs_on+0x36/0x40 [ 34.131038][ T290] _printk+0xc7/0x100 [ 34.131042][ T290] ? snapshot_read.cold+0x21/0x21 [ 34.131045][ T290] ? ipv6_mc_down+0x17e/0x2d0 [ 34.131050][ T290] br_set_state+0x22f/0x430 [bridge] [ 34.131070][ T290] br_stp_disable_port+0x11f/0x390 [bridge] [ 34.131084][ T290] br_device_event+0x7b5/0x8f0 [bridge] [ 34.131098][ T290] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 34.131101][ T290] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 34.131104][ T290] ? addrconf_notify+0x2c8/0xf30 [ 34.131109][ T290] notifier_call_chain+0xae/0x300 [ 34.131113][ T290] __dev_notify_flags+0x23c/0x3c0 [ 34.131118][ T290] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.131121][ T290] ? netif_change_name+0x8c0/0x8c0 [ 34.131124][ T290] ? netif_set_allmulti+0x250/0x250 [ 34.131127][ T290] ? __kernel_text_address+0x12/0x30 [ 34.131130][ T290] ? write_profile+0xf0/0xf0 [ 34.131133][ T290] ? unwind_get_return_address+0x67/0xd0 [ 34.131137][ T290] netif_change_flags+0xfe/0x190 [ 34.131140][ T290] do_setlink.isra.0+0x1cc3/0x2750 [ 34.131144][ T290] ? stack_trace_save+0x93/0xc0 [ 34.131146][ T290] ? rtnl_link_get_size+0x350/0x350 [ 34.131148][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131151][ T290] ? stack_depot_save_flags+0x1bc/0x790 [ 34.131156][ T290] ? kasan_save_stack+0x3d/0x50 [ 34.131160][ T290] ? kasan_save_stack+0x2f/0x50 [ 34.131162][ T290] ? kasan_save_track+0x14/0x30 [ 34.131164][ T290] ? __kasan_kmalloc+0x7b/0x90 [ 34.131166][ T290] ? __kmalloc_cache_noprof+0x29a/0x620 [ 34.131169][ T290] ? rtnl_newlink+0x124/0x11c0 [ 34.131170][ T290] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 34.131172][ T290] ? netlink_rcv_skb+0x14e/0x3a0 [ 34.131176][ T290] ? netlink_unicast+0x486/0x750 [ 34.131178][ T290] ? netlink_sendmsg+0x735/0xc60 [ 34.131180][ T290] ? ____sys_sendmsg+0x415/0x880 [ 34.131183][ T290] ? ___sys_sendmsg+0x14e/0x1d0 [ 34.131185][ T290] ? __sys_sendmsg+0x12c/0x1d0 [ 34.131187][ T290] ? do_syscall_64+0xff/0x530 [ 34.131190][ T290] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 34.131194][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131196][ T290] ? lock_acquire+0x13c/0x160 [ 34.131198][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131200][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131203][ T290] ? trace_contention_end+0xb3/0x180 [ 34.131207][ T290] ? __mutex_lock+0x1db/0x1ea0 [ 34.131209][ T290] ? __mutex_lock+0x9a3/0x1ea0 [ 34.131211][ T290] ? rtnl_newlink+0x9a8/0x11c0 [ 34.131214][ T290] ? ww_mutex_lock+0x160/0x160 [ 34.131217][ T290] ? __rtnl_newlink+0x3fa/0xa50 [ 34.131221][ T290] rtnl_newlink+0x9f1/0x11c0 [ 34.131224][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131227][ T290] ? rtnl_bridge_getlink+0x850/0x850 [ 34.131228][ T290] ? lock_acquire+0x13c/0x160 [ 34.131231][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131234][ T290] ? is_bpf_text_address+0x3e/0x110 [ 34.131238][ T290] ? is_bpf_text_address+0x72/0x110 [ 34.131240][ T290] ? kernel_text_address+0x149/0x170 [ 34.131242][ T290] ? __kernel_text_address+0x12/0x30 [ 34.131244][ T290] ? write_profile+0xf0/0xf0 [ 34.131246][ T290] ? unwind_get_return_address+0x67/0xd0 [ 34.131248][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131251][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131253][ T290] ? cap_capable+0x1d7/0x3d0 [ 34.131257][ T290] ? lock_acquire+0x13c/0x160 [ 34.131259][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131262][ T290] ? rtnl_bridge_getlink+0x850/0x850 [ 34.131264][ T290] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 34.131267][ T290] ? rtnl_link_fill+0x920/0x920 [ 34.131269][ T290] ? filter_irq_stacks+0xd0/0xd0 [ 34.131271][ T290] ? stack_depot_save_flags+0x1bc/0x790 [ 34.131275][ T290] netlink_rcv_skb+0x14e/0x3a0 [ 34.131278][ T290] ? rtnl_link_fill+0x920/0x920 [ 34.131280][ T290] ? lock_release+0x184/0x1f0 [ 34.131282][ T290] ? netlink_ack+0xcf0/0xcf0 [ 34.131284][ T290] ? __netlink_lookup+0xb9/0x6b0 [ 34.131288][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131290][ T290] ? lock_release+0x184/0x1f0 [ 34.131293][ T290] netlink_unicast+0x486/0x750 [ 34.131296][ T290] ? netlink_attachskb+0x810/0x810 [ 34.131299][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131301][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131303][ T290] ? lock_acquire+0x13c/0x160 [ 34.131306][ T290] netlink_sendmsg+0x735/0xc60 [ 34.131309][ T290] ? netlink_unicast+0x750/0x750 [ 34.131312][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131314][ T290] ? lock_release+0x184/0x1f0 [ 34.131317][ T290] ____sys_sendmsg+0x415/0x880 [ 34.131320][ T290] ? copy_msghdr_from_user+0x279/0x420 [ 34.131322][ T290] ? get_timestamp.constprop.0+0x390/0x390 [ 34.131325][ T290] ? move_addr_to_kernel+0x40/0x40 [ 34.131327][ T290] ? filter_irq_stacks+0xd0/0xd0 [ 34.131331][ T290] ___sys_sendmsg+0x14e/0x1d0 [ 34.131334][ T290] ? copy_msghdr_from_user+0x420/0x420 [ 34.131341][ T290] __sys_sendmsg+0x12c/0x1d0 [ 34.131344][ T290] ? __sys_sendmsg_sock+0x20/0x20 [ 34.131348][ T290] ? rcu_is_watching+0x16/0xd0 [ 34.131351][ T290] do_syscall_64+0xff/0x530 [ 34.131354][ T290] ? exc_page_fault+0xee/0x100 [ 34.131356][ T290] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 34.131359][ T290] RIP: 0033:0x7f3d5ddca54e [ 34.131361][ T290] 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 [ 34.131364][ T290] RSP: 002b:00007ffd273963e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 34.131369][ T290] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f3d5ddca54e [ 34.131371][ T290] RDX: 0000000000000000 RSI: 00007ffd27396490 RDI: 0000000000000005 [ 34.131372][ T290] RBP: 00007ffd273963f0 R08: 0000000000000000 R09: 0000000000000000 [ 34.131374][ T290] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91ac08 [ 34.131375][ T290] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd27396b50 [ 34.131379][ T290] [ 34.207478][ T291] br0: port 2(veth2) entered disabled state [ 34.232799][ T292] veth2: left allmulticast mode [ 34.233065][ T292] veth2: left promiscuous mode [ 34.233413][ T292] br0: port 2(veth2) entered disabled state [ 34.234628][ T292] veth1: left allmulticast mode [ 34.235137][ T292] veth1: left promiscuous mode [ 34.235488][ T292] br0: port 1(veth1) entered disabled state