[ 10.331773][ T223] 8021q: 802.1Q VLAN Support v1.8 [ 10.331791][ T223] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.331793][ T223] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 223, name: modprobe [ 10.331795][ T223] preempt_count: 1, expected: 0 [ 10.331796][ T223] RCU nest depth: 0, expected: 0 [ 10.331797][ T223] locks held by modprobe/223: 4, last CPU#2: [ 10.331799][ T223] #0: ffffffffbbd69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.331811][ T223] #1: ffffffffbbd69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.331815][ T223] #2: ffffffffbbc89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.331820][ T223] #3: ffffffffbbc89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.331824][ T223] irq event stamp: 27020 [ 10.331825][ T223] hardirqs last enabled at (27019): [] __down_trylock_console_sem+0x86/0xa0 [ 10.331827][ T223] hardirqs last disabled at (27020): [] console_emit_next_record+0x3d4/0x4c0 [ 10.331829][ T223] softirqs last enabled at (24432): [] handle_softirqs+0x67c/0x900 [ 10.331832][ T223] softirqs last disabled at (23949): [] __irq_exit_rcu+0x145/0x1c0 [ 10.331834][ T223] Preemption disabled at: [ 10.331835][ T223] [] vprintk_emit+0x31b/0x3e0 [ 10.331841][ T223] CPU: 2 UID: 0 PID: 223 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.331844][ T223] Tainted: [W]=WARN [ 10.331845][ T223] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.331847][ T223] Call Trace: [ 10.331848][ T223] [ 10.331850][ T223] dump_stack_lvl+0x6f/0xa0 [ 10.331856][ T223] ? vprintk_emit+0x31b/0x3e0 [ 10.331858][ T223] __might_resched.cold+0x1fe/0x2c1 [ 10.331862][ T223] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.331866][ T223] ? __kmalloc_noprof+0xdb/0x760 [ 10.331871][ T223] __kmalloc_noprof+0x443/0x760 [ 10.331873][ T223] ? alloc_buf.isra.0+0x4b/0x260 [ 10.331879][ T223] ? do_raw_spin_unlock+0x59/0x250 [ 10.331882][ T223] alloc_buf.isra.0+0x4b/0x260 [ 10.331885][ T223] put_chars+0x1e1/0x2f0 [ 10.331888][ T223] ? __send_to_port+0x420/0x420 [ 10.331889][ T223] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.331893][ T223] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.331896][ T223] ? validate_chain+0x38b/0xc20 [ 10.331900][ T223] hvc_console_print+0x292/0x780 [ 10.331903][ T223] ? __lock_acquire+0x518/0xc20 [ 10.331908][ T223] ? hvc_write+0x3a0/0x3a0 [ 10.331910][ T223] ? rcu_is_watching+0x16/0xd0 [ 10.331915][ T223] console_emit_next_record+0x22f/0x4c0 [ 10.331919][ T223] ? devkmsg_read+0x4b0/0x4b0 [ 10.331921][ T223] ? console_flush_one_record+0x106/0x710 [ 10.331924][ T223] ? rcu_is_watching+0x16/0xd0 [ 10.331926][ T223] ? lock_acquire+0x13c/0x160 [ 10.331930][ T223] console_flush_one_record+0x46f/0x710 [ 10.331934][ T223] ? console_emit_next_record+0x4c0/0x4c0 [ 10.331935][ T223] ? __lock_acquire+0x518/0xc20 [ 10.331940][ T223] console_unlock+0xee/0x1f0 [ 10.331943][ T223] ? console_flush_one_record+0x710/0x710 [ 10.331945][ T223] ? rcu_is_watching+0x16/0xd0 [ 10.331947][ T223] ? lock_acquire+0x60/0x160 [ 10.331950][ T223] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.331952][ T223] ? vprintk_emit+0x320/0x3e0 [ 10.331955][ T223] vprintk_emit+0x37c/0x3e0 [ 10.331959][ T223] ? wake_up_klogd_work_func+0x90/0x90 [ 10.331964][ T223] ? vlan_netlink_fini+0x960/0x960 [8021q] [ 10.331968][ T223] _printk+0xc7/0x100 [ 10.331972][ T223] ? snapshot_read.cold+0x21/0x21 [ 10.331978][ T223] ? do_one_initcall+0x113/0x4f0 [ 10.331981][ T223] vlan_proto_init+0x24/0x90 [8021q] [ 10.331984][ T223] do_one_initcall+0x124/0x4f0 [ 10.331986][ T223] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.331991][ T223] ? do_init_module+0xae/0x8a0 [ 10.331994][ T223] ? kasan_unpoison+0x44/0x70 [ 10.332004][ T223] do_init_module+0x2ad/0x8a0 [ 10.332008][ T223] ? lockdep_hardirqs_on+0x91/0x130 [ 10.332012][ T223] ? free_module+0x470/0x470 [ 10.332016][ T223] ? kfree+0x123/0x590 [ 10.332020][ T223] load_module+0x1424/0x1bb0 [ 10.332027][ T223] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.332031][ T223] ? __ia32_sys_fsconfig+0x180/0x180 [ 10.332034][ T223] ? find_held_lock+0x2b/0x80 [ 10.332036][ T223] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.332040][ T223] init_module_from_file+0x11f/0x1a0 [ 10.332043][ T223] ? __do_sys_init_module+0x220/0x220 [ 10.332051][ T223] ? __rwlock_init+0x150/0x150 [ 10.332054][ T223] ? do_raw_spin_unlock+0x59/0x250 [ 10.332057][ T223] idempotent_init_module+0x37b/0x7c0 [ 10.332061][ T223] ? init_module_from_file+0x1a0/0x1a0 [ 10.332067][ T223] ? rcu_is_watching+0x16/0xd0 [ 10.332069][ T223] ? cap_capable+0x1d7/0x3d0 [ 10.332075][ T223] __x64_sys_finit_module+0xdf/0x190 [ 10.332077][ T223] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.332081][ T223] do_syscall_64+0xff/0x530 [ 10.332083][ T223] ? exc_page_fault+0xee/0x100 [ 10.332085][ T223] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.332087][ T223] RIP: 0033:0x7f25f9c5a37d [ 10.332091][ T223] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 10.332092][ T223] RSP: 002b:00007ffe316cb5f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.332095][ T223] RAX: ffffffffffffffda RBX: 0000557302552810 RCX: 00007f25f9c5a37d [ 10.332096][ T223] RDX: 0000000000000000 RSI: 00005572f388cffd RDI: 0000000000000000 [ 10.332097][ T223] RBP: 00007ffe316cb690 R08: 0000000000000000 R09: 0000000000000000 [ 10.332098][ T223] R10: 0000000000000000 R11: 0000000000000246 R12: 00005572f388cffd [ 10.332099][ T223] R13: 0000557302552960 R14: 0000000000040000 R15: 0000000000000000 [ 10.332105][ T223] [ 11.379346][ T259] br1: port 1(veth1) entered blocking state [ 11.379458][ T259] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.379460][ T259] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 259, name: ip [ 11.379462][ T259] preempt_count: 1, expected: 0 [ 11.379463][ T259] RCU nest depth: 0, expected: 0 [ 11.379464][ T259] locks held by ip/259: 5, last CPU#2: [ 11.379466][ T259] #0: ffffffffbc4d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.379478][ T259] #1: ffffffffbbd69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.379484][ T259] #2: ffffffffbbd69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.379488][ T259] #3: ffffffffbbc89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.379492][ T259] #4: ffffffffbbc89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.379496][ T259] irq event stamp: 11804 [ 11.379497][ T259] hardirqs last enabled at (11803): [] __down_trylock_console_sem+0x86/0xa0 [ 11.379499][ T259] hardirqs last disabled at (11804): [] console_emit_next_record+0x3d4/0x4c0 [ 11.379501][ T259] softirqs last enabled at (11716): [] __alloc_skb+0x4c2/0x5f0 [ 11.379504][ T259] softirqs last disabled at (11714): [] __alloc_skb+0x4c2/0x5f0 [ 11.379507][ T259] Preemption disabled at: [ 11.379508][ T259] [] vprintk_emit+0x31b/0x3e0 [ 11.379514][ T259] CPU: 2 UID: 0 PID: 259 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.379518][ T259] Tainted: [W]=WARN [ 11.379519][ T259] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.379520][ T259] Call Trace: [ 11.379522][ T259] [ 11.379523][ T259] dump_stack_lvl+0x6f/0xa0 [ 11.379529][ T259] ? vprintk_emit+0x31b/0x3e0 [ 11.379531][ T259] __might_resched.cold+0x1fe/0x2c1 [ 11.379536][ T259] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.379542][ T259] ? __kmalloc_noprof+0xdb/0x760 [ 11.379547][ T259] __kmalloc_noprof+0x443/0x760 [ 11.379549][ T259] ? alloc_buf.isra.0+0x4b/0x260 [ 11.379555][ T259] ? do_raw_spin_unlock+0x59/0x250 [ 11.379558][ T259] alloc_buf.isra.0+0x4b/0x260 [ 11.379561][ T259] put_chars+0x1e1/0x2f0 [ 11.379564][ T259] ? __send_to_port+0x420/0x420 [ 11.379568][ T259] ? validate_chain+0x34a/0xc20 [ 11.379571][ T259] hvc_console_print+0x292/0x780 [ 11.379574][ T259] ? mark_usage+0x61/0x170 [ 11.379576][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379578][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379582][ T259] ? hvc_write+0x3a0/0x3a0 [ 11.379584][ T259] ? console_emit_next_record+0x1df/0x4c0 [ 11.379587][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379591][ T259] ? lock_acquire+0x13c/0x160 [ 11.379595][ T259] console_emit_next_record+0x22f/0x4c0 [ 11.379598][ T259] ? devkmsg_read+0x4b0/0x4b0 [ 11.379600][ T259] ? console_flush_one_record+0x106/0x710 [ 11.379603][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379605][ T259] ? lock_acquire+0x13c/0x160 [ 11.379609][ T259] console_flush_one_record+0x46f/0x710 [ 11.379613][ T259] ? console_emit_next_record+0x4c0/0x4c0 [ 11.379615][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379620][ T259] console_unlock+0xee/0x1f0 [ 11.379623][ T259] ? console_flush_one_record+0x710/0x710 [ 11.379624][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379627][ T259] ? lock_acquire+0x60/0x160 [ 11.379631][ T259] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.379633][ T259] ? vprintk_emit+0x320/0x3e0 [ 11.379636][ T259] vprintk_emit+0x37c/0x3e0 [ 11.379639][ T259] ? wake_up_klogd_work_func+0x90/0x90 [ 11.379642][ T259] ? __lock_release.isra.0+0x69/0x1a0 [ 11.379643][ T259] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.379648][ T259] ? mark_held_locks+0x40/0x70 [ 11.379651][ T259] _printk+0xc7/0x100 [ 11.379655][ T259] ? snapshot_read.cold+0x21/0x21 [ 11.379659][ T259] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.379672][ T259] ? do_setlink.isra.0+0xa31/0x2750 [ 11.379674][ T259] ? rtnl_newlink+0x9f1/0x11c0 [ 11.379675][ T259] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.379680][ T259] br_set_state+0x22f/0x430 [bridge] [ 11.379690][ T259] br_init_port+0xc4/0x200 [bridge] [ 11.379698][ T259] new_nbp+0x39c/0x580 [bridge] [ 11.379707][ T259] br_add_if+0x212/0x1320 [bridge] [ 11.379714][ T259] ? is_bpf_text_address+0x72/0x110 [ 11.379719][ T259] ? kernel_text_address+0x149/0x170 [ 11.379722][ T259] ? __kernel_text_address+0x12/0x30 [ 11.379726][ T259] do_set_master+0x357/0x580 [ 11.379730][ T259] do_setlink.isra.0+0xa31/0x2750 [ 11.379733][ T259] ? stack_trace_save+0x93/0xc0 [ 11.379736][ T259] ? rtnl_link_get_size+0x350/0x350 [ 11.379738][ T259] ? rcu_read_lock_any_held+0x66/0x90 [ 11.379740][ T259] ? stack_depot_save_flags+0x38e/0x790 [ 11.379744][ T259] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.379747][ T259] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.379748][ T259] ? validate_chain+0x38b/0xc20 [ 11.379751][ T259] ? kasan_save_stack+0x3d/0x50 [ 11.379754][ T259] ? kasan_save_stack+0x2f/0x50 [ 11.379755][ T259] ? kasan_save_track+0x14/0x30 [ 11.379758][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379760][ T259] ? netlink_seq_next+0x4e/0x60 [ 11.379763][ T259] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.379768][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 11.379770][ T259] ? rtnl_newlink+0x9a8/0x11c0 [ 11.379772][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379774][ T259] ? lock_acquire+0x13c/0x160 [ 11.379776][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379778][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379780][ T259] ? trace_contention_end+0xb3/0x180 [ 11.379784][ T259] ? __mutex_lock+0x1db/0x1ea0 [ 11.379786][ T259] ? __mutex_lock+0x9a3/0x1ea0 [ 11.379788][ T259] ? rtnl_newlink+0x9a8/0x11c0 [ 11.379791][ T259] ? ww_mutex_lock+0x160/0x160 [ 11.379792][ T259] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.379797][ T259] ? __rtnl_newlink+0x3fa/0xa50 [ 11.379802][ T259] rtnl_newlink+0x9f1/0x11c0 [ 11.379808][ T259] ? rtnl_bridge_getlink+0x850/0x850 [ 11.379810][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379814][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 11.379816][ T259] ? find_held_lock+0x2b/0x80 [ 11.379818][ T259] ? rtnl_bridge_getlink+0x850/0x850 [ 11.379820][ T259] ? __lock_release.isra.0+0x69/0x1a0 [ 11.379823][ T259] ? rtnl_bridge_getlink+0x850/0x850 [ 11.379825][ T259] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.379829][ T259] ? rtnl_link_fill+0x920/0x920 [ 11.379830][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379834][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 11.379836][ T259] ? find_held_lock+0x2b/0x80 [ 11.379839][ T259] netlink_rcv_skb+0x14e/0x3a0 [ 11.379841][ T259] ? rtnl_link_fill+0x920/0x920 [ 11.379844][ T259] ? netlink_ack+0xcf0/0xcf0 [ 11.379850][ T259] ? netlink_deliver_tap+0xc5/0x330 [ 11.379852][ T259] ? netlink_deliver_tap+0x13c/0x330 [ 11.379856][ T259] netlink_unicast+0x486/0x750 [ 11.379860][ T259] ? netlink_attachskb+0x810/0x810 [ 11.379862][ T259] ? __lock_acquire+0x518/0xc20 [ 11.379867][ T259] netlink_sendmsg+0x735/0xc60 [ 11.379870][ T259] ? netlink_unicast+0x750/0x750 [ 11.379874][ T259] ? __might_fault+0x97/0x140 [ 11.379880][ T259] ____sys_sendmsg+0x415/0x880 [ 11.379882][ T259] ? copy_msghdr_from_user+0x279/0x420 [ 11.379884][ T259] ? get_timestamp.constprop.0+0x390/0x390 [ 11.379886][ T259] ? move_addr_to_kernel+0x40/0x40 [ 11.379893][ T259] ___sys_sendmsg+0x14e/0x1d0 [ 11.379896][ T259] ? copy_msghdr_from_user+0x420/0x420 [ 11.379911][ T259] __sys_sendmsg+0x12c/0x1d0 [ 11.379914][ T259] ? __sys_sendmsg_sock+0x20/0x20 [ 11.379920][ T259] ? rcu_is_watching+0x16/0xd0 [ 11.379924][ T259] do_syscall_64+0xff/0x530 [ 11.379926][ T259] ? exc_page_fault+0xee/0x100 [ 11.379929][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.379931][ T259] RIP: 0033:0x7f3f6832b54e [ 11.379935][ T259] 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.379936][ T259] RSP: 002b:00007ffd071435a0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.379939][ T259] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f3f6832b54e [ 11.379941][ T259] RDX: 0000000000000000 RSI: 00007ffd07143650 RDI: 0000000000000005 [ 11.379942][ T259] RBP: 00007ffd071435b0 R08: 0000000000000000 R09: 0000000000000000 [ 11.379943][ T259] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9156d6 [ 11.379944][ T259] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd07143d10 [ 11.379950][ T259] [ 11.432754][ T259] br1: port 1(veth1) entered disabled state [ 11.433196][ T259] veth1: entered allmulticast mode [ 11.435364][ T259] veth1: entered promiscuous mode [ 11.443888][ T259] ip (259) used greatest stack depth: 23336 bytes left [ 11.464091][ T39] br1: port 1(veth1) entered blocking state [ 11.464515][ T39] br1: port 1(veth1) entered forwarding state [ 26.480606][ C2] [ 26.480624][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.480627][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 26.480629][ C2] preempt_count: 104, expected: 0 [ 26.480630][ C2] RCU nest depth: 0, expected: 0 [ 26.480631][ C2] INFO: lockdep is turned off. [ 26.480632][ C2] irq event stamp: 549562 [ 26.480632][ C2] hardirqs last enabled at (549562): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.480641][ C2] hardirqs last disabled at (549561): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.480643][ C2] softirqs last enabled at (549516): [] handle_softirqs+0x67c/0x900 [ 26.480647][ C2] softirqs last disabled at (549559): [] __irq_exit_rcu+0x145/0x1c0 [ 26.480648][ C2] Preemption disabled at: [ 26.480649][ C2] [<0000000000000000>] 0x0 [ 26.480656][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.480659][ C2] Tainted: [W]=WARN [ 26.480660][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.480662][ C2] Call Trace: [ 26.480663][ C2] [ 26.480665][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.480671][ C2] __might_resched.cold+0x1fe/0x2c1 [ 26.480674][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.480678][ C2] ? __asan_memcpy+0x3c/0x60 [ 26.480680][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480684][ C2] __kmalloc_noprof+0x443/0x760 [ 26.480687][ C2] ? __rwlock_init+0x150/0x150 [ 26.480690][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 26.480693][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 26.480695][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.480697][ C2] put_chars+0x1e1/0x2f0 [ 26.480699][ C2] ? __send_to_port+0x420/0x420 [ 26.480701][ C2] ? console_prepend_replay+0x20/0x20 [ 26.480704][ C2] hvc_console_print+0x292/0x780 [ 26.480707][ C2] ? hvc_write+0x3a0/0x3a0 [ 26.480708][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480710][ C2] ? lock_acquire+0x13c/0x160 [ 26.480712][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.480714][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 26.480716][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480718][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480719][ C2] ? lock_acquire+0x13c/0x160 [ 26.480721][ C2] ? console_flush_one_record+0x111/0x710 [ 26.480723][ C2] console_flush_one_record+0x46f/0x710 [ 26.480725][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 26.480728][ C2] console_unlock+0xee/0x1f0 [ 26.480729][ C2] ? lock_acquire+0x13c/0x160 [ 26.480731][ C2] ? console_flush_one_record+0x710/0x710 [ 26.480732][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480734][ C2] ? lock_release+0x184/0x1f0 [ 26.480735][ C2] ? lock_acquire+0x60/0x160 [ 26.480737][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.480739][ C2] ? vprintk_emit+0x320/0x3e0 [ 26.480741][ C2] vprintk_emit+0x37c/0x3e0 [ 26.480743][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 26.480745][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.480757][ C2] ? lock_release+0x184/0x1f0 [ 26.480759][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.480765][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.480771][ C2] ? is_module_text_address+0x154/0x250 [ 26.480775][ C2] _printk+0xc7/0x100 [ 26.480778][ C2] ? snapshot_read.cold+0x21/0x21 [ 26.480780][ C2] ? arch_stack_walk+0xd7/0x130 [ 26.480784][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.480786][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.480787][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.480790][ C2] mark_lock_irq+0x989/0x9c0 [ 26.480792][ C2] mark_lock+0x1d7/0xa00 [ 26.480794][ C2] mark_usage+0x42/0x170 [ 26.480796][ C2] __lock_acquire+0x388/0xc20 [ 26.480798][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.480800][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.480806][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480808][ C2] ? lock_acquire+0x13c/0x160 [ 26.480809][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.480815][ C2] _raw_spin_lock+0x33/0x40 [ 26.480817][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.480823][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.480829][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.480835][ C2] call_timer_fn+0x160/0x4d0 [ 26.480837][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.480839][ C2] ? debug_object_active_state+0x430/0x430 [ 26.480843][ C2] ? find_held_lock+0x2b/0x80 [ 26.480845][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.480846][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480849][ C2] __run_timers+0x68f/0xaa0 [ 26.480850][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.480857][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.480859][ C2] ? __lock_acquire+0x518/0xc20 [ 26.480861][ C2] ? __rwlock_init+0x150/0x150 [ 26.480863][ C2] run_timer_softirq+0xf0/0x160 [ 26.480865][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.480867][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.480869][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480871][ C2] handle_softirqs+0x1d3/0x900 [ 26.480873][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.480874][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.480876][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.480877][ C2] irq_exit_rcu+0xe/0x30 [ 26.480879][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.480882][ C2] [ 26.480882][ C2] [ 26.480883][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.480884][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.480887][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.480890][ C2] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 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.480891][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.480895][ C2] RAX: 00000000000862b5 RBX: ff11000001bec6c0 RCX: ffffffffb8af0307 [ 26.480896][ C2] RDX: ff11000001bec6c0 RSI: ffffffffbb838b01 RDI: ffffffffbb28d8e0 [ 26.480897][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.480898][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.480899][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.480900][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.480904][ C2] default_idle+0x9/0x10 [ 26.480905][ C2] default_idle_call+0x6e/0xb0 [ 26.480907][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.480909][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.480910][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.480912][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.480914][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.480916][ C2] do_idle+0xd8/0x190 [ 26.480917][ C2] cpu_startup_entry+0x53/0x70 [ 26.480919][ C2] start_secondary+0x204/0x2b0 [ 26.480921][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.480923][ C2] common_startup_64+0x13e/0x148 [ 26.480927][ C2] [ 26.504667][ C2] ======================================================== [ 26.504908][ C2] WARNING: possible irq lock inversion dependency detected [ 26.505114][ C2] 7.2.0-virtme #1 Tainted: G W [ 26.505350][ C2] -------------------------------------------------------- [ 26.505599][ C2] swapper/2/0 just changed the state of lock: [ 26.505767][ C2] ff1100000bcbae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.506099][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.506345][ C2] (fs_reclaim){+.+.}-{0:0} [ 26.506348][ C2] [ 26.506348][ C2] [ 26.506348][ C2] and interrupts could create inverse lock ordering between them. [ 26.506348][ C2] [ 26.506928][ C2] [ 26.506928][ C2] other info that might help us debug this: [ 26.507183][ C2] Chain exists of: [ 26.507183][ C2] &br->lock --> console_owner --> fs_reclaim [ 26.507183][ C2] [ 26.507543][ C2] Possible interrupt unsafe locking scenario: [ 26.507543][ C2] [ 26.507790][ C2] CPU0 CPU1 [ 26.507925][ C2] ---- ---- [ 26.508113][ C2] lock(fs_reclaim); [ 26.508220][ C2] local_irq_disable(); [ 26.508440][ C2] lock(&br->lock); [ 26.508612][ C2] lock(console_owner); [ 26.508826][ C2] [ 26.508929][ C2] lock(&br->lock); [ 26.509079][ C2] [ 26.509079][ C2] *** DEADLOCK *** [ 26.509079][ C2] [ 26.509287][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 26.509506][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.509828][ C2] [ 26.509828][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 26.510111][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 26.510252][ C2] HARDIRQ-ON-W at: [ 26.510410][ C2] __lock_acquire+0x388/0xc20 [ 26.510590][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.510807][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.511023][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.511235][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.511492][ C2] workqueue_init+0x2a/0x680 [ 26.511712][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.511918][ C2] kernel_init+0x21/0x150 [ 26.512139][ C2] ret_from_fork+0x474/0x6b0 [ 26.512395][ C2] ret_from_fork_asm+0x11/0x20 [ 26.512568][ C2] SOFTIRQ-ON-W at: [ 26.512715][ C2] __lock_acquire+0x388/0xc20 [ 26.512884][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.513107][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.513324][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.513532][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.513783][ C2] workqueue_init+0x2a/0x680 [ 26.514002][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.514211][ C2] kernel_init+0x21/0x150 [ 26.514429][ C2] ret_from_fork+0x474/0x6b0 [ 26.514646][ C2] ret_from_fork_asm+0x11/0x20 [ 26.514819][ C2] INITIAL USE at: [ 26.514965][ C2] __lock_acquire+0x388/0xc20 [ 26.515141][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.515359][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.515570][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.515777][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.516021][ C2] workqueue_init+0x2a/0x680 [ 26.516238][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.516411][ C2] kernel_init+0x21/0x150 [ 26.516626][ C2] ret_from_fork+0x474/0x6b0 [ 26.516842][ C2] ret_from_fork_asm+0x11/0x20 [ 26.517009][ C2] } [ 26.517090][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.517340][ C2] ... acquired at: [ 26.517486][ C2] __lock_acquire+0x518/0xc20 [ 26.517622][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.517800][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.517936][ C2] __kmalloc_noprof+0xd3/0x760 [ 26.518116][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.518250][ C2] put_chars+0x1e1/0x2f0 [ 26.518435][ C2] hvc_console_print+0x292/0x780 [ 26.518570][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.518748][ C2] console_flush_one_record+0x46f/0x710 [ 26.518885][ C2] console_unlock+0xee/0x1f0 [ 26.519066][ C2] vprintk_emit+0x37c/0x3e0 [ 26.519206][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 26.519388][ C2] dev_printk_emit+0xb9/0xee [ 26.519524][ C2] _dev_info+0xe2/0x116 [ 26.519628][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 26.519839][ C2] cmos_do_probe+0x73b/0x98a [ 26.520071][ C2] platform_probe+0xfe/0x1f0 [ 26.520212][ C2] call_driver_probe+0x61/0x1c0 [ 26.520394][ C2] really_probe+0x199/0x760 [ 26.520524][ C2] __driver_probe_device+0x24f/0x440 [ 26.520699][ C2] driver_probe_device+0x4a/0xf0 [ 26.520829][ C2] __driver_attach+0x1b8/0x540 [ 26.521002][ C2] bus_for_each_dev+0x130/0x1e0 [ 26.521141][ C2] bus_add_driver+0x2c8/0x530 [ 26.521315][ C2] driver_register+0x1a3/0x390 [ 26.521449][ C2] __platform_driver_probe+0x13f/0x270 [ 26.521626][ C2] cmos_init+0x31/0x40 [ 26.521725][ C2] do_one_initcall+0x124/0x4f0 [ 26.521854][ C2] kernel_init_freeable+0x596/0x630 [ 26.522025][ C2] kernel_init+0x21/0x150 [ 26.522161][ C2] ret_from_fork+0x474/0x6b0 [ 26.522356][ C2] ret_from_fork_asm+0x11/0x20 [ 26.522486][ C2] [ 26.522598][ C2] -> (console_owner){....}-{0:0} { [ 26.522739][ C2] INITIAL USE at: [ 26.522881][ C2] } [ 26.522947][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.523190][ C2] ... acquired at: [ 26.523285][ C2] __lock_acquire+0x518/0xc20 [ 26.523437][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.523648][ C2] console_lock_spinning_enable+0x5c/0x60 [ 26.523906][ C2] console_emit_next_record+0x1d1/0x4c0 [ 26.524068][ C2] console_flush_one_record+0x46f/0x710 [ 26.524258][ C2] console_unlock+0xee/0x1f0 [ 26.524408][ C2] vprintk_emit+0x37c/0x3e0 [ 26.524611][ C2] _printk+0xc7/0x100 [ 26.524729][ C2] br_set_state+0x22f/0x430 [bridge] [ 26.524912][ C2] br_init_port+0xc4/0x200 [bridge] [ 26.525143][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 26.525359][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 26.525524][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 26.525705][ C2] notifier_call_chain+0xae/0x300 [ 26.525835][ C2] netif_state_change+0x139/0x340 [ 26.526010][ C2] __linkwatch_run_queue+0x34c/0x750 [ 26.526151][ C2] linkwatch_event+0x7f/0xb0 [ 26.526329][ C2] process_one_work+0xe3e/0x1560 [ 26.526486][ C2] worker_thread+0x4f1/0xd60 [ 26.526689][ C2] kthread+0x367/0x460 [ 26.526793][ C2] ret_from_fork+0x474/0x6b0 [ 26.526984][ C2] ret_from_fork_asm+0x11/0x20 [ 26.527155][ C2] [ 26.527221][ C2] -> (&br->lock){+.-.}-{3:3} { [ 26.527403][ C2] HARDIRQ-ON-W at: [ 26.527502][ C2] __lock_acquire+0x388/0xc20 [ 26.527711][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.527876][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.528090][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 26.528342][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.528586][ C2] br_changelink+0x575/0x16e0 [bridge] [ 26.528754][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.528990][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.529180][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.529394][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.529605][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.529809][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.530073][ C2] netlink_unicast+0x486/0x750 [ 26.530359][ C2] netlink_sendmsg+0x735/0xc60 [ 26.530569][ C2] ____sys_sendmsg+0x415/0x880 [ 26.530783][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.530994][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.531294][ C2] do_syscall_64+0xff/0x530 [ 26.531531][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.531792][ C2] IN-SOFTIRQ-W at: [ 26.531974][ C2] __lock_acquire+0x388/0xc20 [ 26.532153][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.532400][ C2] _raw_spin_lock+0x33/0x40 [ 26.532667][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.532884][ C2] call_timer_fn+0x160/0x4d0 [ 26.533153][ C2] __run_timers+0x68f/0xaa0 [ 26.533424][ C2] run_timer_softirq+0xf0/0x160 [ 26.533623][ C2] handle_softirqs+0x1d3/0x900 [ 26.533869][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.534093][ C2] irq_exit_rcu+0xe/0x30 [ 26.534290][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.534600][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.534860][ C2] pv_native_safe_halt+0xf/0x10 [ 26.535068][ C2] default_idle+0x9/0x10 [ 26.535289][ C2] default_idle_call+0x6e/0xb0 [ 26.535504][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.535708][ C2] do_idle+0xd8/0x190 [ 26.535918][ C2] cpu_startup_entry+0x53/0x70 [ 26.536095][ C2] start_secondary+0x204/0x2b0 [ 26.536344][ C2] common_startup_64+0x13e/0x148 [ 26.536590][ C2] INITIAL USE at: [ 26.536692][ C2] __lock_acquire+0x388/0xc20 [ 26.536937][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.537163][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.537382][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 26.537668][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.537872][ C2] br_changelink+0x575/0x16e0 [bridge] [ 26.538094][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.538310][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.538503][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.538712][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.538944][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.539119][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.539337][ C2] netlink_unicast+0x486/0x750 [ 26.539505][ C2] netlink_sendmsg+0x735/0xc60 [ 26.539736][ C2] ____sys_sendmsg+0x415/0x880 [ 26.539952][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.540127][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.540339][ C2] do_syscall_64+0xff/0x530 [ 26.540549][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.540748][ C2] } [ 26.540859][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.541056][ C2] ... acquired at: [ 26.541201][ C2] mark_lock+0x1d7/0xa00 [ 26.541331][ C2] mark_usage+0x42/0x170 [ 26.541506][ C2] __lock_acquire+0x388/0xc20 [ 26.541635][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.541808][ C2] _raw_spin_lock+0x33/0x40 [ 26.541937][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.542152][ C2] call_timer_fn+0x160/0x4d0 [ 26.542285][ C2] __run_timers+0x68f/0xaa0 [ 26.542462][ C2] run_timer_softirq+0xf0/0x160 [ 26.542594][ C2] handle_softirqs+0x1d3/0x900 [ 26.542766][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.542896][ C2] irq_exit_rcu+0xe/0x30 [ 26.543070][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.543239][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.543452][ C2] pv_native_safe_halt+0xf/0x10 [ 26.543628][ C2] default_idle+0x9/0x10 [ 26.543759][ C2] default_idle_call+0x6e/0xb0 [ 26.543931][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.544098][ C2] do_idle+0xd8/0x190 [ 26.544239][ C2] cpu_startup_entry+0x53/0x70 [ 26.544373][ C2] start_secondary+0x204/0x2b0 [ 26.544550][ C2] common_startup_64+0x13e/0x148 [ 26.544683][ C2] [ 26.544747][ C2] [ 26.544747][ C2] stack backtrace: [ 26.544952][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.544955][ C2] Tainted: [W]=WARN [ 26.544956][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.544957][ C2] Call Trace: [ 26.544958][ C2] [ 26.544959][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.544963][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.544965][ C2] mark_lock_irq+0x989/0x9c0 [ 26.544968][ C2] mark_lock+0x1d7/0xa00 [ 26.544970][ C2] mark_usage+0x42/0x170 [ 26.544971][ C2] __lock_acquire+0x388/0xc20 [ 26.544973][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.544975][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.544982][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.544985][ C2] ? lock_acquire+0x13c/0x160 [ 26.544987][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.544993][ C2] _raw_spin_lock+0x33/0x40 [ 26.544995][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.545001][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.545008][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.545014][ C2] call_timer_fn+0x160/0x4d0 [ 26.545016][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.545018][ C2] ? debug_object_active_state+0x430/0x430 [ 26.545020][ C2] ? find_held_lock+0x2b/0x80 [ 26.545022][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.545024][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.545026][ C2] __run_timers+0x68f/0xaa0 [ 26.545028][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.545035][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.545036][ C2] ? __lock_acquire+0x518/0xc20 [ 26.545039][ C2] ? __rwlock_init+0x150/0x150 [ 26.545041][ C2] run_timer_softirq+0xf0/0x160 [ 26.545043][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.545045][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.545047][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.545048][ C2] handle_softirqs+0x1d3/0x900 [ 26.545050][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.545052][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.545053][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.545055][ C2] irq_exit_rcu+0xe/0x30 [ 26.545056][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.545058][ C2] [ 26.545058][ C2] [ 26.545059][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.545061][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.545062][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.545064][ C2] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 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.545065][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.545067][ C2] RAX: 00000000000862b5 RBX: ff11000001bec6c0 RCX: ffffffffb8af0307 [ 26.545068][ C2] RDX: ff11000001bec6c0 RSI: ffffffffbb838b01 RDI: ffffffffbb28d8e0 [ 26.545069][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.545070][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.545070][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.545072][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.545074][ C2] default_idle+0x9/0x10 [ 26.545076][ C2] default_idle_call+0x6e/0xb0 [ 26.545077][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.545079][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.545080][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.545087][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.545088][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.545090][ C2] do_idle+0xd8/0x190 [ 26.545091][ C2] cpu_startup_entry+0x53/0x70 [ 26.545093][ C2] start_secondary+0x204/0x2b0 [ 26.545094][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.545096][ C2] common_startup_64+0x13e/0x148 [ 26.545099][ C2] [ 38.035329][ T444] br1: port 1(veth1) entered disabled state [ 38.035346][ T444] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 38.035349][ T444] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 444, name: ip [ 38.035350][ T444] preempt_count: 202, expected: 0 [ 38.035351][ T444] RCU nest depth: 0, expected: 0 [ 38.035352][ T444] INFO: lockdep is turned off. [ 38.035353][ T444] irq event stamp: 0 [ 38.035354][ T444] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 38.035359][ T444] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 38.035365][ T444] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 38.035367][ T444] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 38.035369][ T444] Preemption disabled at: [ 38.035369][ T444] [<0000000000000000>] 0x0 [ 38.035374][ T444] CPU: 0 UID: 0 PID: 444 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 38.035378][ T444] Tainted: [W]=WARN [ 38.035379][ T444] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 38.035380][ T444] Call Trace: [ 38.035382][ T444] [ 38.035383][ T444] dump_stack_lvl+0x6f/0xa0 [ 38.035389][ T444] __might_resched.cold+0x1fe/0x2c1 [ 38.035393][ T444] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 38.035397][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035401][ T444] __kmalloc_noprof+0x443/0x760 [ 38.035404][ T444] ? __rwlock_init+0x150/0x150 [ 38.035407][ T444] ? alloc_buf.isra.0+0x4b/0x260 [ 38.035411][ T444] ? do_raw_spin_unlock+0x59/0x250 [ 38.035413][ T444] alloc_buf.isra.0+0x4b/0x260 [ 38.035415][ T444] put_chars+0x1e1/0x2f0 [ 38.035417][ T444] ? __send_to_port+0x420/0x420 [ 38.035419][ T444] ? printk_get_next_message+0x240/0x650 [ 38.035422][ T444] hvc_console_print+0x292/0x780 [ 38.035425][ T444] ? hvc_write+0x3a0/0x3a0 [ 38.035427][ T444] ? __rwlock_init+0x150/0x150 [ 38.035429][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035431][ T444] ? lock_acquire+0x13c/0x160 [ 38.035433][ T444] console_emit_next_record+0x22f/0x4c0 [ 38.035436][ T444] ? devkmsg_read+0x4b0/0x4b0 [ 38.035438][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035439][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035441][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035443][ T444] ? lock_acquire+0x13c/0x160 [ 38.035445][ T444] ? console_flush_one_record+0x111/0x710 [ 38.035446][ T444] console_flush_one_record+0x46f/0x710 [ 38.035449][ T444] ? console_emit_next_record+0x4c0/0x4c0 [ 38.035451][ T444] console_unlock+0xee/0x1f0 [ 38.035453][ T444] ? lock_acquire+0x13c/0x160 [ 38.035455][ T444] ? console_flush_one_record+0x710/0x710 [ 38.035456][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035458][ T444] ? lock_release+0x184/0x1f0 [ 38.035459][ T444] ? lock_acquire+0x60/0x160 [ 38.035461][ T444] ? __rcu_read_lock+0x30/0x70 [ 38.035463][ T444] ? __down_trylock_console_sem+0x5e/0xa0 [ 38.035465][ T444] ? vprintk_emit+0x320/0x3e0 [ 38.035467][ T444] vprintk_emit+0x37c/0x3e0 [ 38.035470][ T444] ? wake_up_klogd_work_func+0x90/0x90 [ 38.035472][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035474][ T444] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.035476][ T444] ? trace_hardirqs_on+0x36/0x40 [ 38.035478][ T444] _printk+0xc7/0x100 [ 38.035481][ T444] ? snapshot_read.cold+0x21/0x21 [ 38.035483][ T444] ? ipv6_mc_down+0x17e/0x2d0 [ 38.035487][ T444] br_set_state+0x22f/0x430 [bridge] [ 38.035501][ T444] br_stp_disable_port+0x11f/0x390 [bridge] [ 38.035509][ T444] br_device_event+0x7b5/0x8f0 [bridge] [ 38.035518][ T444] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 38.035522][ T444] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 38.035526][ T444] ? addrconf_notify+0x2c8/0xf30 [ 38.035531][ T444] notifier_call_chain+0xae/0x300 [ 38.035535][ T444] __dev_notify_flags+0x23c/0x3c0 [ 38.035542][ T444] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.035545][ T444] ? netif_change_name+0x8c0/0x8c0 [ 38.035548][ T444] ? netif_set_allmulti+0x250/0x250 [ 38.035551][ T444] ? __kernel_text_address+0x12/0x30 [ 38.035554][ T444] ? write_profile+0xf0/0xf0 [ 38.035557][ T444] ? unwind_get_return_address+0x67/0xd0 [ 38.035561][ T444] netif_change_flags+0xfe/0x190 [ 38.035564][ T444] do_setlink.isra.0+0x1cc3/0x2750 [ 38.035567][ T444] ? stack_trace_save+0x93/0xc0 [ 38.035569][ T444] ? rtnl_link_get_size+0x350/0x350 [ 38.035570][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035572][ T444] ? stack_depot_save_flags+0x1bc/0x790 [ 38.035576][ T444] ? kasan_save_stack+0x3d/0x50 [ 38.035579][ T444] ? kasan_save_stack+0x2f/0x50 [ 38.035580][ T444] ? kasan_save_track+0x14/0x30 [ 38.035582][ T444] ? __kasan_kmalloc+0x7b/0x90 [ 38.035584][ T444] ? __kmalloc_cache_noprof+0x29a/0x620 [ 38.035585][ T444] ? rtnl_newlink+0x124/0x11c0 [ 38.035586][ T444] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.035588][ T444] ? netlink_rcv_skb+0x14e/0x3a0 [ 38.035590][ T444] ? netlink_unicast+0x486/0x750 [ 38.035592][ T444] ? netlink_sendmsg+0x735/0xc60 [ 38.035593][ T444] ? ____sys_sendmsg+0x415/0x880 [ 38.035595][ T444] ? ___sys_sendmsg+0x14e/0x1d0 [ 38.035597][ T444] ? __sys_sendmsg+0x12c/0x1d0 [ 38.035598][ T444] ? do_syscall_64+0xff/0x530 [ 38.035601][ T444] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.035603][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035605][ T444] ? lock_acquire+0x13c/0x160 [ 38.035606][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035608][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035610][ T444] ? trace_contention_end+0xb3/0x180 [ 38.035613][ T444] ? __mutex_lock+0x1db/0x1ea0 [ 38.035614][ T444] ? __mutex_lock+0x9a3/0x1ea0 [ 38.035616][ T444] ? rtnl_newlink+0x9a8/0x11c0 [ 38.035617][ T444] ? ww_mutex_lock+0x160/0x160 [ 38.035619][ T444] ? __rtnl_newlink+0x3fa/0xa50 [ 38.035621][ T444] rtnl_newlink+0x9f1/0x11c0 [ 38.035623][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035625][ T444] ? rtnl_bridge_getlink+0x850/0x850 [ 38.035626][ T444] ? lock_acquire+0x13c/0x160 [ 38.035628][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035630][ T444] ? is_bpf_text_address+0x3e/0x110 [ 38.035633][ T444] ? is_bpf_text_address+0x72/0x110 [ 38.035635][ T444] ? kernel_text_address+0x149/0x170 [ 38.035636][ T444] ? __kernel_text_address+0x12/0x30 [ 38.035637][ T444] ? write_profile+0xf0/0xf0 [ 38.035639][ T444] ? unwind_get_return_address+0x67/0xd0 [ 38.035640][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035642][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035643][ T444] ? cap_capable+0x1d7/0x3d0 [ 38.035647][ T444] ? lock_acquire+0x13c/0x160 [ 38.035648][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035650][ T444] ? rtnl_bridge_getlink+0x850/0x850 [ 38.035651][ T444] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.035653][ T444] ? rtnl_link_fill+0x920/0x920 [ 38.035654][ T444] ? filter_irq_stacks+0xd0/0xd0 [ 38.035656][ T444] ? stack_depot_save_flags+0x1bc/0x790 [ 38.035658][ T444] netlink_rcv_skb+0x14e/0x3a0 [ 38.035660][ T444] ? rtnl_link_fill+0x920/0x920 [ 38.035661][ T444] ? lock_release+0x184/0x1f0 [ 38.035663][ T444] ? netlink_ack+0xcf0/0xcf0 [ 38.035664][ T444] ? __netlink_lookup+0xb9/0x6b0 [ 38.035667][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035668][ T444] ? lock_release+0x184/0x1f0 [ 38.035670][ T444] netlink_unicast+0x486/0x750 [ 38.035672][ T444] ? netlink_attachskb+0x810/0x810 [ 38.035674][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035675][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035677][ T444] ? lock_acquire+0x13c/0x160 [ 38.035679][ T444] netlink_sendmsg+0x735/0xc60 [ 38.035681][ T444] ? netlink_unicast+0x750/0x750 [ 38.035683][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035685][ T444] ? lock_release+0x184/0x1f0 [ 38.035686][ T444] ____sys_sendmsg+0x415/0x880 [ 38.035688][ T444] ? copy_msghdr_from_user+0x279/0x420 [ 38.035690][ T444] ? get_timestamp.constprop.0+0x390/0x390 [ 38.035691][ T444] ? move_addr_to_kernel+0x40/0x40 [ 38.035693][ T444] ? filter_irq_stacks+0xd0/0xd0 [ 38.035695][ T444] ___sys_sendmsg+0x14e/0x1d0 [ 38.035697][ T444] ? copy_msghdr_from_user+0x420/0x420 [ 38.035702][ T444] __sys_sendmsg+0x12c/0x1d0 [ 38.035704][ T444] ? __sys_sendmsg_sock+0x20/0x20 [ 38.035707][ T444] ? rcu_is_watching+0x16/0xd0 [ 38.035709][ T444] do_syscall_64+0xff/0x530 [ 38.035710][ T444] ? exc_page_fault+0xee/0x100 [ 38.035712][ T444] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.035714][ T444] RIP: 0033:0x7f3a3eacd54e [ 38.035716][ T444] 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 [ 38.035718][ T444] RSP: 002b:00007ffc972cd050 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 38.035722][ T444] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f3a3eacd54e [ 38.035723][ T444] RDX: 0000000000000000 RSI: 00007ffc972cd100 RDI: 0000000000000005 [ 38.035724][ T444] RBP: 00007ffc972cd060 R08: 0000000000000000 R09: 0000000000000000 [ 38.035725][ T444] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9156f1 [ 38.035726][ T444] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc972cd7c0 [ 38.035728][ T444] [ 38.070893][ T444] ip (444) used greatest stack depth: 23016 bytes left [ 38.088489][ T445] veth1: left allmulticast mode [ 38.088881][ T445] veth1: left promiscuous mode [ 38.089084][ T445] br1: port 1(veth1) entered disabled state