[ 10.237340][ T187] ip (187) used greatest stack depth: 24048 bytes left [ 10.237356][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.237358][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 10.237359][ T187] preempt_count: 2, expected: 0 [ 10.237360][ T187] RCU nest depth: 0, expected: 0 [ 10.237361][ T187] locks held by ip/187: 5, last CPU#2: [ 10.237363][ T187] #0: ffffffffb10027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.237376][ T187] #1: ffffffffb1169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.237381][ T187] #2: ffffffffb1169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.237385][ T187] #3: ffffffffb1089660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.237389][ T187] #4: ffffffffb1089560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.237393][ T187] irq event stamp: 39004 [ 10.237394][ T187] hardirqs last enabled at (39003): [] __down_trylock_console_sem+0x86/0xa0 [ 10.237397][ T187] hardirqs last disabled at (39004): [] console_emit_next_record+0x3d4/0x4c0 [ 10.237399][ T187] softirqs last enabled at (38916): [] handle_softirqs+0x67c/0x900 [ 10.237400][ T187] softirqs last disabled at (38909): [] __irq_exit_rcu+0x145/0x1c0 [ 10.237403][ T187] Preemption disabled at: [ 10.237403][ T187] [<0000000000000000>] 0x0 [ 10.237410][ T187] CPU: 2 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.237414][ T187] Tainted: [W]=WARN [ 10.237414][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.237416][ T187] Call Trace: [ 10.237418][ T187] [ 10.237419][ T187] dump_stack_lvl+0x6f/0xa0 [ 10.237426][ T187] __might_resched.cold+0x1fe/0x2c1 [ 10.237430][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.237434][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 10.237439][ T187] __kmalloc_noprof+0x443/0x760 [ 10.237442][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 10.237448][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 10.237450][ T187] alloc_buf.isra.0+0x4b/0x260 [ 10.237454][ T187] put_chars+0x1e1/0x2f0 [ 10.237456][ T187] ? __send_to_port+0x420/0x420 [ 10.237458][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.237463][ T187] ? validate_chain+0x38b/0xc20 [ 10.237468][ T187] hvc_console_print+0x292/0x780 [ 10.237474][ T187] ? hvc_write+0x3a0/0x3a0 [ 10.237476][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.237478][ T187] ? lock_acquire+0x13c/0x160 [ 10.237482][ T187] console_emit_next_record+0x22f/0x4c0 [ 10.237486][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 10.237488][ T187] ? console_flush_one_record+0x106/0x710 [ 10.237491][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.237494][ T187] ? lock_acquire+0x13c/0x160 [ 10.237498][ T187] console_flush_one_record+0x46f/0x710 [ 10.237502][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 10.237504][ T187] ? __lock_acquire+0x518/0xc20 [ 10.237509][ T187] console_unlock+0xee/0x1f0 [ 10.237512][ T187] ? console_flush_one_record+0x710/0x710 [ 10.237514][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.237516][ T187] ? lock_acquire+0x60/0x160 [ 10.237520][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.237521][ T187] ? vprintk_emit+0x320/0x3e0 [ 10.237525][ T187] vprintk_emit+0x37c/0x3e0 [ 10.237528][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 10.237532][ T187] ? __lock_acquire+0x518/0xc20 [ 10.237536][ T187] _printk+0xc7/0x100 [ 10.237539][ T187] ? snapshot_read.cold+0x21/0x21 [ 10.237542][ T187] ? do_raw_spin_lock+0x131/0x280 [ 10.237545][ T187] ? __rwlock_init+0x150/0x150 [ 10.237549][ T187] ? do_raw_spin_lock+0x131/0x280 [ 10.237552][ T187] do_exit.cold+0x82/0x9c [ 10.237555][ T187] ? exit_notify+0x890/0x890 [ 10.237557][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 10.237560][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.237564][ T187] do_group_exit+0xb8/0x370 [ 10.237567][ T187] __x64_sys_exit_group+0x3c/0x50 [ 10.237569][ T187] x64_sys_call+0x1567/0x1570 [ 10.237571][ T187] do_syscall_64+0xff/0x530 [ 10.237574][ T187] ? exc_page_fault+0xee/0x100 [ 10.237577][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.237579][ T187] RIP: 0033:0x7f102254d1b8 [ 10.237582][ T187] Code: Unable to access opcode bytes at 0x7f102254d18e. [ 10.237583][ T187] RSP: 002b:00007ffeba446bc8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.237586][ T187] RAX: ffffffffffffffda RBX: 00007f102267df88 RCX: 00007f102254d1b8 [ 10.237587][ T187] RDX: 00007f1022297fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.237588][ T187] RBP: 00007ffeba446c20 R08: 0000000000000000 R09: 0000000000008010 [ 10.237589][ T187] R10: 00007ffeba4469e0 R11: 0000000000000246 R12: 0000000000000001 [ 10.237590][ T187] R13: 0000000000000000 R14: 00007f102267c680 R15: 00007f102267dfa0 [ 10.237596][ T187] [ 11.663807][ T238] br0: port 1(veth1) entered blocking state [ 11.663958][ T238] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.663960][ T238] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 238, name: ip [ 11.663962][ T238] preempt_count: 1, expected: 0 [ 11.663964][ T238] RCU nest depth: 0, expected: 0 [ 11.663965][ T238] locks held by ip/238: 5, last CPU#0: [ 11.663969][ T238] #0: ffffffffb18d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.663984][ T238] #1: ffffffffb1169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.663992][ T238] #2: ffffffffb1169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.663999][ T238] #3: ffffffffb1089660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.664005][ T238] #4: ffffffffb1089560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.664011][ T238] irq event stamp: 20972 [ 11.664012][ T238] hardirqs last enabled at (20971): [] __down_trylock_console_sem+0x86/0xa0 [ 11.664015][ T238] hardirqs last disabled at (20972): [] console_emit_next_record+0x3d4/0x4c0 [ 11.664018][ T238] softirqs last enabled at (20884): [] __alloc_skb+0x4c2/0x5f0 [ 11.664022][ T238] softirqs last disabled at (20882): [] __alloc_skb+0x4c2/0x5f0 [ 11.664026][ T238] Preemption disabled at: [ 11.664027][ T238] [] vprintk_emit+0x31b/0x3e0 [ 11.664034][ T238] CPU: 0 UID: 0 PID: 238 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.664039][ T238] Tainted: [W]=WARN [ 11.664040][ T238] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.664043][ T238] Call Trace: [ 11.664045][ T238] [ 11.664047][ T238] dump_stack_lvl+0x6f/0xa0 [ 11.664054][ T238] ? vprintk_emit+0x31b/0x3e0 [ 11.664057][ T238] __might_resched.cold+0x1fe/0x2c1 [ 11.664064][ T238] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.664070][ T238] ? __kmalloc_noprof+0xdb/0x760 [ 11.664079][ T238] __kmalloc_noprof+0x443/0x760 [ 11.664083][ T238] ? alloc_buf.isra.0+0x4b/0x260 [ 11.664091][ T238] ? do_raw_spin_unlock+0x59/0x250 [ 11.664095][ T238] alloc_buf.isra.0+0x4b/0x260 [ 11.664101][ T238] put_chars+0x1e1/0x2f0 [ 11.664105][ T238] ? __send_to_port+0x420/0x420 [ 11.664113][ T238] ? validate_chain+0x34a/0xc20 [ 11.664119][ T238] hvc_console_print+0x292/0x780 [ 11.664123][ T238] ? mark_usage+0x61/0x170 [ 11.664126][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664128][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664135][ T238] ? hvc_write+0x3a0/0x3a0 [ 11.664139][ T238] ? console_emit_next_record+0x1df/0x4c0 [ 11.664144][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664149][ T238] ? lock_acquire+0x13c/0x160 [ 11.664156][ T238] console_emit_next_record+0x22f/0x4c0 [ 11.664162][ T238] ? devkmsg_read+0x4b0/0x4b0 [ 11.664165][ T238] ? console_flush_one_record+0x106/0x710 [ 11.664173][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664182][ T238] ? lock_acquire+0x13c/0x160 [ 11.664189][ T238] console_flush_one_record+0x46f/0x710 [ 11.664197][ T238] ? console_emit_next_record+0x4c0/0x4c0 [ 11.664200][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664209][ T238] console_unlock+0xee/0x1f0 [ 11.664214][ T238] ? console_flush_one_record+0x710/0x710 [ 11.664217][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664220][ T238] ? lock_acquire+0x60/0x160 [ 11.664227][ T238] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.664229][ T238] ? vprintk_emit+0x320/0x3e0 [ 11.664235][ T238] vprintk_emit+0x37c/0x3e0 [ 11.664241][ T238] ? wake_up_klogd_work_func+0x90/0x90 [ 11.664245][ T238] ? __lock_release.isra.0+0x69/0x1a0 [ 11.664247][ T238] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.664253][ T238] ? mark_held_locks+0x40/0x70 [ 11.664259][ T238] _printk+0xc7/0x100 [ 11.664264][ T238] ? snapshot_read.cold+0x21/0x21 [ 11.664270][ T238] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.664288][ T238] ? do_setlink.isra.0+0xa31/0x2750 [ 11.664291][ T238] ? rtnl_newlink+0x9f1/0x11c0 [ 11.664293][ T238] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.664301][ T238] br_set_state+0x22f/0x430 [bridge] [ 11.664318][ T238] br_init_port+0xc4/0x200 [bridge] [ 11.664333][ T238] new_nbp+0x39c/0x580 [bridge] [ 11.664349][ T238] br_add_if+0x212/0x1320 [bridge] [ 11.664362][ T238] ? is_bpf_text_address+0x72/0x110 [ 11.664367][ T238] ? kernel_text_address+0x149/0x170 [ 11.664371][ T238] ? __kernel_text_address+0x12/0x30 [ 11.664378][ T238] do_set_master+0x357/0x580 [ 11.664386][ T238] do_setlink.isra.0+0xa31/0x2750 [ 11.664392][ T238] ? stack_trace_save+0x93/0xc0 [ 11.664397][ T238] ? rtnl_link_get_size+0x350/0x350 [ 11.664399][ T238] ? rcu_read_lock_any_held+0x66/0x90 [ 11.664402][ T238] ? stack_depot_save_flags+0x38e/0x790 [ 11.664407][ T238] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.664411][ T238] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.664414][ T238] ? validate_chain+0x38b/0xc20 [ 11.664418][ T238] ? kasan_save_stack+0x3d/0x50 [ 11.664422][ T238] ? kasan_save_stack+0x2f/0x50 [ 11.664424][ T238] ? kasan_save_track+0x14/0x30 [ 11.664429][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664431][ T238] ? netlink_seq_next+0xe/0x60 [ 11.664436][ T238] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.664443][ T238] ? lock_acquire.part.0+0xd4/0x280 [ 11.664446][ T238] ? rtnl_newlink+0x9a8/0x11c0 [ 11.664450][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664454][ T238] ? lock_acquire+0x13c/0x160 [ 11.664456][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664458][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664462][ T238] ? trace_contention_end+0xb3/0x180 [ 11.664468][ T238] ? __mutex_lock+0x1db/0x1ea0 [ 11.664470][ T238] ? __mutex_lock+0x9a3/0x1ea0 [ 11.664474][ T238] ? rtnl_newlink+0x9a8/0x11c0 [ 11.664480][ T238] ? ww_mutex_lock+0x160/0x160 [ 11.664482][ T238] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.664492][ T238] ? __rtnl_newlink+0x3fa/0xa50 [ 11.664501][ T238] rtnl_newlink+0x9f1/0x11c0 [ 11.664511][ T238] ? rtnl_bridge_getlink+0x850/0x850 [ 11.664514][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664522][ T238] ? lock_acquire.part.0+0xd4/0x280 [ 11.664525][ T238] ? find_held_lock+0x2b/0x80 [ 11.664529][ T238] ? rtnl_bridge_getlink+0x850/0x850 [ 11.664532][ T238] ? __lock_release.isra.0+0x69/0x1a0 [ 11.664538][ T238] ? rtnl_bridge_getlink+0x850/0x850 [ 11.664542][ T238] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.664547][ T238] ? rtnl_link_fill+0x920/0x920 [ 11.664550][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664557][ T238] ? lock_acquire.part.0+0xd4/0x280 [ 11.664560][ T238] ? find_held_lock+0x2b/0x80 [ 11.664567][ T238] netlink_rcv_skb+0x14e/0x3a0 [ 11.664569][ T238] ? rtnl_link_fill+0x920/0x920 [ 11.664575][ T238] ? netlink_ack+0xcf0/0xcf0 [ 11.664586][ T238] ? netlink_deliver_tap+0xc5/0x330 [ 11.664588][ T238] ? netlink_deliver_tap+0x13c/0x330 [ 11.664596][ T238] netlink_unicast+0x486/0x750 [ 11.664603][ T238] ? netlink_attachskb+0x810/0x810 [ 11.664608][ T238] ? __lock_acquire+0x518/0xc20 [ 11.664615][ T238] netlink_sendmsg+0x735/0xc60 [ 11.664622][ T238] ? netlink_unicast+0x750/0x750 [ 11.664629][ T238] ? __might_fault+0x97/0x140 [ 11.664637][ T238] ____sys_sendmsg+0x415/0x880 [ 11.664641][ T238] ? copy_msghdr_from_user+0x279/0x420 [ 11.664645][ T238] ? get_timestamp.constprop.0+0x390/0x390 [ 11.664647][ T238] ? move_addr_to_kernel+0x40/0x40 [ 11.664661][ T238] ___sys_sendmsg+0x14e/0x1d0 [ 11.664666][ T238] ? copy_msghdr_from_user+0x420/0x420 [ 11.664697][ T238] __sys_sendmsg+0x12c/0x1d0 [ 11.664702][ T238] ? __sys_sendmsg_sock+0x20/0x20 [ 11.664713][ T238] ? rcu_is_watching+0x16/0xd0 [ 11.664719][ T238] do_syscall_64+0xff/0x530 [ 11.664723][ T238] ? exc_page_fault+0xee/0x100 [ 11.664727][ T238] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.664731][ T238] RIP: 0033:0x7fb1024a754e [ 11.664735][ T238] 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.664739][ T238] RSP: 002b:00007ffd431d9c60 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.664742][ T238] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fb1024a754e [ 11.664744][ T238] RDX: 0000000000000000 RSI: 00007ffd431d9d10 RDI: 0000000000000005 [ 11.664746][ T238] RBP: 00007ffd431d9c70 R08: 0000000000000000 R09: 0000000000000000 [ 11.664747][ T238] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b5d4 [ 11.664749][ T238] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd431da3d0 [ 11.664763][ T238] [ 11.740545][ T238] br0: port 1(veth1) entered disabled state [ 11.741513][ T238] veth1: entered allmulticast mode [ 11.744809][ T238] veth1: entered promiscuous mode [ 11.755103][ T238] ip (238) used greatest stack depth: 23336 bytes left [ 11.779690][ T239] br0: port 2(veth2) entered blocking state [ 11.780057][ T239] br0: port 2(veth2) entered disabled state [ 11.780904][ T239] veth2: entered allmulticast mode [ 11.782537][ T239] veth2: entered promiscuous mode [ 11.855136][ T38] br0: port 1(veth1) entered blocking state [ 11.855805][ T38] br0: port 1(veth1) entered forwarding state [ 22.052294][ T46] br0: port 2(veth2) entered blocking state [ 22.052315][ T46] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 22.052317][ T46] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 46, name: kworker/u17:1 [ 22.052319][ T46] preempt_count: 202, expected: 0 [ 22.052320][ T46] RCU nest depth: 0, expected: 0 [ 22.052321][ T46] locks held by kworker/u17:1/46: 8, last CPU#0: [ 22.052323][ T46] #0: ff1100000114a940 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 22.052336][ T46] #1: ffa000000032fd10 ((linkwatch_work).work){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 22.052340][ T46] #2: ffffffffb18d2c40 (rtnl_mutex){+.+.}-{4:4}, at: linkwatch_event+0x4e/0xb0 [ 22.052346][ T46] #3: ff1100000cb4ae58 (&br->lock){+...}-{3:3}, at: br_port_carrier_check+0x161/0x430 [bridge] [ 22.052362][ T46] #4: ffffffffb1169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 22.052367][ T46] #5: ffffffffb1169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 22.052371][ T46] #6: ffffffffb1089660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 22.052375][ T46] #7: ffffffffb1089560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 22.052379][ T46] irq event stamp: 56939 [ 22.052380][ T46] hardirqs last enabled at (56938): [] __down_trylock_console_sem+0x86/0xa0 [ 22.052383][ T46] hardirqs last disabled at (56939): [] console_emit_next_record+0x3d4/0x4c0 [ 22.052385][ T46] softirqs last enabled at (56898): [] inet6_fill_ifla6_attrs+0x1b3e/0x21e0 [ 22.052388][ T46] softirqs last disabled at (56934): [] br_port_carrier_check+0x161/0x430 [bridge] [ 22.052396][ T46] Preemption disabled at: [ 22.052397][ T46] [<0000000000000000>] 0x0 [ 22.052403][ T46] CPU: 0 UID: 0 PID: 46 Comm: kworker/u17:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 22.052407][ T46] Tainted: [W]=WARN [ 22.052408][ T46] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.052410][ T46] Workqueue: events_unbound linkwatch_event [ 22.052414][ T46] Call Trace: [ 22.052415][ T46] [ 22.052417][ T46] dump_stack_lvl+0x6f/0xa0 [ 22.052423][ T46] __might_resched.cold+0x1fe/0x2c1 [ 22.052428][ T46] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 22.052433][ T46] ? __kmalloc_noprof+0xdb/0x760 [ 22.052438][ T46] __kmalloc_noprof+0x443/0x760 [ 22.052440][ T46] ? alloc_buf.isra.0+0x4b/0x260 [ 22.052446][ T46] ? do_raw_spin_unlock+0x59/0x250 [ 22.052449][ T46] alloc_buf.isra.0+0x4b/0x260 [ 22.052452][ T46] put_chars+0x1e1/0x2f0 [ 22.052456][ T46] ? __send_to_port+0x420/0x420 [ 22.052458][ T46] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.052463][ T46] ? rcu_read_lock_any_held+0x3c/0x90 [ 22.052466][ T46] ? validate_chain+0x38b/0xc20 [ 22.052470][ T46] hvc_console_print+0x292/0x780 [ 22.052472][ T46] ? mark_usage+0x61/0x170 [ 22.052474][ T46] ? __lock_acquire+0x518/0xc20 [ 22.052476][ T46] ? __lock_acquire+0x518/0xc20 [ 22.052480][ T46] ? hvc_write+0x3a0/0x3a0 [ 22.052482][ T46] ? lock_acquire.part.0+0xd4/0x280 [ 22.052486][ T46] ? lock_acquire+0x13c/0x160 [ 22.052491][ T46] console_emit_next_record+0x22f/0x4c0 [ 22.052495][ T46] ? devkmsg_read+0x4b0/0x4b0 [ 22.052497][ T46] ? console_flush_one_record+0x106/0x710 [ 22.052500][ T46] ? rcu_is_watching+0x16/0xd0 [ 22.052503][ T46] ? lock_acquire+0x13c/0x160 [ 22.052507][ T46] console_flush_one_record+0x46f/0x710 [ 22.052511][ T46] ? console_emit_next_record+0x4c0/0x4c0 [ 22.052513][ T46] ? __lock_acquire+0x518/0xc20 [ 22.052518][ T46] console_unlock+0xee/0x1f0 [ 22.052521][ T46] ? console_flush_one_record+0x710/0x710 [ 22.052523][ T46] ? rcu_is_watching+0x16/0xd0 [ 22.052526][ T46] ? lock_acquire+0x60/0x160 [ 22.052530][ T46] ? __down_trylock_console_sem+0x5e/0xa0 [ 22.052531][ T46] ? vprintk_emit+0x320/0x3e0 [ 22.052535][ T46] vprintk_emit+0x37c/0x3e0 [ 22.052539][ T46] ? wake_up_klogd_work_func+0x90/0x90 [ 22.052542][ T46] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.052545][ T46] ? rcu_read_lock_any_held+0x3c/0x90 [ 22.052549][ T46] _printk+0xc7/0x100 [ 22.052553][ T46] ? snapshot_read.cold+0x21/0x21 [ 22.052558][ T46] ? lock_acquire.part.0+0xd4/0x280 [ 22.052563][ T46] br_set_state+0x22f/0x430 [bridge] [ 22.052573][ T46] br_init_port+0xc4/0x200 [bridge] [ 22.052582][ T46] br_stp_enable_port+0x12/0x50 [bridge] [ 22.052589][ T46] br_port_carrier_check+0x220/0x430 [bridge] [ 22.052598][ T46] br_device_event+0x52d/0x8f0 [bridge] [ 22.052607][ T46] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 22.052609][ T46] ? nlmsg_notify+0x62/0x250 [ 22.052616][ T46] notifier_call_chain+0xae/0x300 [ 22.052622][ T46] netif_state_change+0x139/0x340 [ 22.052627][ T46] ? call_netdevice_notifiers_info+0xd0/0xd0 [ 22.052632][ T46] ? linkwatch_do_dev+0xae/0xd0 [ 22.052635][ T46] __linkwatch_run_queue+0x34c/0x750 [ 22.052638][ T46] ? linkwatch_do_dev+0xd0/0xd0 [ 22.052640][ T46] ? lock_acquire.part.0+0xd4/0x280 [ 22.052645][ T46] linkwatch_event+0x7f/0xb0 [ 22.052647][ T46] ? __linkwatch_run_queue+0x750/0x750 [ 22.052651][ T46] process_one_work+0xe3e/0x1560 [ 22.052658][ T46] ? __queue_delayed_work+0x470/0x470 [ 22.052661][ T46] ? lock_acquire.part.0+0xd4/0x280 [ 22.052669][ T46] worker_thread+0x4f1/0xd60 [ 22.052676][ T46] ? rescuer_thread+0x1340/0x1340 [ 22.052678][ T46] kthread+0x367/0x460 [ 22.052680][ T46] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 22.052683][ T46] ? kthread_affine_preferred+0x4c0/0x4c0 [ 22.052686][ T46] ret_from_fork+0x474/0x6b0 [ 22.052690][ T46] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 22.052694][ T46] ? __switch_to+0x5a3/0xe00 [ 22.052697][ T46] ? kthread_affine_preferred+0x4c0/0x4c0 [ 22.052700][ T46] ret_from_fork_asm+0x11/0x20 [ 22.052709][ T46] [ 22.082193][ T46] br0: port 2(veth2) entered forwarding state [ 27.013264][ C2] [ 27.013282][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.013284][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 27.013286][ C2] preempt_count: 104, expected: 0 [ 27.013287][ C2] RCU nest depth: 0, expected: 0 [ 27.013289][ C2] INFO: lockdep is turned off. [ 27.013289][ C2] irq event stamp: 499132 [ 27.013290][ C2] hardirqs last enabled at (499132): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.013299][ C2] hardirqs last disabled at (499131): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.013301][ C2] softirqs last enabled at (499104): [] handle_softirqs+0x67c/0x900 [ 27.013305][ C2] softirqs last disabled at (499129): [] __irq_exit_rcu+0x145/0x1c0 [ 27.013306][ C2] Preemption disabled at: [ 27.013307][ C2] [<0000000000000000>] 0x0 [ 27.013314][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.013318][ C2] Tainted: [W]=WARN [ 27.013319][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.013320][ C2] Call Trace: [ 27.013322][ C2] [ 27.013324][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.013330][ C2] __might_resched.cold+0x1fe/0x2c1 [ 27.013333][ C2] ? br_handle_frame_finish+0xbee/0x1d20 [bridge] [ 27.013346][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.013350][ C2] ? __asan_memcpy+0x3c/0x60 [ 27.013352][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013356][ C2] __kmalloc_noprof+0x443/0x760 [ 27.013360][ C2] ? __rwlock_init+0x150/0x150 [ 27.013362][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 27.013366][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 27.013368][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.013370][ C2] put_chars+0x1e1/0x2f0 [ 27.013372][ C2] ? __send_to_port+0x420/0x420 [ 27.013373][ C2] ? console_prepend_replay+0x20/0x20 [ 27.013377][ C2] hvc_console_print+0x292/0x780 [ 27.013380][ C2] ? hvc_write+0x3a0/0x3a0 [ 27.013382][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013383][ C2] ? lock_acquire+0x13c/0x160 [ 27.013386][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.013388][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 27.013390][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013392][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013394][ C2] ? lock_acquire+0x13c/0x160 [ 27.013395][ C2] ? console_flush_one_record+0x111/0x710 [ 27.013397][ C2] console_flush_one_record+0x46f/0x710 [ 27.013399][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 27.013402][ C2] console_unlock+0xee/0x1f0 [ 27.013404][ C2] ? lock_acquire+0x13c/0x160 [ 27.013405][ C2] ? console_flush_one_record+0x710/0x710 [ 27.013407][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013408][ C2] ? lock_release+0x184/0x1f0 [ 27.013410][ C2] ? lock_acquire+0x60/0x160 [ 27.013412][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.013413][ C2] ? vprintk_emit+0x320/0x3e0 [ 27.013415][ C2] vprintk_emit+0x37c/0x3e0 [ 27.013418][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 27.013419][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.013427][ C2] ? lock_release+0x184/0x1f0 [ 27.013429][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.013435][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.013441][ C2] ? is_module_text_address+0x154/0x250 [ 27.013444][ C2] _printk+0xc7/0x100 [ 27.013448][ C2] ? snapshot_read.cold+0x21/0x21 [ 27.013449][ C2] ? arch_stack_walk+0xd7/0x130 [ 27.013453][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.013455][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.013457][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.013459][ C2] mark_lock_irq+0x989/0x9c0 [ 27.013462][ C2] mark_lock+0x1d7/0xa00 [ 27.013464][ C2] mark_usage+0x42/0x170 [ 27.013466][ C2] __lock_acquire+0x388/0xc20 [ 27.013468][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.013470][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.013476][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013478][ C2] ? lock_acquire+0x13c/0x160 [ 27.013480][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.013486][ C2] _raw_spin_lock+0x33/0x40 [ 27.013487][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.013494][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.013500][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.013506][ C2] call_timer_fn+0x160/0x4d0 [ 27.013509][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.013511][ C2] ? debug_object_active_state+0x430/0x430 [ 27.013514][ C2] ? find_held_lock+0x2b/0x80 [ 27.013516][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.013518][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013520][ C2] __run_timers+0x68f/0xaa0 [ 27.013522][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.013529][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.013531][ C2] ? __lock_acquire+0x518/0xc20 [ 27.013533][ C2] ? __rwlock_init+0x150/0x150 [ 27.013536][ C2] run_timer_softirq+0xf0/0x160 [ 27.013538][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.013539][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.013542][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013543][ C2] handle_softirqs+0x1d3/0x900 [ 27.013545][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.013547][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.013549][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.013550][ C2] irq_exit_rcu+0xe/0x30 [ 27.013552][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.013555][ C2] [ 27.013555][ C2] [ 27.013556][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.013558][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.013560][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.013563][ 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 [ 27.013565][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.013568][ C2] RAX: 0000000000079db7 RBX: ff11000001bec6c0 RCX: ffffffffadef0307 [ 27.013570][ C2] RDX: ff11000001bec6c0 RSI: ffffffffb0c38af6 RDI: ffffffffb068d8e0 [ 27.013570][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.013571][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.013572][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.013573][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.013577][ C2] default_idle+0x9/0x10 [ 27.013579][ C2] default_idle_call+0x6e/0xb0 [ 27.013580][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.013582][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.013583][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.013586][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013587][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.013589][ C2] do_idle+0xd8/0x190 [ 27.013591][ C2] cpu_startup_entry+0x53/0x70 [ 27.013593][ C2] start_secondary+0x204/0x2b0 [ 27.013594][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.013596][ C2] common_startup_64+0x13e/0x148 [ 27.013600][ C2] [ 27.031853][ C2] ======================================================== [ 27.032096][ C2] WARNING: possible irq lock inversion dependency detected [ 27.032345][ C2] 7.2.0-virtme #1 Tainted: G W [ 27.032507][ C2] -------------------------------------------------------- [ 27.032748][ C2] swapper/2/0 just changed the state of lock: [ 27.032950][ C2] ff1100000cb4ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.033270][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.033463][ C2] (fs_reclaim){+.+.}-{0:0} [ 27.033465][ C2] [ 27.033465][ C2] [ 27.033465][ C2] and interrupts could create inverse lock ordering between them. [ 27.033465][ C2] [ 27.034023][ C2] [ 27.034023][ C2] other info that might help us debug this: [ 27.034263][ C2] Chain exists of: [ 27.034263][ C2] &br->lock --> console_owner --> fs_reclaim [ 27.034263][ C2] [ 27.034598][ C2] Possible interrupt unsafe locking scenario: [ 27.034598][ C2] [ 27.034838][ C2] CPU0 CPU1 [ 27.034967][ C2] ---- ---- [ 27.035144][ C2] lock(fs_reclaim); [ 27.035249][ C2] local_irq_disable(); [ 27.035453][ C2] lock(&br->lock); [ 27.035660][ C2] lock(console_owner); [ 27.035827][ C2] [ 27.035970][ C2] lock(&br->lock); [ 27.036071][ C2] [ 27.036071][ C2] *** DEADLOCK *** [ 27.036071][ C2] [ 27.036312][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 27.036475][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.036783][ C2] [ 27.036783][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 27.037057][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 27.037241][ C2] HARDIRQ-ON-W at: [ 27.037345][ C2] __lock_acquire+0x388/0xc20 [ 27.037561][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.037725][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.037934][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.038177][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.038379][ C2] workqueue_init+0x2a/0x680 [ 27.038590][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.038834][ C2] kernel_init+0x21/0x150 [ 27.039004][ C2] ret_from_fork+0x474/0x6b0 [ 27.039213][ C2] ret_from_fork_asm+0x11/0x20 [ 27.039432][ C2] SOFTIRQ-ON-W at: [ 27.039529][ C2] __lock_acquire+0x388/0xc20 [ 27.039738][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.039901][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.040111][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.040360][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.040558][ C2] workqueue_init+0x2a/0x680 [ 27.040771][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.041029][ C2] kernel_init+0x21/0x150 [ 27.041194][ C2] ret_from_fork+0x474/0x6b0 [ 27.041411][ C2] ret_from_fork_asm+0x11/0x20 [ 27.041621][ C2] INITIAL USE at: [ 27.041720][ C2] __lock_acquire+0x388/0xc20 [ 27.041928][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.042093][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.042303][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.042548][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.042749][ C2] workqueue_init+0x2a/0x680 [ 27.042956][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.043168][ C2] kernel_init+0x21/0x150 [ 27.043338][ C2] ret_from_fork+0x474/0x6b0 [ 27.043545][ C2] ret_from_fork_asm+0x11/0x20 [ 27.043758][ C2] } [ 27.043828][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.044068][ C2] ... acquired at: [ 27.044170][ C2] __lock_acquire+0x518/0xc20 [ 27.044312][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.044486][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.044613][ C2] __kmalloc_noprof+0xd3/0x760 [ 27.044786][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.044919][ C2] put_chars+0x1e1/0x2f0 [ 27.045093][ C2] hvc_console_print+0x292/0x780 [ 27.045225][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.045404][ C2] console_flush_one_record+0x46f/0x710 [ 27.045534][ C2] console_unlock+0xee/0x1f0 [ 27.045707][ C2] vprintk_emit+0x37c/0x3e0 [ 27.045836][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 27.046011][ C2] dev_printk_emit+0xb9/0xee [ 27.046141][ C2] _dev_info+0xe2/0x116 [ 27.046243][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.046407][ C2] cmos_do_probe+0x73b/0x98a [ 27.046582][ C2] platform_probe+0xfe/0x1f0 [ 27.046717][ C2] call_driver_probe+0x61/0x1c0 [ 27.046892][ C2] really_probe+0x199/0x760 [ 27.047023][ C2] __driver_probe_device+0x24f/0x440 [ 27.047197][ C2] driver_probe_device+0x4a/0xf0 [ 27.047331][ C2] __driver_attach+0x1b8/0x540 [ 27.047511][ C2] bus_for_each_dev+0x130/0x1e0 [ 27.047640][ C2] bus_add_driver+0x2c8/0x530 [ 27.047810][ C2] driver_register+0x1a3/0x390 [ 27.047937][ C2] __platform_driver_probe+0x13f/0x270 [ 27.048112][ C2] cmos_init+0x31/0x40 [ 27.048212][ C2] do_one_initcall+0x124/0x4f0 [ 27.048389][ C2] kernel_init_freeable+0x596/0x630 [ 27.048518][ C2] kernel_init+0x21/0x150 [ 27.048697][ C2] ret_from_fork+0x474/0x6b0 [ 27.048826][ C2] ret_from_fork_asm+0x11/0x20 [ 27.049001][ C2] [ 27.049068][ C2] -> (console_owner){....}-{0:0} { [ 27.049259][ C2] INITIAL USE at: [ 27.049358][ C2] } [ 27.049424][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.049664][ C2] ... acquired at: [ 27.049761][ C2] __lock_acquire+0x518/0xc20 [ 27.049938][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.050067][ C2] console_lock_spinning_enable+0x5c/0x60 [ 27.050278][ C2] console_emit_next_record+0x1d1/0x4c0 [ 27.050410][ C2] console_flush_one_record+0x46f/0x710 [ 27.050588][ C2] console_unlock+0xee/0x1f0 [ 27.050720][ C2] vprintk_emit+0x37c/0x3e0 [ 27.050893][ C2] _printk+0xc7/0x100 [ 27.050992][ C2] br_set_state+0x22f/0x430 [bridge] [ 27.051175][ C2] br_init_port+0xc4/0x200 [bridge] [ 27.051315][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 27.051526][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 27.051741][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 27.051879][ C2] notifier_call_chain+0xae/0x300 [ 27.052053][ C2] netif_state_change+0x139/0x340 [ 27.052188][ C2] __linkwatch_run_queue+0x34c/0x750 [ 27.052370][ C2] linkwatch_event+0x7f/0xb0 [ 27.052497][ C2] process_one_work+0xe3e/0x1560 [ 27.052670][ C2] worker_thread+0x4f1/0xd60 [ 27.052802][ C2] kthread+0x367/0x460 [ 27.052901][ C2] ret_from_fork+0x474/0x6b0 [ 27.053079][ C2] ret_from_fork_asm+0x11/0x20 [ 27.053210][ C2] [ 27.053323][ C2] -> (&br->lock){+.-.}-{3:3} { [ 27.053454][ C2] HARDIRQ-ON-W at: [ 27.053599][ C2] __lock_acquire+0x388/0xc20 [ 27.053766][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.053975][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.054144][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 27.054367][ C2] do_set_master+0x357/0x580 [ 27.054577][ C2] do_setlink.isra.0+0xa31/0x2750 [ 27.054742][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.054950][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.055161][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.055335][ C2] netlink_unicast+0x486/0x750 [ 27.055550][ C2] netlink_sendmsg+0x735/0xc60 [ 27.055715][ C2] ____sys_sendmsg+0x415/0x880 [ 27.055923][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.056131][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.056301][ C2] do_syscall_64+0xff/0x530 [ 27.056509][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.056755][ C2] IN-SOFTIRQ-W at: [ 27.056856][ C2] __lock_acquire+0x388/0xc20 [ 27.057064][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.057235][ C2] _raw_spin_lock+0x33/0x40 [ 27.057455][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.057702][ C2] call_timer_fn+0x160/0x4d0 [ 27.057868][ C2] __run_timers+0x68f/0xaa0 [ 27.058079][ C2] run_timer_softirq+0xf0/0x160 [ 27.058324][ C2] handle_softirqs+0x1d3/0x900 [ 27.058489][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.058704][ C2] irq_exit_rcu+0xe/0x30 [ 27.058876][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.059116][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.059361][ C2] pv_native_safe_halt+0xf/0x10 [ 27.059567][ C2] default_idle+0x9/0x10 [ 27.059733][ C2] default_idle_call+0x6e/0xb0 [ 27.059943][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.060187][ C2] do_idle+0xd8/0x190 [ 27.060323][ C2] cpu_startup_entry+0x53/0x70 [ 27.060534][ C2] start_secondary+0x204/0x2b0 [ 27.060698][ C2] common_startup_64+0x13e/0x148 [ 27.060912][ C2] INITIAL USE at: [ 27.061009][ C2] __lock_acquire+0x388/0xc20 [ 27.061223][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.061435][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.061600][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 27.061816][ C2] do_set_master+0x357/0x580 [ 27.061986][ C2] do_setlink.isra.0+0xa31/0x2750 [ 27.062196][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.062416][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.062583][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.062794][ C2] netlink_unicast+0x486/0x750 [ 27.063004][ C2] netlink_sendmsg+0x735/0xc60 [ 27.063169][ C2] ____sys_sendmsg+0x415/0x880 [ 27.063381][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.063545][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.063755][ C2] do_syscall_64+0xff/0x530 [ 27.063962][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.064159][ C2] } [ 27.064281][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.064480][ C2] ... acquired at: [ 27.064622][ C2] mark_lock+0x1d7/0xa00 [ 27.064757][ C2] mark_usage+0x42/0x170 [ 27.064933][ C2] __lock_acquire+0x388/0xc20 [ 27.065065][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.065246][ C2] _raw_spin_lock+0x33/0x40 [ 27.065380][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065597][ C2] call_timer_fn+0x160/0x4d0 [ 27.065730][ C2] __run_timers+0x68f/0xaa0 [ 27.065905][ C2] run_timer_softirq+0xf0/0x160 [ 27.066037][ C2] handle_softirqs+0x1d3/0x900 [ 27.066217][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.066356][ C2] irq_exit_rcu+0xe/0x30 [ 27.066531][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.066741][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.066906][ C2] pv_native_safe_halt+0xf/0x10 [ 27.067080][ C2] default_idle+0x9/0x10 [ 27.067211][ C2] default_idle_call+0x6e/0xb0 [ 27.067391][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.067553][ C2] do_idle+0xd8/0x190 [ 27.067694][ C2] cpu_startup_entry+0x53/0x70 [ 27.067824][ C2] start_secondary+0x204/0x2b0 [ 27.067998][ C2] common_startup_64+0x13e/0x148 [ 27.068129][ C2] [ 27.068195][ C2] [ 27.068195][ C2] stack backtrace: [ 27.068404][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.068409][ C2] Tainted: [W]=WARN [ 27.068410][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.068411][ C2] Call Trace: [ 27.068412][ C2] [ 27.068414][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.068418][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.068422][ C2] mark_lock_irq+0x989/0x9c0 [ 27.068425][ C2] mark_lock+0x1d7/0xa00 [ 27.068427][ C2] mark_usage+0x42/0x170 [ 27.068428][ C2] __lock_acquire+0x388/0xc20 [ 27.068430][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.068432][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.068439][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.068442][ C2] ? lock_acquire+0x13c/0x160 [ 27.068443][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.068451][ C2] _raw_spin_lock+0x33/0x40 [ 27.068452][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.068458][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.068465][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.068471][ C2] call_timer_fn+0x160/0x4d0 [ 27.068473][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.068474][ C2] ? debug_object_active_state+0x430/0x430 [ 27.068478][ C2] ? find_held_lock+0x2b/0x80 [ 27.068480][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.068482][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.068484][ C2] __run_timers+0x68f/0xaa0 [ 27.068485][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.068492][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.068494][ C2] ? __lock_acquire+0x518/0xc20 [ 27.068496][ C2] ? __rwlock_init+0x150/0x150 [ 27.068499][ C2] run_timer_softirq+0xf0/0x160 [ 27.068500][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.068502][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.068505][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.068506][ C2] handle_softirqs+0x1d3/0x900 [ 27.068508][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.068510][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.068512][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.068513][ C2] irq_exit_rcu+0xe/0x30 [ 27.068514][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.068516][ C2] [ 27.068517][ C2] [ 27.068517][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.068519][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.068521][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.068523][ 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 [ 27.068525][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.068528][ C2] RAX: 0000000000079db7 RBX: ff11000001bec6c0 RCX: ffffffffadef0307 [ 27.068529][ C2] RDX: ff11000001bec6c0 RSI: ffffffffb0c38af6 RDI: ffffffffb068d8e0 [ 27.068530][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.068531][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.068532][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.068533][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.068535][ C2] default_idle+0x9/0x10 [ 27.068537][ C2] default_idle_call+0x6e/0xb0 [ 27.068538][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.068540][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.068542][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.068543][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.068545][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.068547][ C2] do_idle+0xd8/0x190 [ 27.068549][ C2] cpu_startup_entry+0x53/0x70 [ 27.068550][ C2] start_secondary+0x204/0x2b0 [ 27.068551][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.068553][ C2] common_startup_64+0x13e/0x148 [ 27.068556][ C2] [ 27.250568][ T259] GACT probability NOT on [ 28.393719][ T267] br0: port 2(veth2) entered disabled state [ 28.393736][ T267] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 28.393739][ T267] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 267, name: ip [ 28.393740][ T267] preempt_count: 202, expected: 0 [ 28.393741][ T267] RCU nest depth: 0, expected: 0 [ 28.393742][ T267] INFO: lockdep is turned off. [ 28.393743][ T267] irq event stamp: 0 [ 28.393744][ T267] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 28.393749][ T267] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 28.393755][ T267] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 28.393756][ T267] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 28.393758][ T267] Preemption disabled at: [ 28.393759][ T267] [<0000000000000000>] 0x0 [ 28.393764][ T267] CPU: 2 UID: 0 PID: 267 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.393767][ T267] Tainted: [W]=WARN [ 28.393768][ T267] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.393770][ T267] Call Trace: [ 28.393772][ T267] [ 28.393773][ T267] dump_stack_lvl+0x6f/0xa0 [ 28.393778][ T267] __might_resched.cold+0x1fe/0x2c1 [ 28.393782][ T267] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 28.393786][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393790][ T267] __kmalloc_noprof+0x443/0x760 [ 28.393793][ T267] ? __rwlock_init+0x150/0x150 [ 28.393796][ T267] ? alloc_buf.isra.0+0x4b/0x260 [ 28.393800][ T267] ? do_raw_spin_unlock+0x59/0x250 [ 28.393801][ T267] alloc_buf.isra.0+0x4b/0x260 [ 28.393803][ T267] put_chars+0x1e1/0x2f0 [ 28.393805][ T267] ? __send_to_port+0x420/0x420 [ 28.393807][ T267] ? printk_get_next_message+0x240/0x650 [ 28.393810][ T267] hvc_console_print+0x292/0x780 [ 28.393813][ T267] ? hvc_write+0x3a0/0x3a0 [ 28.393816][ T267] ? __rwlock_init+0x150/0x150 [ 28.393817][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393819][ T267] ? lock_acquire+0x13c/0x160 [ 28.393821][ T267] console_emit_next_record+0x22f/0x4c0 [ 28.393824][ T267] ? devkmsg_read+0x4b0/0x4b0 [ 28.393825][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393827][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393829][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393830][ T267] ? lock_acquire+0x13c/0x160 [ 28.393832][ T267] ? console_flush_one_record+0x111/0x710 [ 28.393834][ T267] console_flush_one_record+0x46f/0x710 [ 28.393836][ T267] ? console_emit_next_record+0x4c0/0x4c0 [ 28.393839][ T267] console_unlock+0xee/0x1f0 [ 28.393840][ T267] ? lock_acquire+0x13c/0x160 [ 28.393842][ T267] ? console_flush_one_record+0x710/0x710 [ 28.393843][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393845][ T267] ? lock_release+0x184/0x1f0 [ 28.393846][ T267] ? lock_acquire+0x60/0x160 [ 28.393848][ T267] ? __rcu_read_lock+0x30/0x70 [ 28.393850][ T267] ? __down_trylock_console_sem+0x5e/0xa0 [ 28.393852][ T267] ? vprintk_emit+0x320/0x3e0 [ 28.393854][ T267] vprintk_emit+0x37c/0x3e0 [ 28.393856][ T267] ? wake_up_klogd_work_func+0x90/0x90 [ 28.393858][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393860][ T267] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.393862][ T267] ? trace_hardirqs_on+0x36/0x40 [ 28.393864][ T267] _printk+0xc7/0x100 [ 28.393867][ T267] ? snapshot_read.cold+0x21/0x21 [ 28.393869][ T267] ? ipv6_mc_down+0x17e/0x2d0 [ 28.393874][ T267] br_set_state+0x22f/0x430 [bridge] [ 28.393888][ T267] br_stp_disable_port+0x11f/0x390 [bridge] [ 28.393896][ T267] br_device_event+0x7b5/0x8f0 [bridge] [ 28.393904][ T267] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 28.393907][ T267] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 28.393909][ T267] ? addrconf_notify+0x2c8/0xf30 [ 28.393913][ T267] notifier_call_chain+0xae/0x300 [ 28.393916][ T267] __dev_notify_flags+0x23c/0x3c0 [ 28.393920][ T267] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.393921][ T267] ? netif_change_name+0x8c0/0x8c0 [ 28.393923][ T267] ? netif_set_allmulti+0x250/0x250 [ 28.393925][ T267] ? __kernel_text_address+0x12/0x30 [ 28.393927][ T267] ? write_profile+0xf0/0xf0 [ 28.393929][ T267] ? unwind_get_return_address+0x67/0xd0 [ 28.393932][ T267] netif_change_flags+0xfe/0x190 [ 28.393934][ T267] do_setlink.isra.0+0x1cc3/0x2750 [ 28.393937][ T267] ? stack_trace_save+0x93/0xc0 [ 28.393938][ T267] ? rtnl_link_get_size+0x350/0x350 [ 28.393940][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393941][ T267] ? stack_depot_save_flags+0x1bc/0x790 [ 28.393945][ T267] ? kasan_save_stack+0x3d/0x50 [ 28.393949][ T267] ? kasan_save_stack+0x2f/0x50 [ 28.393950][ T267] ? kasan_save_track+0x14/0x30 [ 28.393951][ T267] ? __kasan_kmalloc+0x7b/0x90 [ 28.393953][ T267] ? __kmalloc_cache_noprof+0x29a/0x620 [ 28.393954][ T267] ? rtnl_newlink+0x124/0x11c0 [ 28.393955][ T267] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.393956][ T267] ? netlink_rcv_skb+0x14e/0x3a0 [ 28.393959][ T267] ? netlink_unicast+0x486/0x750 [ 28.393960][ T267] ? netlink_sendmsg+0x735/0xc60 [ 28.393961][ T267] ? ____sys_sendmsg+0x415/0x880 [ 28.393964][ T267] ? ___sys_sendmsg+0x14e/0x1d0 [ 28.393965][ T267] ? __sys_sendmsg+0x12c/0x1d0 [ 28.393967][ T267] ? do_syscall_64+0xff/0x530 [ 28.393969][ T267] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.393971][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393973][ T267] ? lock_acquire+0x13c/0x160 [ 28.393975][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393976][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393978][ T267] ? trace_contention_end+0xb3/0x180 [ 28.393981][ T267] ? __mutex_lock+0x1db/0x1ea0 [ 28.393982][ T267] ? __mutex_lock+0x9a3/0x1ea0 [ 28.393984][ T267] ? rtnl_newlink+0x9a8/0x11c0 [ 28.393985][ T267] ? ww_mutex_lock+0x160/0x160 [ 28.393987][ T267] ? __rtnl_newlink+0x3fa/0xa50 [ 28.393989][ T267] rtnl_newlink+0x9f1/0x11c0 [ 28.393991][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393993][ T267] ? rtnl_bridge_getlink+0x850/0x850 [ 28.393994][ T267] ? lock_acquire+0x13c/0x160 [ 28.393995][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.393997][ T267] ? is_bpf_text_address+0x3e/0x110 [ 28.394001][ T267] ? is_bpf_text_address+0x72/0x110 [ 28.394002][ T267] ? kernel_text_address+0x149/0x170 [ 28.394004][ T267] ? __kernel_text_address+0x12/0x30 [ 28.394005][ T267] ? write_profile+0xf0/0xf0 [ 28.394006][ T267] ? unwind_get_return_address+0x67/0xd0 [ 28.394007][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394009][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394011][ T267] ? cap_capable+0x1d7/0x3d0 [ 28.394015][ T267] ? lock_acquire+0x13c/0x160 [ 28.394016][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394018][ T267] ? rtnl_bridge_getlink+0x850/0x850 [ 28.394019][ T267] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.394021][ T267] ? rtnl_link_fill+0x920/0x920 [ 28.394022][ T267] ? filter_irq_stacks+0xd0/0xd0 [ 28.394023][ T267] ? stack_depot_save_flags+0x1bc/0x790 [ 28.394026][ T267] netlink_rcv_skb+0x14e/0x3a0 [ 28.394027][ T267] ? rtnl_link_fill+0x920/0x920 [ 28.394028][ T267] ? lock_release+0x184/0x1f0 [ 28.394030][ T267] ? netlink_ack+0xcf0/0xcf0 [ 28.394031][ T267] ? __netlink_lookup+0xb9/0x6b0 [ 28.394033][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394035][ T267] ? lock_release+0x184/0x1f0 [ 28.394037][ T267] netlink_unicast+0x486/0x750 [ 28.394039][ T267] ? netlink_attachskb+0x810/0x810 [ 28.394040][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394042][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394044][ T267] ? lock_acquire+0x13c/0x160 [ 28.394045][ T267] netlink_sendmsg+0x735/0xc60 [ 28.394047][ T267] ? netlink_unicast+0x750/0x750 [ 28.394049][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394051][ T267] ? lock_release+0x184/0x1f0 [ 28.394053][ T267] ____sys_sendmsg+0x415/0x880 [ 28.394054][ T267] ? copy_msghdr_from_user+0x279/0x420 [ 28.394056][ T267] ? get_timestamp.constprop.0+0x390/0x390 [ 28.394057][ T267] ? move_addr_to_kernel+0x40/0x40 [ 28.394059][ T267] ? filter_irq_stacks+0xd0/0xd0 [ 28.394061][ T267] ___sys_sendmsg+0x14e/0x1d0 [ 28.394063][ T267] ? copy_msghdr_from_user+0x420/0x420 [ 28.394068][ T267] __sys_sendmsg+0x12c/0x1d0 [ 28.394069][ T267] ? __sys_sendmsg_sock+0x20/0x20 [ 28.394072][ T267] ? rcu_is_watching+0x16/0xd0 [ 28.394074][ T267] do_syscall_64+0xff/0x530 [ 28.394075][ T267] ? exc_page_fault+0xee/0x100 [ 28.394078][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.394079][ T267] RIP: 0033:0x7f01c24ad54e [ 28.394081][ T267] 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 [ 28.394084][ T267] RSP: 002b:00007ffeb1cb3290 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 28.394087][ T267] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f01c24ad54e [ 28.394089][ T267] RDX: 0000000000000000 RSI: 00007ffeb1cb3340 RDI: 0000000000000005 [ 28.394090][ T267] RBP: 00007ffeb1cb32a0 R08: 0000000000000000 R09: 0000000000000000 [ 28.394090][ T267] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b5e4 [ 28.394091][ T267] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffeb1cb3a00 [ 28.394094][ T267] [ 28.436947][ T267] ip (267) used greatest stack depth: 23016 bytes left [ 28.497475][ T39] br0: port 2(veth2) entered blocking state [ 28.497697][ T39] br0: port 2(veth2) entered forwarding state [ 34.725948][ T291] br0: port 1(veth1) entered disabled state [ 34.725967][ T291] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 34.725970][ T291] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 291, name: ip [ 34.725972][ T291] preempt_count: 202, expected: 0 [ 34.725972][ T291] RCU nest depth: 0, expected: 0 [ 34.725973][ T291] INFO: lockdep is turned off. [ 34.725974][ T291] irq event stamp: 0 [ 34.725975][ T291] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 34.725980][ T291] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 34.725986][ T291] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 34.725988][ T291] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 34.725990][ T291] Preemption disabled at: [ 34.725990][ T291] [<0000000000000000>] 0x0 [ 34.725995][ T291] CPU: 0 UID: 0 PID: 291 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 34.725999][ T291] Tainted: [W]=WARN [ 34.726000][ T291] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 34.726001][ T291] Call Trace: [ 34.726003][ T291] [ 34.726004][ T291] dump_stack_lvl+0x6f/0xa0 [ 34.726010][ T291] __might_resched.cold+0x1fe/0x2c1 [ 34.726014][ T291] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 34.726017][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726021][ T291] __kmalloc_noprof+0x443/0x760 [ 34.726025][ T291] ? __rwlock_init+0x150/0x150 [ 34.726028][ T291] ? alloc_buf.isra.0+0x4b/0x260 [ 34.726032][ T291] ? do_raw_spin_unlock+0x59/0x250 [ 34.726033][ T291] alloc_buf.isra.0+0x4b/0x260 [ 34.726035][ T291] put_chars+0x1e1/0x2f0 [ 34.726037][ T291] ? __send_to_port+0x420/0x420 [ 34.726039][ T291] ? printk_get_next_message+0x240/0x650 [ 34.726042][ T291] hvc_console_print+0x292/0x780 [ 34.726046][ T291] ? hvc_write+0x3a0/0x3a0 [ 34.726048][ T291] ? __rwlock_init+0x150/0x150 [ 34.726049][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726051][ T291] ? lock_acquire+0x13c/0x160 [ 34.726053][ T291] console_emit_next_record+0x22f/0x4c0 [ 34.726056][ T291] ? devkmsg_read+0x4b0/0x4b0 [ 34.726058][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726059][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726061][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726063][ T291] ? lock_acquire+0x13c/0x160 [ 34.726064][ T291] ? console_flush_one_record+0x111/0x710 [ 34.726066][ T291] console_flush_one_record+0x46f/0x710 [ 34.726068][ T291] ? console_emit_next_record+0x4c0/0x4c0 [ 34.726071][ T291] console_unlock+0xee/0x1f0 [ 34.726073][ T291] ? lock_acquire+0x13c/0x160 [ 34.726074][ T291] ? console_flush_one_record+0x710/0x710 [ 34.726076][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726077][ T291] ? lock_release+0x184/0x1f0 [ 34.726079][ T291] ? lock_acquire+0x60/0x160 [ 34.726080][ T291] ? __rcu_read_lock+0x30/0x70 [ 34.726082][ T291] ? __down_trylock_console_sem+0x5e/0xa0 [ 34.726084][ T291] ? vprintk_emit+0x320/0x3e0 [ 34.726086][ T291] vprintk_emit+0x37c/0x3e0 [ 34.726088][ T291] ? wake_up_klogd_work_func+0x90/0x90 [ 34.726090][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726092][ T291] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.726094][ T291] ? trace_hardirqs_on+0x36/0x40 [ 34.726096][ T291] _printk+0xc7/0x100 [ 34.726099][ T291] ? snapshot_read.cold+0x21/0x21 [ 34.726101][ T291] ? ipv6_mc_down+0x17e/0x2d0 [ 34.726105][ T291] br_set_state+0x22f/0x430 [bridge] [ 34.726118][ T291] br_stp_disable_port+0x11f/0x390 [bridge] [ 34.726126][ T291] br_device_event+0x7b5/0x8f0 [bridge] [ 34.726135][ T291] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 34.726138][ T291] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 34.726140][ T291] ? addrconf_notify+0x2c8/0xf30 [ 34.726143][ T291] notifier_call_chain+0xae/0x300 [ 34.726147][ T291] __dev_notify_flags+0x23c/0x3c0 [ 34.726150][ T291] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.726152][ T291] ? netif_change_name+0x8c0/0x8c0 [ 34.726154][ T291] ? netif_set_allmulti+0x250/0x250 [ 34.726156][ T291] ? __kernel_text_address+0x12/0x30 [ 34.726158][ T291] ? write_profile+0xf0/0xf0 [ 34.726160][ T291] ? unwind_get_return_address+0x67/0xd0 [ 34.726163][ T291] netif_change_flags+0xfe/0x190 [ 34.726165][ T291] do_setlink.isra.0+0x1cc3/0x2750 [ 34.726171][ T291] ? stack_trace_save+0x93/0xc0 [ 34.726173][ T291] ? rtnl_link_get_size+0x350/0x350 [ 34.726174][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726176][ T291] ? stack_depot_save_flags+0x1bc/0x790 [ 34.726180][ T291] ? kasan_save_stack+0x3d/0x50 [ 34.726183][ T291] ? kasan_save_stack+0x2f/0x50 [ 34.726185][ T291] ? kasan_save_track+0x14/0x30 [ 34.726186][ T291] ? __kasan_kmalloc+0x7b/0x90 [ 34.726188][ T291] ? __kmalloc_cache_noprof+0x29a/0x620 [ 34.726189][ T291] ? rtnl_newlink+0x124/0x11c0 [ 34.726190][ T291] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 34.726191][ T291] ? netlink_rcv_skb+0x14e/0x3a0 [ 34.726194][ T291] ? netlink_unicast+0x486/0x750 [ 34.726195][ T291] ? netlink_sendmsg+0x735/0xc60 [ 34.726196][ T291] ? ____sys_sendmsg+0x415/0x880 [ 34.726199][ T291] ? ___sys_sendmsg+0x14e/0x1d0 [ 34.726200][ T291] ? __sys_sendmsg+0x12c/0x1d0 [ 34.726202][ T291] ? do_syscall_64+0xff/0x530 [ 34.726204][ T291] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 34.726206][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726208][ T291] ? lock_acquire+0x13c/0x160 [ 34.726209][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726211][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726213][ T291] ? trace_contention_end+0xb3/0x180 [ 34.726216][ T291] ? __mutex_lock+0x1db/0x1ea0 [ 34.726217][ T291] ? __mutex_lock+0x9a3/0x1ea0 [ 34.726218][ T291] ? rtnl_newlink+0x9a8/0x11c0 [ 34.726220][ T291] ? ww_mutex_lock+0x160/0x160 [ 34.726222][ T291] ? __rtnl_newlink+0x3fa/0xa50 [ 34.726224][ T291] rtnl_newlink+0x9f1/0x11c0 [ 34.726226][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726228][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 34.726229][ T291] ? lock_acquire+0x13c/0x160 [ 34.726230][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726232][ T291] ? is_bpf_text_address+0x3e/0x110 [ 34.726235][ T291] ? is_bpf_text_address+0x72/0x110 [ 34.726237][ T291] ? kernel_text_address+0x149/0x170 [ 34.726238][ T291] ? __kernel_text_address+0x12/0x30 [ 34.726240][ T291] ? write_profile+0xf0/0xf0 [ 34.726241][ T291] ? unwind_get_return_address+0x67/0xd0 [ 34.726242][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726244][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726245][ T291] ? cap_capable+0x1d7/0x3d0 [ 34.726249][ T291] ? lock_acquire+0x13c/0x160 [ 34.726250][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726252][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 34.726253][ T291] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 34.726255][ T291] ? rtnl_link_fill+0x920/0x920 [ 34.726256][ T291] ? filter_irq_stacks+0xd0/0xd0 [ 34.726262][ T291] ? stack_depot_save_flags+0x1bc/0x790 [ 34.726264][ T291] netlink_rcv_skb+0x14e/0x3a0 [ 34.726266][ T291] ? rtnl_link_fill+0x920/0x920 [ 34.726267][ T291] ? lock_release+0x184/0x1f0 [ 34.726269][ T291] ? netlink_ack+0xcf0/0xcf0 [ 34.726270][ T291] ? __netlink_lookup+0xb9/0x6b0 [ 34.726272][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726274][ T291] ? lock_release+0x184/0x1f0 [ 34.726276][ T291] netlink_unicast+0x486/0x750 [ 34.726278][ T291] ? netlink_attachskb+0x810/0x810 [ 34.726280][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726281][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726283][ T291] ? lock_acquire+0x13c/0x160 [ 34.726285][ T291] netlink_sendmsg+0x735/0xc60 [ 34.726287][ T291] ? netlink_unicast+0x750/0x750 [ 34.726289][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726290][ T291] ? lock_release+0x184/0x1f0 [ 34.726292][ T291] ____sys_sendmsg+0x415/0x880 [ 34.726294][ T291] ? copy_msghdr_from_user+0x279/0x420 [ 34.726295][ T291] ? get_timestamp.constprop.0+0x390/0x390 [ 34.726297][ T291] ? move_addr_to_kernel+0x40/0x40 [ 34.726298][ T291] ? filter_irq_stacks+0xd0/0xd0 [ 34.726301][ T291] ___sys_sendmsg+0x14e/0x1d0 [ 34.726303][ T291] ? copy_msghdr_from_user+0x420/0x420 [ 34.726307][ T291] __sys_sendmsg+0x12c/0x1d0 [ 34.726309][ T291] ? __sys_sendmsg_sock+0x20/0x20 [ 34.726312][ T291] ? rcu_is_watching+0x16/0xd0 [ 34.726314][ T291] do_syscall_64+0xff/0x530 [ 34.726315][ T291] ? exc_page_fault+0xee/0x100 [ 34.726317][ T291] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 34.726319][ T291] RIP: 0033:0x7fd8aa1fe54e [ 34.726321][ T291] 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.726323][ T291] RSP: 002b:00007ffc2b86a8f0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 34.726327][ T291] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fd8aa1fe54e [ 34.726328][ T291] RDX: 0000000000000000 RSI: 00007ffc2b86a9a0 RDI: 0000000000000005 [ 34.726329][ T291] RBP: 00007ffc2b86a900 R08: 0000000000000000 R09: 0000000000000000 [ 34.726330][ T291] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b5eb [ 34.726331][ T291] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc2b86b060 [ 34.726333][ T291] [ 34.772964][ T292] br0: port 2(veth2) entered disabled state [ 34.792371][ T293] veth2: left allmulticast mode [ 34.792540][ T293] veth2: left promiscuous mode [ 34.792747][ T293] br0: port 2(veth2) entered disabled state [ 34.793945][ T293] veth1: left allmulticast mode [ 34.794086][ T293] veth1: left promiscuous mode [ 34.794478][ T293] br0: port 1(veth1) entered disabled state