[ 11.526197][ T241] 8021q: 802.1Q VLAN Support v1.8 [ 11.526217][ T241] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.526219][ T241] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 241, name: modprobe [ 11.526221][ T241] preempt_count: 1, expected: 0 [ 11.526222][ T241] RCU nest depth: 0, expected: 0 [ 11.526223][ T241] locks held by modprobe/241: 4, last CPU#3: [ 11.526226][ T241] #0: ffffffff95b69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.526239][ T241] #1: ffffffff95b69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.526243][ T241] #2: ffffffff95a89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.526247][ T241] #3: ffffffff95a89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.526251][ T241] irq event stamp: 30088 [ 11.526251][ T241] hardirqs last enabled at (30087): [] __down_trylock_console_sem+0x86/0xa0 [ 11.526254][ T241] hardirqs last disabled at (30088): [] console_emit_next_record+0x3d4/0x4c0 [ 11.526256][ T241] softirqs last enabled at (27228): [] handle_softirqs+0x67c/0x900 [ 11.526259][ T241] softirqs last disabled at (27221): [] __irq_exit_rcu+0x145/0x1c0 [ 11.526261][ T241] Preemption disabled at: [ 11.526262][ T241] [] vprintk_emit+0x31b/0x3e0 [ 11.526268][ T241] CPU: 3 UID: 0 PID: 241 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.526271][ T241] Tainted: [W]=WARN [ 11.526272][ T241] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.526274][ T241] Call Trace: [ 11.526275][ T241] [ 11.526277][ T241] dump_stack_lvl+0x6f/0xa0 [ 11.526283][ T241] ? vprintk_emit+0x31b/0x3e0 [ 11.526285][ T241] __might_resched.cold+0x1fe/0x2c1 [ 11.526290][ T241] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.526294][ T241] ? __kmalloc_noprof+0xdb/0x760 [ 11.526300][ T241] __kmalloc_noprof+0x443/0x760 [ 11.526302][ T241] ? alloc_buf.isra.0+0x4b/0x260 [ 11.526309][ T241] ? do_raw_spin_unlock+0x59/0x250 [ 11.526312][ T241] alloc_buf.isra.0+0x4b/0x260 [ 11.526315][ T241] put_chars+0x1e1/0x2f0 [ 11.526318][ T241] ? __send_to_port+0x420/0x420 [ 11.526320][ T241] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.526325][ T241] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.526327][ T241] ? validate_chain+0x38b/0xc20 [ 11.526333][ T241] hvc_console_print+0x292/0x780 [ 11.526337][ T241] ? __lock_acquire+0x518/0xc20 [ 11.526343][ T241] ? hvc_write+0x3a0/0x3a0 [ 11.526346][ T241] ? rcu_is_watching+0x16/0xd0 [ 11.526352][ T241] console_emit_next_record+0x22f/0x4c0 [ 11.526357][ T241] ? devkmsg_read+0x4b0/0x4b0 [ 11.526359][ T241] ? console_flush_one_record+0x106/0x710 [ 11.526362][ T241] ? rcu_is_watching+0x16/0xd0 [ 11.526365][ T241] ? lock_acquire+0x13c/0x160 [ 11.526369][ T241] console_flush_one_record+0x46f/0x710 [ 11.526374][ T241] ? console_emit_next_record+0x4c0/0x4c0 [ 11.526376][ T241] ? __lock_acquire+0x518/0xc20 [ 11.526382][ T241] console_unlock+0xee/0x1f0 [ 11.526385][ T241] ? console_flush_one_record+0x710/0x710 [ 11.526387][ T241] ? rcu_is_watching+0x16/0xd0 [ 11.526390][ T241] ? lock_acquire+0x60/0x160 [ 11.526394][ T241] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.526396][ T241] ? vprintk_emit+0x320/0x3e0 [ 11.526399][ T241] vprintk_emit+0x37c/0x3e0 [ 11.526405][ T241] ? wake_up_klogd_work_func+0x90/0x90 [ 11.526412][ T241] ? vlan_netlink_fini+0x960/0x960 [8021q] [ 11.526415][ T241] _printk+0xc7/0x100 [ 11.526419][ T241] ? snapshot_read.cold+0x21/0x21 [ 11.526427][ T241] ? do_one_initcall+0x113/0x4f0 [ 11.526430][ T241] vlan_proto_init+0x24/0x90 [8021q] [ 11.526434][ T241] do_one_initcall+0x124/0x4f0 [ 11.526437][ T241] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 11.526442][ T241] ? do_init_module+0xae/0x8a0 [ 11.526446][ T241] ? kasan_unpoison+0x44/0x70 [ 11.526451][ T241] do_init_module+0x2ad/0x8a0 [ 11.526455][ T241] ? lockdep_hardirqs_on+0x91/0x130 [ 11.526460][ T241] ? free_module+0x470/0x470 [ 11.526463][ T241] ? kfree+0x123/0x590 [ 11.526468][ T241] load_module+0x1424/0x1bb0 [ 11.526477][ T241] ? layout_and_allocate.isra.0+0x680/0x680 [ 11.526481][ T241] ? __ia32_sys_fsconfig+0x180/0x180 [ 11.526485][ T241] ? find_held_lock+0x2b/0x80 [ 11.526488][ T241] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.526492][ T241] init_module_from_file+0x11f/0x1a0 [ 11.526496][ T241] ? __do_sys_init_module+0x220/0x220 [ 11.526506][ T241] ? __rwlock_init+0x150/0x150 [ 11.526510][ T241] ? do_raw_spin_unlock+0x59/0x250 [ 11.526514][ T241] idempotent_init_module+0x37b/0x7c0 [ 11.526517][ T241] ? init_module_from_file+0x1a0/0x1a0 [ 11.526525][ T241] ? rcu_is_watching+0x16/0xd0 [ 11.526528][ T241] ? cap_capable+0x1d7/0x3d0 [ 11.526535][ T241] __x64_sys_finit_module+0xdf/0x190 [ 11.526537][ T241] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.526541][ T241] do_syscall_64+0xff/0x530 [ 11.526543][ T241] ? exc_page_fault+0xee/0x100 [ 11.526546][ T241] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.526548][ T241] RIP: 0033:0x7fcd2ff6a37d [ 11.526552][ T241] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 11.526554][ T241] RSP: 002b:00007ffc533f4018 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 11.526557][ T241] RAX: ffffffffffffffda RBX: 0000561da2186810 RCX: 00007fcd2ff6a37d [ 11.526558][ T241] RDX: 0000000000000000 RSI: 0000561d9c9d0ffd RDI: 0000000000000000 [ 11.526559][ T241] RBP: 00007ffc533f40b0 R08: 0000000000000000 R09: 0000000000000000 [ 11.526560][ T241] R10: 0000000000000000 R11: 0000000000000246 R12: 0000561d9c9d0ffd [ 11.526560][ T241] R13: 0000561da2186960 R14: 0000000000040000 R15: 0000000000000000 [ 11.526569][ T241] [ 13.785310][ T371] br10: port 1(veth1.10) entered blocking state [ 13.786428][ T371] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.786430][ T371] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 371, name: ip [ 13.786432][ T371] preempt_count: 1, expected: 0 [ 13.786433][ T371] RCU nest depth: 0, expected: 0 [ 13.786434][ T371] locks held by ip/371: 5, last CPU#3: [ 13.786436][ T371] #0: ffffffff962d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.786447][ T371] #1: ffffffff95b69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.786453][ T371] #2: ffffffff95b69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.786457][ T371] #3: ffffffff95a89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.786461][ T371] #4: ffffffff95a89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.786465][ T371] irq event stamp: 15614 [ 13.786466][ T371] hardirqs last enabled at (15613): [] irqentry_exit+0x21c/0x790 [ 13.786469][ T371] hardirqs last disabled at (15614): [] console_emit_next_record+0x3d4/0x4c0 [ 13.786471][ T371] softirqs last enabled at (15612): [] handle_softirqs+0x67c/0x900 [ 13.786474][ T371] softirqs last disabled at (14145): [] __irq_exit_rcu+0x145/0x1c0 [ 13.786477][ T371] Preemption disabled at: [ 13.786477][ T371] [] vprintk_emit+0x31b/0x3e0 [ 13.786483][ T371] CPU: 3 UID: 0 PID: 371 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.786486][ T371] Tainted: [W]=WARN [ 13.786487][ T371] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.786489][ T371] Call Trace: [ 13.786491][ T371] [ 13.786492][ T371] dump_stack_lvl+0x6f/0xa0 [ 13.786498][ T371] ? vprintk_emit+0x31b/0x3e0 [ 13.786500][ T371] __might_resched.cold+0x1fe/0x2c1 [ 13.786505][ T371] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.786510][ T371] ? __kmalloc_noprof+0xdb/0x760 [ 13.786515][ T371] __kmalloc_noprof+0x443/0x760 [ 13.786518][ T371] ? alloc_buf.isra.0+0x4b/0x260 [ 13.786524][ T371] ? do_raw_spin_unlock+0x59/0x250 [ 13.786527][ T371] alloc_buf.isra.0+0x4b/0x260 [ 13.786530][ T371] put_chars+0x1e1/0x2f0 [ 13.786533][ T371] ? __send_to_port+0x420/0x420 [ 13.786538][ T371] ? validate_chain+0x34a/0xc20 [ 13.786542][ T371] hvc_console_print+0x292/0x780 [ 13.786545][ T371] ? mark_usage+0x61/0x170 [ 13.786547][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786549][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786553][ T371] ? hvc_write+0x3a0/0x3a0 [ 13.786556][ T371] ? console_emit_next_record+0x1df/0x4c0 [ 13.786559][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786563][ T371] ? lock_acquire+0x13c/0x160 [ 13.786568][ T371] console_emit_next_record+0x22f/0x4c0 [ 13.786572][ T371] ? devkmsg_read+0x4b0/0x4b0 [ 13.786573][ T371] ? console_flush_one_record+0x106/0x710 [ 13.786577][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786579][ T371] ? lock_acquire+0x13c/0x160 [ 13.786584][ T371] console_flush_one_record+0x46f/0x710 [ 13.786588][ T371] ? console_emit_next_record+0x4c0/0x4c0 [ 13.786590][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786596][ T371] console_unlock+0xee/0x1f0 [ 13.786599][ T371] ? console_flush_one_record+0x710/0x710 [ 13.786601][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786603][ T371] ? lock_acquire+0x60/0x160 [ 13.786608][ T371] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.786609][ T371] ? vprintk_emit+0x320/0x3e0 [ 13.786613][ T371] vprintk_emit+0x37c/0x3e0 [ 13.786617][ T371] ? wake_up_klogd_work_func+0x90/0x90 [ 13.786620][ T371] ? __lock_release.isra.0+0x69/0x1a0 [ 13.786621][ T371] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.786628][ T371] _printk+0xc7/0x100 [ 13.786631][ T371] ? snapshot_read.cold+0x21/0x21 [ 13.786635][ T371] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.786648][ T371] ? do_setlink.isra.0+0xa31/0x2750 [ 13.786649][ T371] ? rtnl_newlink+0x9f1/0x11c0 [ 13.786650][ T371] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.786656][ T371] br_set_state+0x22f/0x430 [bridge] [ 13.786666][ T371] br_init_port+0xc4/0x200 [bridge] [ 13.786674][ T371] new_nbp+0x39c/0x580 [bridge] [ 13.786683][ T371] br_add_if+0x212/0x1320 [bridge] [ 13.786690][ T371] ? is_bpf_text_address+0x72/0x110 [ 13.786694][ T371] ? kernel_text_address+0x149/0x170 [ 13.786698][ T371] ? __kernel_text_address+0x12/0x30 [ 13.786702][ T371] do_set_master+0x357/0x580 [ 13.786707][ T371] do_setlink.isra.0+0xa31/0x2750 [ 13.786711][ T371] ? stack_trace_save+0x93/0xc0 [ 13.786714][ T371] ? rtnl_link_get_size+0x350/0x350 [ 13.786715][ T371] ? rcu_read_lock_any_held+0x66/0x90 [ 13.786718][ T371] ? stack_depot_save_flags+0x38e/0x790 [ 13.786722][ T371] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.786724][ T371] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.786726][ T371] ? validate_chain+0x38b/0xc20 [ 13.786729][ T371] ? kasan_save_stack+0x3d/0x50 [ 13.786732][ T371] ? kasan_save_stack+0x2f/0x50 [ 13.786734][ T371] ? kasan_save_track+0x14/0x30 [ 13.786737][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786739][ T371] ? netlink_seq_next+0xe/0x60 [ 13.786742][ T371] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.786747][ T371] ? lock_acquire.part.0+0xd4/0x280 [ 13.786749][ T371] ? rtnl_newlink+0x9a8/0x11c0 [ 13.786752][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786754][ T371] ? lock_acquire+0x13c/0x160 [ 13.786756][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786758][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786760][ T371] ? trace_contention_end+0xb3/0x180 [ 13.786764][ T371] ? __mutex_lock+0x1db/0x1ea0 [ 13.786766][ T371] ? __mutex_lock+0x9a3/0x1ea0 [ 13.786768][ T371] ? rtnl_newlink+0x9a8/0x11c0 [ 13.786772][ T371] ? ww_mutex_lock+0x160/0x160 [ 13.786774][ T371] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.786780][ T371] ? __rtnl_newlink+0x3fa/0xa50 [ 13.786786][ T371] rtnl_newlink+0x9f1/0x11c0 [ 13.786792][ T371] ? rtnl_bridge_getlink+0x850/0x850 [ 13.786794][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786799][ T371] ? lock_acquire.part.0+0xd4/0x280 [ 13.786801][ T371] ? find_held_lock+0x2b/0x80 [ 13.786803][ T371] ? rtnl_bridge_getlink+0x850/0x850 [ 13.786811][ T371] ? __lock_release.isra.0+0x69/0x1a0 [ 13.786815][ T371] ? rtnl_bridge_getlink+0x850/0x850 [ 13.786817][ T371] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.786821][ T371] ? rtnl_link_fill+0x920/0x920 [ 13.786822][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786827][ T371] ? lock_acquire.part.0+0xd4/0x280 [ 13.786829][ T371] ? find_held_lock+0x2b/0x80 [ 13.786833][ T371] netlink_rcv_skb+0x14e/0x3a0 [ 13.786835][ T371] ? rtnl_link_fill+0x920/0x920 [ 13.786838][ T371] ? netlink_ack+0xcf0/0xcf0 [ 13.786846][ T371] ? netlink_deliver_tap+0xc5/0x330 [ 13.786847][ T371] ? netlink_deliver_tap+0x13c/0x330 [ 13.786852][ T371] netlink_unicast+0x486/0x750 [ 13.786856][ T371] ? netlink_attachskb+0x810/0x810 [ 13.786859][ T371] ? __lock_acquire+0x518/0xc20 [ 13.786864][ T371] netlink_sendmsg+0x735/0xc60 [ 13.786868][ T371] ? netlink_unicast+0x750/0x750 [ 13.786873][ T371] ? __might_fault+0x97/0x140 [ 13.786879][ T371] ____sys_sendmsg+0x415/0x880 [ 13.786881][ T371] ? copy_msghdr_from_user+0x279/0x420 [ 13.786883][ T371] ? get_timestamp.constprop.0+0x390/0x390 [ 13.786885][ T371] ? move_addr_to_kernel+0x40/0x40 [ 13.786894][ T371] ___sys_sendmsg+0x14e/0x1d0 [ 13.786897][ T371] ? copy_msghdr_from_user+0x420/0x420 [ 13.786917][ T371] __sys_sendmsg+0x12c/0x1d0 [ 13.786920][ T371] ? __sys_sendmsg_sock+0x20/0x20 [ 13.786927][ T371] ? rcu_is_watching+0x16/0xd0 [ 13.786931][ T371] do_syscall_64+0xff/0x530 [ 13.786933][ T371] ? exc_page_fault+0xee/0x100 [ 13.786936][ T371] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.786938][ T371] RIP: 0033:0x7ff10cd5b54e [ 13.786942][ T371] 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 [ 13.786944][ T371] RSP: 002b:00007ffdc075ded0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.786947][ T371] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007ff10cd5b54e [ 13.786948][ T371] RDX: 0000000000000000 RSI: 00007ffdc075df80 RDI: 0000000000000005 [ 13.786949][ T371] RBP: 00007ffdc075dee0 R08: 0000000000000000 R09: 0000000000000000 [ 13.786950][ T371] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918580 [ 13.786950][ T371] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffdc075e640 [ 13.786959][ T371] [ 13.829377][ T371] br10: port 1(veth1.10) entered disabled state [ 13.829741][ T371] veth1.10: entered allmulticast mode [ 13.829982][ T371] veth1: entered allmulticast mode [ 13.831949][ T371] veth1.10: entered promiscuous mode [ 13.832211][ T371] veth1: entered promiscuous mode [ 13.839131][ T371] ip (371) used greatest stack depth: 23336 bytes left [ 13.890747][ T378] br10: port 2(veth2.10) entered blocking state [ 13.891177][ T378] br10: port 2(veth2.10) entered disabled state [ 13.891639][ T378] veth2.10: entered allmulticast mode [ 13.891989][ T378] veth2: entered allmulticast mode [ 13.893892][ T378] veth2.10: entered promiscuous mode [ 13.894157][ T378] veth2: entered promiscuous mode [ 13.956519][ T383] br10: port 2(veth2.10) entered blocking state [ 13.957253][ T383] br10: port 2(veth2.10) entered forwarding state [ 13.958430][ T383] br10: port 1(veth1.10) entered blocking state [ 13.958993][ T383] br10: port 1(veth1.10) entered forwarding state [ 14.440991][ T418] br11: port 1(veth1.11) entered blocking state [ 14.441379][ T418] br11: port 1(veth1.11) entered disabled state [ 14.441715][ T418] veth1.11: entered allmulticast mode [ 14.444394][ T418] veth1.11: entered promiscuous mode [ 14.511155][ T424] br11: port 2(veth2.11) entered blocking state [ 14.511531][ T424] br11: port 2(veth2.11) entered disabled state [ 14.511882][ T424] veth2.11: entered allmulticast mode [ 14.513602][ T424] veth2.11: entered promiscuous mode [ 14.560188][ T429] br11: port 2(veth2.11) entered blocking state [ 14.560630][ T429] br11: port 2(veth2.11) entered forwarding state [ 14.561111][ T429] br11: port 1(veth1.11) entered blocking state [ 14.561506][ T429] br11: port 1(veth1.11) entered forwarding state [ 15.051004][ T465] br12: port 1(veth1.12) entered blocking state [ 15.051027][ T465] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.051030][ T465] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 465, name: ip [ 15.051032][ T465] preempt_count: 1, expected: 0 [ 15.051033][ T465] RCU nest depth: 0, expected: 0 [ 15.051035][ T465] locks held by ip/465: 5, last CPU#2: [ 15.051038][ T465] #0: ffffffff962d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 15.051053][ T465] #1: ffffffff95b69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.051061][ T465] #2: ffffffff95b69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.051067][ T465] #3: ffffffff95a89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.051073][ T465] #4: ffffffff95a89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.051078][ T465] irq event stamp: 16978 [ 15.051079][ T465] hardirqs last enabled at (16977): [] __down_trylock_console_sem+0x86/0xa0 [ 15.051083][ T465] hardirqs last disabled at (16978): [] console_emit_next_record+0x3d4/0x4c0 [ 15.051086][ T465] softirqs last enabled at (16920): [] __alloc_skb+0x4c2/0x5f0 [ 15.051090][ T465] softirqs last disabled at (16918): [] __alloc_skb+0x4c2/0x5f0 [ 15.051093][ T465] Preemption disabled at: [ 15.051094][ T465] [] vprintk_emit+0x31b/0x3e0 [ 15.051101][ T465] CPU: 2 UID: 0 PID: 465 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.051106][ T465] Tainted: [W]=WARN [ 15.051107][ T465] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.051109][ T465] Call Trace: [ 15.051111][ T465] [ 15.051113][ T465] dump_stack_lvl+0x6f/0xa0 [ 15.051120][ T465] ? vprintk_emit+0x31b/0x3e0 [ 15.051124][ T465] __might_resched.cold+0x1fe/0x2c1 [ 15.051129][ T465] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.051135][ T465] ? __kmalloc_noprof+0xdb/0x760 [ 15.051142][ T465] __kmalloc_noprof+0x443/0x760 [ 15.051146][ T465] ? alloc_buf.isra.0+0x4b/0x260 [ 15.051155][ T465] ? do_raw_spin_unlock+0x59/0x250 [ 15.051158][ T465] alloc_buf.isra.0+0x4b/0x260 [ 15.051163][ T465] put_chars+0x1e1/0x2f0 [ 15.051166][ T465] ? prb_final_commit+0x50/0x50 [ 15.051169][ T465] ? __send_to_port+0x420/0x420 [ 15.051175][ T465] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.051180][ T465] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.051184][ T465] ? validate_chain+0x38b/0xc20 [ 15.051190][ T465] hvc_console_print+0x292/0x780 [ 15.051194][ T465] ? mark_usage+0x61/0x170 [ 15.051197][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051199][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051206][ T465] ? hvc_write+0x3a0/0x3a0 [ 15.051210][ T465] ? console_emit_next_record+0x1df/0x4c0 [ 15.051215][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051219][ T465] ? lock_acquire+0x13c/0x160 [ 15.051226][ T465] console_emit_next_record+0x22f/0x4c0 [ 15.051232][ T465] ? devkmsg_read+0x4b0/0x4b0 [ 15.051235][ T465] ? console_flush_one_record+0x106/0x710 [ 15.051240][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051244][ T465] ? lock_acquire+0x13c/0x160 [ 15.051251][ T465] console_flush_one_record+0x46f/0x710 [ 15.051258][ T465] ? console_emit_next_record+0x4c0/0x4c0 [ 15.051260][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051270][ T465] console_unlock+0xee/0x1f0 [ 15.051275][ T465] ? console_flush_one_record+0x710/0x710 [ 15.051277][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051281][ T465] ? lock_acquire+0x60/0x160 [ 15.051287][ T465] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.051290][ T465] ? vprintk_emit+0x320/0x3e0 [ 15.051295][ T465] vprintk_emit+0x37c/0x3e0 [ 15.051302][ T465] ? wake_up_klogd_work_func+0x90/0x90 [ 15.051305][ T465] ? __lock_release.isra.0+0x69/0x1a0 [ 15.051314][ T465] _printk+0xc7/0x100 [ 15.051319][ T465] ? snapshot_read.cold+0x21/0x21 [ 15.051326][ T465] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 15.051346][ T465] ? do_setlink.isra.0+0xa31/0x2750 [ 15.051349][ T465] ? rtnl_newlink+0x9f1/0x11c0 [ 15.051351][ T465] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.051359][ T465] br_set_state+0x22f/0x430 [bridge] [ 15.051376][ T465] br_init_port+0xc4/0x200 [bridge] [ 15.051391][ T465] new_nbp+0x39c/0x580 [bridge] [ 15.051411][ T465] br_add_if+0x212/0x1320 [bridge] [ 15.051423][ T465] ? is_bpf_text_address+0x72/0x110 [ 15.051428][ T465] ? kernel_text_address+0x149/0x170 [ 15.051432][ T465] ? __kernel_text_address+0x12/0x30 [ 15.051440][ T465] do_set_master+0x357/0x580 [ 15.051447][ T465] do_setlink.isra.0+0xa31/0x2750 [ 15.051453][ T465] ? stack_trace_save+0x93/0xc0 [ 15.051457][ T465] ? rtnl_link_get_size+0x350/0x350 [ 15.051459][ T465] ? rcu_read_lock_any_held+0x66/0x90 [ 15.051462][ T465] ? stack_depot_save_flags+0x38e/0x790 [ 15.051466][ T465] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.051470][ T465] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.051473][ T465] ? validate_chain+0x38b/0xc20 [ 15.051477][ T465] ? kasan_save_stack+0x3d/0x50 [ 15.051481][ T465] ? kasan_save_stack+0x2f/0x50 [ 15.051483][ T465] ? kasan_save_track+0x14/0x30 [ 15.051488][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051491][ T465] ? netlink_seq_next+0xe/0x60 [ 15.051495][ T465] ? ___sys_sendmsg+0xb0/0x1d0 [ 15.051502][ T465] ? lock_acquire.part.0+0xd4/0x280 [ 15.051505][ T465] ? rtnl_newlink+0x9a8/0x11c0 [ 15.051509][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051513][ T465] ? lock_acquire+0x13c/0x160 [ 15.051515][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051517][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051521][ T465] ? trace_contention_end+0xb3/0x180 [ 15.051526][ T465] ? __mutex_lock+0x1db/0x1ea0 [ 15.051530][ T465] ? __mutex_lock+0x9a3/0x1ea0 [ 15.051534][ T465] ? rtnl_newlink+0x9a8/0x11c0 [ 15.051540][ T465] ? ww_mutex_lock+0x160/0x160 [ 15.051543][ T465] ? nla_get_range_signed+0x3d0/0x3d0 [ 15.051552][ T465] ? __rtnl_newlink+0x3fa/0xa50 [ 15.051562][ T465] rtnl_newlink+0x9f1/0x11c0 [ 15.051573][ T465] ? rtnl_bridge_getlink+0x850/0x850 [ 15.051576][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051584][ T465] ? lock_acquire.part.0+0xd4/0x280 [ 15.051587][ T465] ? find_held_lock+0x2b/0x80 [ 15.051590][ T465] ? rtnl_bridge_getlink+0x850/0x850 [ 15.051594][ T465] ? __lock_release.isra.0+0x69/0x1a0 [ 15.051600][ T465] ? rtnl_bridge_getlink+0x850/0x850 [ 15.051604][ T465] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.051609][ T465] ? rtnl_link_fill+0x920/0x920 [ 15.051611][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051619][ T465] ? lock_acquire.part.0+0xd4/0x280 [ 15.051622][ T465] ? find_held_lock+0x2b/0x80 [ 15.051628][ T465] netlink_rcv_skb+0x14e/0x3a0 [ 15.051631][ T465] ? rtnl_link_fill+0x920/0x920 [ 15.051636][ T465] ? netlink_ack+0xcf0/0xcf0 [ 15.051648][ T465] ? netlink_deliver_tap+0xc5/0x330 [ 15.051650][ T465] ? netlink_deliver_tap+0x13c/0x330 [ 15.051658][ T465] netlink_unicast+0x486/0x750 [ 15.051665][ T465] ? netlink_attachskb+0x810/0x810 [ 15.051669][ T465] ? __lock_acquire+0x518/0xc20 [ 15.051677][ T465] netlink_sendmsg+0x735/0xc60 [ 15.051683][ T465] ? netlink_unicast+0x750/0x750 [ 15.051691][ T465] ? __might_fault+0x97/0x140 [ 15.051699][ T465] ____sys_sendmsg+0x415/0x880 [ 15.051704][ T465] ? copy_msghdr_from_user+0x279/0x420 [ 15.051707][ T465] ? get_timestamp.constprop.0+0x390/0x390 [ 15.051710][ T465] ? move_addr_to_kernel+0x40/0x40 [ 15.051724][ T465] ___sys_sendmsg+0x14e/0x1d0 [ 15.051729][ T465] ? copy_msghdr_from_user+0x420/0x420 [ 15.051760][ T465] __sys_sendmsg+0x12c/0x1d0 [ 15.051764][ T465] ? __sys_sendmsg_sock+0x20/0x20 [ 15.051776][ T465] ? rcu_is_watching+0x16/0xd0 [ 15.051783][ T465] do_syscall_64+0xff/0x530 [ 15.051786][ T465] ? exc_page_fault+0xee/0x100 [ 15.051791][ T465] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.051794][ T465] RIP: 0033:0x7f803041c54e [ 15.051799][ T465] 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 [ 15.051801][ T465] RSP: 002b:00007ffe45200e00 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 15.051806][ T465] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f803041c54e [ 15.051808][ T465] RDX: 0000000000000000 RSI: 00007ffe45200eb0 RDI: 0000000000000005 [ 15.051809][ T465] RBP: 00007ffe45200e10 R08: 0000000000000000 R09: 0000000000000000 [ 15.051815][ T465] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918581 [ 15.051816][ T465] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe45201570 [ 15.051829][ T465] [ 15.143658][ T465] br12: port 1(veth1.12) entered disabled state [ 15.144642][ T465] veth1.12: entered allmulticast mode [ 15.148290][ T465] veth1.12: entered promiscuous mode [ 15.206189][ T470] br12: port 2(veth2.12) entered blocking state [ 15.206872][ T470] br12: port 2(veth2.12) entered disabled state [ 15.207225][ T470] veth2.12: entered allmulticast mode [ 15.208939][ T470] veth2.12: entered promiscuous mode [ 15.265413][ T475] br12: port 2(veth2.12) entered blocking state [ 15.265770][ T475] br12: port 2(veth2.12) entered forwarding state [ 15.266267][ T475] br12: port 1(veth1.12) entered blocking state [ 15.266596][ T475] br12: port 1(veth1.12) entered forwarding state [ 29.117392][ C2] [ 29.117410][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.117412][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 570, name: mausezahn [ 29.117414][ C2] preempt_count: 104, expected: 0 [ 29.117415][ C2] RCU nest depth: 0, expected: 0 [ 29.117416][ C2] INFO: lockdep is turned off. [ 29.117416][ C2] irq event stamp: 5829312 [ 29.117417][ C2] hardirqs last enabled at (5829312): [] _raw_spin_unlock_irq+0x28/0x50 [ 29.117426][ C2] hardirqs last disabled at (5829311): [] _raw_spin_lock_irq+0x4a/0x50 [ 29.117428][ C2] softirqs last enabled at (5828640): [] __alloc_skb+0x4c2/0x5f0 [ 29.117432][ C2] softirqs last disabled at (5829309): [] __irq_exit_rcu+0x145/0x1c0 [ 29.117435][ C2] Preemption disabled at: [ 29.117436][ C2] [<0000000000000000>] 0x0 [ 29.117443][ C2] CPU: 2 UID: 0 PID: 570 Comm: mausezahn Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.117447][ C2] Tainted: [W]=WARN [ 29.117448][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.117450][ C2] Call Trace: [ 29.117452][ C2] [ 29.117453][ C2] dump_stack_lvl+0x6f/0xa0 [ 29.117458][ C2] __might_resched.cold+0x1fe/0x2c1 [ 29.117462][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.117465][ C2] ? __asan_memcpy+0x3c/0x60 [ 29.117468][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117472][ C2] __kmalloc_noprof+0x443/0x760 [ 29.117475][ C2] ? __rwlock_init+0x150/0x150 [ 29.117478][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 29.117482][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 29.117483][ C2] alloc_buf.isra.0+0x4b/0x260 [ 29.117486][ C2] put_chars+0x1e1/0x2f0 [ 29.117487][ C2] ? __send_to_port+0x420/0x420 [ 29.117489][ C2] ? console_prepend_replay+0x20/0x20 [ 29.117492][ C2] hvc_console_print+0x292/0x780 [ 29.117496][ C2] ? hvc_write+0x3a0/0x3a0 [ 29.117497][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117499][ C2] ? lock_acquire+0x13c/0x160 [ 29.117501][ C2] console_emit_next_record+0x22f/0x4c0 [ 29.117503][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 29.117505][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117507][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117509][ C2] ? lock_acquire+0x13c/0x160 [ 29.117510][ C2] ? console_flush_one_record+0x111/0x710 [ 29.117512][ C2] console_flush_one_record+0x46f/0x710 [ 29.117514][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 29.117517][ C2] console_unlock+0xee/0x1f0 [ 29.117519][ C2] ? lock_acquire+0x13c/0x160 [ 29.117520][ C2] ? console_flush_one_record+0x710/0x710 [ 29.117522][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117523][ C2] ? lock_release+0x184/0x1f0 [ 29.117525][ C2] ? lock_acquire+0x60/0x160 [ 29.117527][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.117528][ C2] ? vprintk_emit+0x320/0x3e0 [ 29.117530][ C2] vprintk_emit+0x37c/0x3e0 [ 29.117533][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 29.117534][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117536][ C2] ? lock_release+0x184/0x1f0 [ 29.117538][ C2] _printk+0xc7/0x100 [ 29.117541][ C2] ? snapshot_read.cold+0x21/0x21 [ 29.117543][ C2] ? arch_stack_walk+0xed/0x130 [ 29.117547][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 29.117549][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 29.117550][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 29.117553][ C2] mark_lock_irq+0x989/0x9c0 [ 29.117556][ C2] mark_lock+0x1d7/0xa00 [ 29.117558][ C2] mark_usage+0x42/0x170 [ 29.117559][ C2] __lock_acquire+0x388/0xc20 [ 29.117561][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.117563][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.117575][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117577][ C2] ? lock_acquire+0x13c/0x160 [ 29.117579][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.117585][ C2] _raw_spin_lock+0x33/0x40 [ 29.117587][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.117593][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.117599][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.117605][ C2] call_timer_fn+0x160/0x4d0 [ 29.117609][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 29.117610][ C2] ? debug_object_active_state+0x430/0x430 [ 29.117614][ C2] ? find_held_lock+0x2b/0x80 [ 29.117616][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 29.117618][ C2] ? mark_held_locks+0x40/0x70 [ 29.117620][ C2] __run_timers+0x68f/0xaa0 [ 29.117621][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.117628][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.117630][ C2] ? __lock_acquire+0x518/0xc20 [ 29.117633][ C2] ? __rwlock_init+0x150/0x150 [ 29.117635][ C2] run_timer_softirq+0xf0/0x160 [ 29.117637][ C2] ? __run_timers+0xaa0/0xaa0 [ 29.117639][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.117641][ C2] handle_softirqs+0x1d3/0x900 [ 29.117642][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 29.117644][ C2] ? _local_bh_enable+0xc0/0xc0 [ 29.117646][ C2] __irq_exit_rcu+0x145/0x1c0 [ 29.117647][ C2] irq_exit_rcu+0xe/0x30 [ 29.117649][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.117652][ C2] [ 29.117652][ C2] [ 29.117653][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.117655][ C2] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 29.117658][ C2] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 9f ba fd 48 89 df e8 c9 f5 ba fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 bf ac fd 65 48 83 3d af fb f2 01 00 74 12 5b [ 29.117660][ C2] RSP: 0018:ffa00000005e7938 EFLAGS: 00000246 [ 29.117663][ C2] RAX: 0000000000000096 RBX: ff11000001f96400 RCX: ffffffff94da9483 [ 29.117664][ C2] RDX: ff1100000a42a380 RSI: ffffffff95638af6 RDI: ffffffff9508d8e0 [ 29.117665][ C2] RBP: 0000000000000286 R08: 0000000000000000 R09: 0000000000000000 [ 29.117666][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: ff110000115f66c0 [ 29.117667][ C2] R13: 0000000000000000 R14: ffa00000005e7980 R15: 0000000000000cc0 [ 29.117669][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 29.117671][ C2] qlist_free_all+0x5c/0x110 [ 29.117673][ C2] kasan_quarantine_reduce+0x196/0x240 [ 29.117675][ C2] __kasan_slab_alloc+0x4b/0x70 [ 29.117678][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 29.117679][ C2] ? __alloc_skb+0xde/0x5f0 [ 29.117682][ C2] __alloc_skb+0xde/0x5f0 [ 29.117683][ C2] ? __alloc_skb+0x4c2/0x5f0 [ 29.117685][ C2] ? napi_skb_cache_get+0x830/0x830 [ 29.117687][ C2] ? mark_usage+0x61/0x170 [ 29.117688][ C2] ? ref_tracker_alloc+0x2e7/0x430 [ 29.117691][ C2] ? ref_tracker_dir_print+0x100/0x100 [ 29.117693][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 29.117695][ C2] ? find_held_lock+0x2b/0x80 [ 29.117697][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 29.117699][ C2] packet_snd+0x5dd/0x1840 [ 29.117704][ C2] ? tpacket_snd+0x1990/0x1990 [ 29.117707][ C2] ? __might_fault+0x97/0x140 [ 29.117710][ C2] ? __might_fault+0x97/0x140 [ 29.117712][ C2] __sys_sendto+0x2aa/0x400 [ 29.117715][ C2] ? __ia32_sys_getpeername+0xd0/0xd0 [ 29.117717][ C2] ? sock_ioctl+0x3cb/0x5f0 [ 29.117719][ C2] ? irqentry_exit+0x21c/0x790 [ 29.117722][ C2] ? br_ioctl_call+0x80/0x80 [ 29.117724][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 29.117725][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.117728][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 29.117729][ C2] ? do_syscall_64+0xa6/0x530 [ 29.117731][ C2] do_syscall_64+0xff/0x530 [ 29.117732][ C2] ? irq_exit_rcu+0x1a/0x30 [ 29.117733][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.117735][ C2] RIP: 0033:0x7f4b6eeb454e [ 29.117737][ C2] 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 [ 29.117738][ C2] RSP: 002b:00007ffdd7573200 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 29.117740][ C2] RAX: ffffffffffffffda RBX: 000055d6a94c75e2 RCX: 00007f4b6eeb454e [ 29.117740][ C2] RDX: 0000000000001f6a RSI: 000055d6a94c75e2 RDI: 0000000000000005 [ 29.117741][ C2] RBP: 00007ffdd7573210 R08: 00007ffdd7573260 R09: 0000000000000014 [ 29.117742][ C2] R10: 0000000000000000 R11: 0000000000000202 R12: 000055d6a94c4830 [ 29.117743][ C2] R13: 0000000000001f6a R14: 0000000000000005 R15: 000055d6a94c4830 [ 29.117745][ C2] [ 29.142377][ C2] ======================================================== [ 29.142597][ C2] WARNING: possible irq lock inversion dependency detected [ 29.142816][ C2] 7.2.0-virtme #1 Tainted: G W [ 29.143025][ C2] -------------------------------------------------------- [ 29.143240][ C2] mausezahn/570 just changed the state of lock: [ 29.143447][ C2] ff1100000cf7ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.143823][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 29.144089][ C2] (fs_reclaim){+.+.}-{0:0} [ 29.144091][ C2] [ 29.144091][ C2] [ 29.144091][ C2] and interrupts could create inverse lock ordering between them. [ 29.144091][ C2] [ 29.144734][ C2] [ 29.144734][ C2] other info that might help us debug this: [ 29.144975][ C2] Chain exists of: [ 29.144975][ C2] &br->lock --> console_owner --> fs_reclaim [ 29.144975][ C2] [ 29.145343][ C2] Possible interrupt unsafe locking scenario: [ 29.145343][ C2] [ 29.145591][ C2] CPU0 CPU1 [ 29.145768][ C2] ---- ---- [ 29.145946][ C2] lock(fs_reclaim); [ 29.146091][ C2] local_irq_disable(); [ 29.146281][ C2] lock(&br->lock); [ 29.146468][ C2] lock(console_owner); [ 29.146679][ C2] [ 29.146814][ C2] lock(&br->lock); [ 29.146936][ C2] [ 29.146936][ C2] *** DEADLOCK *** [ 29.146936][ C2] [ 29.147180][ C2] locks held by mausezahn/570: 2, last CPU#2: [ 29.147367][ C2] #0: ffffffff95dae1b8 (remove_cache_srcu){.+.+}-{0:0}, at: kasan_quarantine_reduce+0x84/0x240 [ 29.147692][ C2] #1: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 29.148000][ C2] [ 29.148000][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 29.148256][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 29.148412][ C2] HARDIRQ-ON-W at: [ 29.148529][ C2] __lock_acquire+0x388/0xc20 [ 29.148720][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.148911][ C2] fs_reclaim_acquire+0xd5/0x120 [ 29.149094][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 29.149320][ C2] kthread_create_worker_on_node+0xea/0x210 [ 29.149546][ C2] workqueue_init+0x2a/0x680 [ 29.149733][ C2] kernel_init_freeable+0x2fe/0x630 [ 29.149964][ C2] kernel_init+0x21/0x150 [ 29.150150][ C2] ret_from_fork+0x474/0x6b0 [ 29.150340][ C2] ret_from_fork_asm+0x11/0x20 [ 29.150533][ C2] SOFTIRQ-ON-W at: [ 29.150646][ C2] __lock_acquire+0x388/0xc20 [ 29.150831][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.151022][ C2] fs_reclaim_acquire+0xd5/0x120 [ 29.151210][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 29.151436][ C2] kthread_create_worker_on_node+0xea/0x210 [ 29.151657][ C2] workqueue_init+0x2a/0x680 [ 29.151846][ C2] kernel_init_freeable+0x2fe/0x630 [ 29.152073][ C2] kernel_init+0x21/0x150 [ 29.152262][ C2] ret_from_fork+0x474/0x6b0 [ 29.152449][ C2] ret_from_fork_asm+0x11/0x20 [ 29.152637][ C2] INITIAL USE at: [ 29.152750][ C2] __lock_acquire+0x388/0xc20 [ 29.152944][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.153130][ C2] fs_reclaim_acquire+0xd5/0x120 [ 29.153317][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 29.153542][ C2] kthread_create_worker_on_node+0xea/0x210 [ 29.153763][ C2] workqueue_init+0x2a/0x680 [ 29.153952][ C2] kernel_init_freeable+0x2fe/0x630 [ 29.154140][ C2] kernel_init+0x21/0x150 [ 29.154324][ C2] ret_from_fork+0x474/0x6b0 [ 29.154508][ C2] ret_from_fork_asm+0x11/0x20 [ 29.154691][ C2] } [ 29.154768][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 29.158491][ C2] ... acquired at: [ 29.158603][ C2] __lock_acquire+0x518/0xc20 [ 29.158759][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.158965][ C2] fs_reclaim_acquire+0xd5/0x120 [ 29.159109][ C2] __kmalloc_noprof+0xd3/0x760 [ 29.159255][ C2] alloc_buf.isra.0+0x4b/0x260 [ 29.159403][ C2] put_chars+0x1e1/0x2f0 [ 29.159600][ C2] hvc_console_print+0x292/0x780 [ 29.159794][ C2] console_emit_next_record+0x22f/0x4c0 [ 29.160000][ C2] console_flush_one_record+0x46f/0x710 [ 29.160148][ C2] console_unlock+0xee/0x1f0 [ 29.160343][ C2] vprintk_emit+0x37c/0x3e0 [ 29.160538][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 29.160684][ C2] dev_printk_emit+0xb9/0xee [ 29.160828][ C2] _dev_info+0xe2/0x116 [ 29.160940][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 29.161122][ C2] cmos_do_probe+0x73b/0x98a [ 29.161321][ C2] platform_probe+0xfe/0x1f0 [ 29.161471][ C2] call_driver_probe+0x61/0x1c0 [ 29.161668][ C2] really_probe+0x199/0x760 [ 29.161812][ C2] __driver_probe_device+0x24f/0x440 [ 29.162010][ C2] driver_probe_device+0x4a/0xf0 [ 29.162205][ C2] __driver_attach+0x1b8/0x540 [ 29.162400][ C2] bus_for_each_dev+0x130/0x1e0 [ 29.162599][ C2] bus_add_driver+0x2c8/0x530 [ 29.162797][ C2] driver_register+0x1a3/0x390 [ 29.162950][ C2] __platform_driver_probe+0x13f/0x270 [ 29.163097][ C2] cmos_init+0x31/0x40 [ 29.163260][ C2] do_one_initcall+0x124/0x4f0 [ 29.163462][ C2] kernel_init_freeable+0x596/0x630 [ 29.163609][ C2] kernel_init+0x21/0x150 [ 29.163759][ C2] ret_from_fork+0x474/0x6b0 [ 29.163913][ C2] ret_from_fork_asm+0x11/0x20 [ 29.164058][ C2] [ 29.164135][ C2] -> (console_owner){....}-{0:0} { [ 29.164291][ C2] INITIAL USE at: [ 29.164455][ C2] } [ 29.164529][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 29.164798][ C2] ... acquired at: [ 29.164960][ C2] __lock_acquire+0x518/0xc20 [ 29.165107][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.165250][ C2] console_lock_spinning_enable+0x5c/0x60 [ 29.165483][ C2] console_emit_next_record+0x1d1/0x4c0 [ 29.165629][ C2] console_flush_one_record+0x46f/0x710 [ 29.165824][ C2] console_unlock+0xee/0x1f0 [ 29.165974][ C2] vprintk_emit+0x37c/0x3e0 [ 29.166173][ C2] _printk+0xc7/0x100 [ 29.166285][ C2] br_set_state+0x22f/0x430 [bridge] [ 29.166441][ C2] br_init_port+0xc4/0x200 [bridge] [ 29.166591][ C2] br_stp_enable_bridge+0x1fc/0x340 [bridge] [ 29.166831][ C2] br_dev_open+0x94/0x120 [bridge] [ 29.166989][ C2] __dev_open+0x2f3/0x610 [ 29.167136][ C2] __dev_change_flags+0x4a4/0x660 [ 29.167337][ C2] netif_change_flags+0x87/0x190 [ 29.167537][ C2] do_setlink.isra.0+0x1cc3/0x2750 [ 29.167686][ C2] rtnl_newlink+0x9f1/0x11c0 [ 29.167830][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.168032][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 29.168231][ C2] netlink_unicast+0x486/0x750 [ 29.168432][ C2] netlink_sendmsg+0x735/0xc60 [ 29.168630][ C2] ____sys_sendmsg+0x415/0x880 [ 29.168779][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 29.168931][ C2] __sys_sendmsg+0x12c/0x1d0 [ 29.169133][ C2] do_syscall_64+0xff/0x530 [ 29.169280][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.169467][ C2] [ 29.169591][ C2] -> (&br->lock){+.-.}-{3:3} { [ 29.169738][ C2] HARDIRQ-ON-W at: [ 29.169910][ C2] __lock_acquire+0x388/0xc20 [ 29.170148][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.170332][ C2] _raw_spin_lock_bh+0x38/0x50 [ 29.170574][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 29.170763][ C2] do_set_master+0x357/0x580 [ 29.171008][ C2] do_setlink.isra.0+0xa31/0x2750 [ 29.171204][ C2] rtnl_newlink+0x9f1/0x11c0 [ 29.171386][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.171623][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 29.171870][ C2] netlink_unicast+0x486/0x750 [ 29.172114][ C2] netlink_sendmsg+0x735/0xc60 [ 29.172301][ C2] ____sys_sendmsg+0x415/0x880 [ 29.172543][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 29.172784][ C2] __sys_sendmsg+0x12c/0x1d0 [ 29.173026][ C2] do_syscall_64+0xff/0x530 [ 29.173269][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.173604][ C2] IN-SOFTIRQ-W at: [ 29.173716][ C2] __lock_acquire+0x388/0xc20 [ 29.173905][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.174094][ C2] _raw_spin_lock+0x33/0x40 [ 29.174335][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.174568][ C2] call_timer_fn+0x160/0x4d0 [ 29.174810][ C2] __run_timers+0x68f/0xaa0 [ 29.175052][ C2] run_timer_softirq+0xf0/0x160 [ 29.175295][ C2] handle_softirqs+0x1d3/0x900 [ 29.175488][ C2] __irq_exit_rcu+0x145/0x1c0 [ 29.175673][ C2] irq_exit_rcu+0xe/0x30 [ 29.175855][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.176134][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.176461][ C2] _raw_spin_unlock_irqrestore+0x36/0x80 [ 29.176681][ C2] qlist_free_all+0x5c/0x110 [ 29.176868][ C2] kasan_quarantine_reduce+0x196/0x240 [ 29.177051][ C2] __kasan_slab_alloc+0x4b/0x70 [ 29.177288][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 29.177616][ C2] __alloc_skb+0xde/0x5f0 [ 29.177801][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 29.177988][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 29.178223][ C2] packet_snd+0x5dd/0x1840 [ 29.178516][ C2] __sys_sendto+0x2aa/0x400 [ 29.178749][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 29.178938][ C2] do_syscall_64+0xff/0x530 [ 29.179121][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.179338][ C2] INITIAL USE at: [ 29.179501][ C2] __lock_acquire+0x388/0xc20 [ 29.179682][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.179866][ C2] _raw_spin_lock_bh+0x38/0x50 [ 29.180156][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 29.180348][ C2] do_set_master+0x357/0x580 [ 29.180533][ C2] do_setlink.isra.0+0xa31/0x2750 [ 29.180770][ C2] rtnl_newlink+0x9f1/0x11c0 [ 29.181011][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.181196][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 29.181437][ C2] netlink_unicast+0x486/0x750 [ 29.181674][ C2] netlink_sendmsg+0x735/0xc60 [ 29.181856][ C2] ____sys_sendmsg+0x415/0x880 [ 29.182098][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 29.182279][ C2] __sys_sendmsg+0x12c/0x1d0 [ 29.182519][ C2] do_syscall_64+0xff/0x530 [ 29.182702][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.182926][ C2] } [ 29.183051][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 29.183272][ C2] ... acquired at: [ 29.183382][ C2] mark_lock+0x1d7/0xa00 [ 29.183581][ C2] mark_usage+0x42/0x170 [ 29.183728][ C2] __lock_acquire+0x388/0xc20 [ 29.183880][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.184081][ C2] _raw_spin_lock+0x33/0x40 [ 29.184230][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.184474][ C2] call_timer_fn+0x160/0x4d0 [ 29.184671][ C2] __run_timers+0x68f/0xaa0 [ 29.184814][ C2] run_timer_softirq+0xf0/0x160 [ 29.185012][ C2] handle_softirqs+0x1d3/0x900 [ 29.185157][ C2] __irq_exit_rcu+0x145/0x1c0 [ 29.185350][ C2] irq_exit_rcu+0xe/0x30 [ 29.185493][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.185722][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.185903][ C2] _raw_spin_unlock_irqrestore+0x36/0x80 [ 29.186128][ C2] qlist_free_all+0x5c/0x110 [ 29.186272][ C2] kasan_quarantine_reduce+0x196/0x240 [ 29.186466][ C2] __kasan_slab_alloc+0x4b/0x70 [ 29.186611][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 29.186836][ C2] __alloc_skb+0xde/0x5f0 [ 29.187033][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 29.187231][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 29.187430][ C2] packet_snd+0x5dd/0x1840 [ 29.187627][ C2] __sys_sendto+0x2aa/0x400 [ 29.187773][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 29.187929][ C2] do_syscall_64+0xff/0x530 [ 29.188126][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.188310][ C2] [ 29.188384][ C2] [ 29.188384][ C2] stack backtrace: [ 29.188574][ C2] CPU: 2 UID: 0 PID: 570 Comm: mausezahn Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.188577][ C2] Tainted: [W]=WARN [ 29.188578][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.188579][ C2] Call Trace: [ 29.188580][ C2] [ 29.188581][ C2] dump_stack_lvl+0x6f/0xa0 [ 29.188586][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 29.188588][ C2] mark_lock_irq+0x989/0x9c0 [ 29.188591][ C2] mark_lock+0x1d7/0xa00 [ 29.188593][ C2] mark_usage+0x42/0x170 [ 29.188595][ C2] __lock_acquire+0x388/0xc20 [ 29.188597][ C2] lock_acquire.part.0+0xd4/0x280 [ 29.188599][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.188606][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.188609][ C2] ? lock_acquire+0x13c/0x160 [ 29.188610][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.188617][ C2] _raw_spin_lock+0x33/0x40 [ 29.188618][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.188624][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.188630][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.188637][ C2] call_timer_fn+0x160/0x4d0 [ 29.188639][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 29.188640][ C2] ? debug_object_active_state+0x430/0x430 [ 29.188644][ C2] ? find_held_lock+0x2b/0x80 [ 29.188646][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 29.188647][ C2] ? mark_held_locks+0x40/0x70 [ 29.188649][ C2] __run_timers+0x68f/0xaa0 [ 29.188651][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.188658][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.188660][ C2] ? __lock_acquire+0x518/0xc20 [ 29.188662][ C2] ? __rwlock_init+0x150/0x150 [ 29.188665][ C2] run_timer_softirq+0xf0/0x160 [ 29.188667][ C2] ? __run_timers+0xaa0/0xaa0 [ 29.188669][ C2] ? rcu_is_watching+0x16/0xd0 [ 29.188670][ C2] handle_softirqs+0x1d3/0x900 [ 29.188672][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 29.188674][ C2] ? _local_bh_enable+0xc0/0xc0 [ 29.188676][ C2] __irq_exit_rcu+0x145/0x1c0 [ 29.188677][ C2] irq_exit_rcu+0xe/0x30 [ 29.188679][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.188681][ C2] [ 29.188681][ C2] [ 29.188682][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.188683][ C2] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 29.188686][ C2] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 9f ba fd 48 89 df e8 c9 f5 ba fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 bf ac fd 65 48 83 3d af fb f2 01 00 74 12 5b [ 29.188687][ C2] RSP: 0018:ffa00000005e7938 EFLAGS: 00000246 [ 29.188690][ C2] RAX: 0000000000000096 RBX: ff11000001f96400 RCX: ffffffff94da9483 [ 29.188691][ C2] RDX: ff1100000a42a380 RSI: ffffffff95638af6 RDI: ffffffff9508d8e0 [ 29.188692][ C2] RBP: 0000000000000286 R08: 0000000000000000 R09: 0000000000000000 [ 29.188693][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: ff110000115f66c0 [ 29.188694][ C2] R13: 0000000000000000 R14: ffa00000005e7980 R15: 0000000000000cc0 [ 29.188695][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 29.188698][ C2] qlist_free_all+0x5c/0x110 [ 29.188699][ C2] kasan_quarantine_reduce+0x196/0x240 [ 29.188701][ C2] __kasan_slab_alloc+0x4b/0x70 [ 29.188703][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 29.188705][ C2] ? __alloc_skb+0xde/0x5f0 [ 29.188707][ C2] __alloc_skb+0xde/0x5f0 [ 29.188709][ C2] ? __alloc_skb+0x4c2/0x5f0 [ 29.188710][ C2] ? napi_skb_cache_get+0x830/0x830 [ 29.188712][ C2] ? mark_usage+0x61/0x170 [ 29.188713][ C2] ? ref_tracker_alloc+0x2e7/0x430 [ 29.188716][ C2] ? ref_tracker_dir_print+0x100/0x100 [ 29.188718][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 29.188720][ C2] ? find_held_lock+0x2b/0x80 [ 29.188721][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 29.188723][ C2] packet_snd+0x5dd/0x1840 [ 29.188727][ C2] ? tpacket_snd+0x1990/0x1990 [ 29.188729][ C2] ? __might_fault+0x97/0x140 [ 29.188732][ C2] ? __might_fault+0x97/0x140 [ 29.188734][ C2] __sys_sendto+0x2aa/0x400 [ 29.188736][ C2] ? __ia32_sys_getpeername+0xd0/0xd0 [ 29.188738][ C2] ? sock_ioctl+0x3cb/0x5f0 [ 29.188740][ C2] ? irqentry_exit+0x21c/0x790 [ 29.188743][ C2] ? br_ioctl_call+0x80/0x80 [ 29.188745][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 29.188746][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.188749][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 29.188750][ C2] ? do_syscall_64+0xa6/0x530 [ 29.188751][ C2] do_syscall_64+0xff/0x530 [ 29.188752][ C2] ? irq_exit_rcu+0x1a/0x30 [ 29.188754][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.188755][ C2] RIP: 0033:0x7f4b6eeb454e [ 29.188757][ C2] 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 [ 29.188758][ C2] RSP: 002b:00007ffdd7573200 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 29.188760][ C2] RAX: ffffffffffffffda RBX: 000055d6a94c75e2 RCX: 00007f4b6eeb454e [ 29.188760][ C2] RDX: 0000000000001f6a RSI: 000055d6a94c75e2 RDI: 0000000000000005 [ 29.188761][ C2] RBP: 00007ffdd7573210 R08: 00007ffdd7573260 R09: 0000000000000014 [ 29.188762][ C2] R10: 0000000000000000 R11: 0000000000000202 R12: 000055d6a94c4830 [ 29.188763][ C2] R13: 0000000000001f6a R14: 0000000000000005 R15: 000055d6a94c4830 [ 29.188765][ C2] [ 392.691999][ T1968] br12: port 2(veth2.12) entered disabled state [ 392.692015][ T1968] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 392.692018][ T1968] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1968, name: ip [ 392.692019][ T1968] preempt_count: 202, expected: 0 [ 392.692020][ T1968] RCU nest depth: 0, expected: 0 [ 392.692021][ T1968] INFO: lockdep is turned off. [ 392.692022][ T1968] irq event stamp: 0 [ 392.692023][ T1968] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 392.692027][ T1968] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 392.692034][ T1968] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 392.692035][ T1968] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 392.692037][ T1968] Preemption disabled at: [ 392.692038][ T1968] [<0000000000000000>] 0x0 [ 392.692042][ T1968] CPU: 2 UID: 0 PID: 1968 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 392.692046][ T1968] Tainted: [W]=WARN [ 392.692047][ T1968] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 392.692048][ T1968] Call Trace: [ 392.692050][ T1968] [ 392.692051][ T1968] dump_stack_lvl+0x6f/0xa0 [ 392.692056][ T1968] __might_resched.cold+0x1fe/0x2c1 [ 392.692060][ T1968] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 392.692064][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692068][ T1968] __kmalloc_noprof+0x443/0x760 [ 392.692071][ T1968] ? __rwlock_init+0x150/0x150 [ 392.692074][ T1968] ? alloc_buf.isra.0+0x4b/0x260 [ 392.692078][ T1968] ? do_raw_spin_unlock+0x59/0x250 [ 392.692079][ T1968] alloc_buf.isra.0+0x4b/0x260 [ 392.692081][ T1968] put_chars+0x1e1/0x2f0 [ 392.692083][ T1968] ? __send_to_port+0x420/0x420 [ 392.692085][ T1968] ? printk_get_next_message+0x240/0x650 [ 392.692088][ T1968] hvc_console_print+0x292/0x780 [ 392.692091][ T1968] ? hvc_write+0x3a0/0x3a0 [ 392.692093][ T1968] ? __rwlock_init+0x150/0x150 [ 392.692094][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692096][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692098][ T1968] console_emit_next_record+0x22f/0x4c0 [ 392.692100][ T1968] ? devkmsg_read+0x4b0/0x4b0 [ 392.692102][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692104][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692105][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692107][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692109][ T1968] ? console_flush_one_record+0x111/0x710 [ 392.692110][ T1968] console_flush_one_record+0x46f/0x710 [ 392.692112][ T1968] ? console_emit_next_record+0x4c0/0x4c0 [ 392.692115][ T1968] console_unlock+0xee/0x1f0 [ 392.692117][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692118][ T1968] ? console_flush_one_record+0x710/0x710 [ 392.692120][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692121][ T1968] ? lock_release+0x184/0x1f0 [ 392.692122][ T1968] ? lock_acquire+0x60/0x160 [ 392.692124][ T1968] ? __rcu_read_lock+0x30/0x70 [ 392.692126][ T1968] ? __down_trylock_console_sem+0x5e/0xa0 [ 392.692127][ T1968] ? vprintk_emit+0x320/0x3e0 [ 392.692129][ T1968] vprintk_emit+0x37c/0x3e0 [ 392.692132][ T1968] ? wake_up_klogd_work_func+0x90/0x90 [ 392.692133][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692135][ T1968] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 392.692137][ T1968] ? trace_hardirqs_on+0x36/0x40 [ 392.692139][ T1968] _printk+0xc7/0x100 [ 392.692142][ T1968] ? snapshot_read.cold+0x21/0x21 [ 392.692144][ T1968] ? ipv6_mc_down+0x17e/0x2d0 [ 392.692149][ T1968] br_set_state+0x22f/0x430 [bridge] [ 392.692161][ T1968] br_stp_disable_port+0x11f/0x390 [bridge] [ 392.692169][ T1968] br_device_event+0x7b5/0x8f0 [bridge] [ 392.692177][ T1968] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 392.692180][ T1968] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 392.692183][ T1968] ? addrconf_notify+0x2c8/0xf30 [ 392.692186][ T1968] notifier_call_chain+0xae/0x300 [ 392.692189][ T1968] __dev_notify_flags+0x23c/0x3c0 [ 392.692192][ T1968] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 392.692194][ T1968] ? netif_change_name+0x8c0/0x8c0 [ 392.692196][ T1968] ? netif_set_allmulti+0x250/0x250 [ 392.692198][ T1968] ? __kernel_text_address+0x12/0x30 [ 392.692200][ T1968] ? write_profile+0xf0/0xf0 [ 392.692202][ T1968] ? unwind_get_return_address+0x67/0xd0 [ 392.692205][ T1968] netif_change_flags+0xfe/0x190 [ 392.692207][ T1968] do_setlink.isra.0+0x1cc3/0x2750 [ 392.692217][ T1968] ? stack_trace_save+0x93/0xc0 [ 392.692218][ T1968] ? rtnl_link_get_size+0x350/0x350 [ 392.692219][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692221][ T1968] ? stack_depot_save_flags+0x1bc/0x790 [ 392.692225][ T1968] ? kasan_save_stack+0x3d/0x50 [ 392.692227][ T1968] ? kasan_save_stack+0x2f/0x50 [ 392.692229][ T1968] ? kasan_save_track+0x14/0x30 [ 392.692230][ T1968] ? __kasan_kmalloc+0x7b/0x90 [ 392.692232][ T1968] ? __kmalloc_cache_noprof+0x29a/0x620 [ 392.692233][ T1968] ? rtnl_newlink+0x124/0x11c0 [ 392.692234][ T1968] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 392.692235][ T1968] ? netlink_rcv_skb+0x14e/0x3a0 [ 392.692238][ T1968] ? netlink_unicast+0x486/0x750 [ 392.692239][ T1968] ? netlink_sendmsg+0x735/0xc60 [ 392.692240][ T1968] ? ____sys_sendmsg+0x415/0x880 [ 392.692243][ T1968] ? ___sys_sendmsg+0x14e/0x1d0 [ 392.692244][ T1968] ? __sys_sendmsg+0x12c/0x1d0 [ 392.692246][ T1968] ? do_syscall_64+0xff/0x530 [ 392.692248][ T1968] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 392.692250][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692252][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692253][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692255][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692257][ T1968] ? trace_contention_end+0xb3/0x180 [ 392.692260][ T1968] ? __mutex_lock+0x1db/0x1ea0 [ 392.692261][ T1968] ? __mutex_lock+0x9a3/0x1ea0 [ 392.692262][ T1968] ? rtnl_newlink+0x9a8/0x11c0 [ 392.692264][ T1968] ? ww_mutex_lock+0x160/0x160 [ 392.692266][ T1968] ? __rtnl_newlink+0x3fa/0xa50 [ 392.692268][ T1968] rtnl_newlink+0x9f1/0x11c0 [ 392.692270][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692272][ T1968] ? rtnl_bridge_getlink+0x850/0x850 [ 392.692273][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692274][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692276][ T1968] ? is_bpf_text_address+0x3e/0x110 [ 392.692279][ T1968] ? is_bpf_text_address+0x72/0x110 [ 392.692281][ T1968] ? kernel_text_address+0x149/0x170 [ 392.692282][ T1968] ? __kernel_text_address+0x12/0x30 [ 392.692283][ T1968] ? write_profile+0xf0/0xf0 [ 392.692285][ T1968] ? unwind_get_return_address+0x67/0xd0 [ 392.692286][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692288][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692289][ T1968] ? cap_capable+0x1d7/0x3d0 [ 392.692293][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692294][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692296][ T1968] ? rtnl_bridge_getlink+0x850/0x850 [ 392.692297][ T1968] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 392.692298][ T1968] ? rtnl_link_fill+0x920/0x920 [ 392.692300][ T1968] ? filter_irq_stacks+0xd0/0xd0 [ 392.692301][ T1968] ? stack_depot_save_flags+0x1bc/0x790 [ 392.692303][ T1968] netlink_rcv_skb+0x14e/0x3a0 [ 392.692305][ T1968] ? rtnl_link_fill+0x920/0x920 [ 392.692306][ T1968] ? lock_release+0x184/0x1f0 [ 392.692308][ T1968] ? netlink_ack+0xcf0/0xcf0 [ 392.692309][ T1968] ? __netlink_lookup+0xb9/0x6b0 [ 392.692311][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692313][ T1968] ? lock_release+0x184/0x1f0 [ 392.692315][ T1968] netlink_unicast+0x486/0x750 [ 392.692317][ T1968] ? netlink_attachskb+0x810/0x810 [ 392.692318][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692320][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692322][ T1968] ? lock_acquire+0x13c/0x160 [ 392.692323][ T1968] netlink_sendmsg+0x735/0xc60 [ 392.692325][ T1968] ? netlink_unicast+0x750/0x750 [ 392.692327][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692329][ T1968] ? lock_release+0x184/0x1f0 [ 392.692330][ T1968] ____sys_sendmsg+0x415/0x880 [ 392.692332][ T1968] ? copy_msghdr_from_user+0x279/0x420 [ 392.692336][ T1968] ? get_timestamp.constprop.0+0x390/0x390 [ 392.692338][ T1968] ? move_addr_to_kernel+0x40/0x40 [ 392.692339][ T1968] ? filter_irq_stacks+0xd0/0xd0 [ 392.692341][ T1968] ___sys_sendmsg+0x14e/0x1d0 [ 392.692343][ T1968] ? copy_msghdr_from_user+0x420/0x420 [ 392.692348][ T1968] __sys_sendmsg+0x12c/0x1d0 [ 392.692350][ T1968] ? __sys_sendmsg_sock+0x20/0x20 [ 392.692352][ T1968] ? rcu_is_watching+0x16/0xd0 [ 392.692354][ T1968] do_syscall_64+0xff/0x530 [ 392.692356][ T1968] ? exc_page_fault+0xee/0x100 [ 392.692358][ T1968] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 392.692359][ T1968] RIP: 0033:0x7fb302d5454e [ 392.692361][ T1968] 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 [ 392.692363][ T1968] RSP: 002b:00007ffd96958660 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 392.692367][ T1968] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fb302d5454e [ 392.692368][ T1968] RDX: 0000000000000000 RSI: 00007ffd96958710 RDI: 0000000000000005 [ 392.692369][ T1968] RBP: 00007ffd96958670 R08: 0000000000000000 R09: 0000000000000000 [ 392.692370][ T1968] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9186fa [ 392.692370][ T1968] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd96958dd0 [ 392.692373][ T1968] [ 392.726112][ T1968] ip (1968) used greatest stack depth: 23016 bytes left [ 392.753150][ T1970] br12: port 1(veth1.12) entered disabled state [ 392.793798][ T1974] veth2.12: left allmulticast mode [ 392.794021][ T1974] veth2.12: left promiscuous mode [ 392.794393][ T1974] br12: port 2(veth2.12) entered disabled state [ 392.818328][ T1976] veth1.12: left allmulticast mode [ 392.818583][ T1976] veth1.12: left promiscuous mode [ 392.819013][ T1976] br12: port 1(veth1.12) entered disabled state [ 392.960521][ T1987] br11: port 2(veth2.11) entered disabled state [ 392.986533][ T1989] br11: port 1(veth1.11) entered disabled state [ 393.031993][ T1994] veth2.11: left allmulticast mode [ 393.032170][ T1994] veth2.11: left promiscuous mode [ 393.032469][ T1994] br11: port 2(veth2.11) entered disabled state [ 393.050359][ T1996] veth1.11: left allmulticast mode [ 393.050558][ T1996] veth1.11: left promiscuous mode [ 393.050894][ T1996] br11: port 1(veth1.11) entered disabled state [ 393.177107][ T2004] br10: port 2(veth2.10) entered disabled state [ 393.194238][ T2006] br10: port 1(veth1.10) entered disabled state [ 393.235532][ T2011] veth2.10: left allmulticast mode [ 393.235729][ T2011] veth2: left allmulticast mode [ 393.235922][ T2011] veth2.10: left promiscuous mode [ 393.236106][ T2011] veth2: left promiscuous mode [ 393.236474][ T2011] br10: port 2(veth2.10) entered disabled state [ 393.260312][ T2013] veth1.10: left allmulticast mode [ 393.260485][ T2013] veth1: left allmulticast mode [ 393.260633][ T2013] veth1.10: left promiscuous mode [ 393.260767][ T2013] veth1: left promiscuous mode [ 393.261064][ T2013] br10: port 1(veth1.10) entered disabled state