[ 10.089871][ T188] ip (188) used greatest stack depth: 23936 bytes left [ 10.089887][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.089889][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 10.089891][ T188] preempt_count: 2, expected: 0 [ 10.089891][ T188] RCU nest depth: 0, expected: 0 [ 10.089893][ T188] locks held by ip/188: 5, last CPU#2: [ 10.089895][ T188] #0: ffffffff992027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.089907][ T188] #1: ffffffff99369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.089913][ T188] #2: ffffffff99369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.089917][ T188] #3: ffffffff99289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.089921][ T188] #4: ffffffff99289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.089925][ T188] irq event stamp: 49908 [ 10.089926][ T188] hardirqs last enabled at (49907): [] __down_trylock_console_sem+0x86/0xa0 [ 10.089928][ T188] hardirqs last disabled at (49908): [] console_emit_next_record+0x3d4/0x4c0 [ 10.089930][ T188] softirqs last enabled at (49348): [] handle_softirqs+0x67c/0x900 [ 10.089932][ T188] softirqs last disabled at (49343): [] __irq_exit_rcu+0x145/0x1c0 [ 10.089935][ T188] Preemption disabled at: [ 10.089935][ T188] [<0000000000000000>] 0x0 [ 10.089942][ T188] CPU: 2 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.089945][ T188] Tainted: [W]=WARN [ 10.089946][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.089948][ T188] Call Trace: [ 10.089950][ T188] [ 10.089951][ T188] dump_stack_lvl+0x6f/0xa0 [ 10.089957][ T188] __might_resched.cold+0x1fe/0x2c1 [ 10.089962][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.089966][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 10.089971][ T188] __kmalloc_noprof+0x443/0x760 [ 10.089973][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 10.089979][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 10.089982][ T188] alloc_buf.isra.0+0x4b/0x260 [ 10.089985][ T188] put_chars+0x1e1/0x2f0 [ 10.089988][ T188] ? __send_to_port+0x420/0x420 [ 10.089989][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.089994][ T188] ? validate_chain+0x38b/0xc20 [ 10.089999][ T188] hvc_console_print+0x292/0x780 [ 10.090006][ T188] ? hvc_write+0x3a0/0x3a0 [ 10.090008][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.090010][ T188] ? lock_acquire+0x13c/0x160 [ 10.090014][ T188] console_emit_next_record+0x22f/0x4c0 [ 10.090018][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 10.090020][ T188] ? console_flush_one_record+0x106/0x710 [ 10.090023][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.090025][ T188] ? lock_acquire+0x13c/0x160 [ 10.090030][ T188] console_flush_one_record+0x46f/0x710 [ 10.090034][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 10.090035][ T188] ? __lock_acquire+0x518/0xc20 [ 10.090041][ T188] console_unlock+0xee/0x1f0 [ 10.090043][ T188] ? console_flush_one_record+0x710/0x710 [ 10.090045][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.090047][ T188] ? lock_acquire+0x60/0x160 [ 10.090051][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.090053][ T188] ? vprintk_emit+0x320/0x3e0 [ 10.090056][ T188] vprintk_emit+0x37c/0x3e0 [ 10.090060][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 10.090063][ T188] ? __lock_acquire+0x518/0xc20 [ 10.090067][ T188] _printk+0xc7/0x100 [ 10.090071][ T188] ? snapshot_read.cold+0x21/0x21 [ 10.090074][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.090076][ T188] ? __rwlock_init+0x150/0x150 [ 10.090080][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.090083][ T188] do_exit.cold+0x82/0x9c [ 10.090086][ T188] ? exit_notify+0x890/0x890 [ 10.090088][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 10.090091][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.090095][ T188] do_group_exit+0xb8/0x370 [ 10.090098][ T188] __x64_sys_exit_group+0x3c/0x50 [ 10.090100][ T188] x64_sys_call+0x1567/0x1570 [ 10.090102][ T188] do_syscall_64+0xff/0x530 [ 10.090107][ T188] ? exc_page_fault+0xee/0x100 [ 10.090110][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.090113][ T188] RIP: 0033:0x7f2a5a16c1b8 [ 10.090115][ T188] Code: Unable to access opcode bytes at 0x7f2a5a16c18e. [ 10.090117][ T188] RSP: 002b:00007ffd600c0698 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.090119][ T188] RAX: ffffffffffffffda RBX: 00007f2a5a29cf88 RCX: 00007f2a5a16c1b8 [ 10.090121][ T188] RDX: 00007f2a59eb6fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.090122][ T188] RBP: 00007ffd600c06f0 R08: 0000000000000000 R09: 0000000000008010 [ 10.090123][ T188] R10: 00007ffd600c04b0 R11: 0000000000000246 R12: 0000000000000001 [ 10.090124][ T188] R13: 0000000000000000 R14: 00007f2a5a29b680 R15: 00007f2a5a29cfa0 [ 10.090132][ T188] [ 11.056847][ T245] 8021q: 802.1Q VLAN Support v1.8 [ 12.444976][ T316] GACT probability NOT on [ 12.444995][ T316] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.444998][ T316] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 316, name: modprobe [ 12.445000][ T316] preempt_count: 1, expected: 0 [ 12.445001][ T316] RCU nest depth: 0, expected: 0 [ 12.445002][ T316] locks held by modprobe/316: 4, last CPU#2: [ 12.445004][ T316] #0: ffffffff99369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.445017][ T316] #1: ffffffff99369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.445021][ T316] #2: ffffffff99289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.445026][ T316] #3: ffffffff99289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.445030][ T316] irq event stamp: 17064 [ 12.445030][ T316] hardirqs last enabled at (17063): [] __down_trylock_console_sem+0x86/0xa0 [ 12.445033][ T316] hardirqs last disabled at (17064): [] console_emit_next_record+0x3d4/0x4c0 [ 12.445035][ T316] softirqs last enabled at (16276): [] handle_softirqs+0x67c/0x900 [ 12.445038][ T316] softirqs last disabled at (15357): [] __irq_exit_rcu+0x145/0x1c0 [ 12.445040][ T316] Preemption disabled at: [ 12.445041][ T316] [] vprintk_emit+0x31b/0x3e0 [ 12.445047][ T316] CPU: 2 UID: 0 PID: 316 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.445050][ T316] Tainted: [W]=WARN [ 12.445051][ T316] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.445053][ T316] Call Trace: [ 12.445055][ T316] [ 12.445056][ T316] dump_stack_lvl+0x6f/0xa0 [ 12.445062][ T316] ? vprintk_emit+0x31b/0x3e0 [ 12.445064][ T316] __might_resched.cold+0x1fe/0x2c1 [ 12.445069][ T316] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.445073][ T316] ? __kmalloc_noprof+0xdb/0x760 [ 12.445078][ T316] __kmalloc_noprof+0x443/0x760 [ 12.445080][ T316] ? alloc_buf.isra.0+0x4b/0x260 [ 12.445086][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 12.445089][ T316] alloc_buf.isra.0+0x4b/0x260 [ 12.445092][ T316] put_chars+0x1e1/0x2f0 [ 12.445094][ T316] ? desc_read_finalized_seq+0x79/0x120 [ 12.445096][ T316] ? __send_to_port+0x420/0x420 [ 12.445099][ T316] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.445105][ T316] ? validate_chain+0x38b/0xc20 [ 12.445109][ T316] hvc_console_print+0x292/0x780 [ 12.445112][ T316] ? __lock_acquire+0x518/0xc20 [ 12.445114][ T316] ? __lock_acquire+0x518/0xc20 [ 12.445119][ T316] ? hvc_write+0x3a0/0x3a0 [ 12.445120][ T316] ? console_emit_next_record+0x1df/0x4c0 [ 12.445123][ T316] ? rcu_is_watching+0x16/0xd0 [ 12.445127][ T316] ? lock_acquire+0x13c/0x160 [ 12.445131][ T316] console_emit_next_record+0x22f/0x4c0 [ 12.445134][ T316] ? devkmsg_read+0x4b0/0x4b0 [ 12.445136][ T316] ? console_flush_one_record+0x106/0x710 [ 12.445139][ T316] ? rcu_is_watching+0x16/0xd0 [ 12.445142][ T316] ? lock_acquire+0x13c/0x160 [ 12.445146][ T316] console_flush_one_record+0x46f/0x710 [ 12.445150][ T316] ? console_emit_next_record+0x4c0/0x4c0 [ 12.445152][ T316] ? __lock_acquire+0x518/0xc20 [ 12.445157][ T316] console_unlock+0xee/0x1f0 [ 12.445160][ T316] ? console_flush_one_record+0x710/0x710 [ 12.445162][ T316] ? rcu_is_watching+0x16/0xd0 [ 12.445164][ T316] ? lock_acquire+0x60/0x160 [ 12.445168][ T316] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.445170][ T316] ? vprintk_emit+0x320/0x3e0 [ 12.445173][ T316] vprintk_emit+0x37c/0x3e0 [ 12.445177][ T316] ? wake_up_klogd_work_func+0x90/0x90 [ 12.445183][ T316] ? gact_cleanup_module+0xf0/0xf0 [act_gact] [ 12.445186][ T316] _printk+0xc7/0x100 [ 12.445190][ T316] ? snapshot_read.cold+0x21/0x21 [ 12.445196][ T316] ? do_one_initcall+0x113/0x4f0 [ 12.445199][ T316] gact_init_module+0x15/0x1000 [act_gact] [ 12.445202][ T316] do_one_initcall+0x124/0x4f0 [ 12.445205][ T316] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 12.445209][ T316] ? do_init_module+0xae/0x8a0 [ 12.445213][ T316] ? kasan_unpoison+0x44/0x70 [ 12.445217][ T316] do_init_module+0x2ad/0x8a0 [ 12.445221][ T316] ? lockdep_hardirqs_on+0x91/0x130 [ 12.445225][ T316] ? free_module+0x470/0x470 [ 12.445229][ T316] ? kfree+0x123/0x590 [ 12.445233][ T316] load_module+0x1424/0x1bb0 [ 12.445240][ T316] ? layout_and_allocate.isra.0+0x680/0x680 [ 12.445244][ T316] ? __ia32_sys_fsconfig+0x180/0x180 [ 12.445247][ T316] ? ovl_real_file+0x172/0x1e0 [ 12.445251][ T316] ? backing_file_read_iter+0x541/0x790 [ 12.445254][ T316] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.445258][ T316] init_module_from_file+0x11f/0x1a0 [ 12.445261][ T316] ? __do_sys_init_module+0x220/0x220 [ 12.445269][ T316] ? __rwlock_init+0x150/0x150 [ 12.445273][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 12.445276][ T316] idempotent_init_module+0x37b/0x7c0 [ 12.445280][ T316] ? init_module_from_file+0x1a0/0x1a0 [ 12.445286][ T316] ? rcu_is_watching+0x16/0xd0 [ 12.445288][ T316] ? cap_capable+0x1d7/0x3d0 [ 12.445295][ T316] __x64_sys_finit_module+0xdf/0x190 [ 12.445297][ T316] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.445301][ T316] do_syscall_64+0xff/0x530 [ 12.445303][ T316] ? exc_page_fault+0xee/0x100 [ 12.445305][ T316] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.445308][ T316] RIP: 0033:0x7f4f161b737d [ 12.445311][ T316] 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 [ 12.445313][ T316] RSP: 002b:00007ffc3ae30ba8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 12.445316][ T316] RAX: ffffffffffffffda RBX: 0000563646f62810 RCX: 00007f4f161b737d [ 12.445317][ T316] RDX: 0000000000000000 RSI: 0000563631b34ffd RDI: 0000000000000000 [ 12.445318][ T316] RBP: 00007ffc3ae30c40 R08: 0000000000000000 R09: 0000000000000000 [ 12.445319][ T316] R10: 0000000000000000 R11: 0000000000000246 R12: 0000563631b34ffd [ 12.445320][ T316] R13: 0000563646f62960 R14: 0000000000040000 R15: 0000000000000000 [ 12.445327][ T316] [ 13.182017][ T352] br10: port 1(veth1.10) entered blocking state [ 13.182949][ T352] br10: port 1(veth1.10) entered disabled state [ 13.183542][ T352] veth1.10: entered allmulticast mode [ 13.183926][ T352] veth1: entered allmulticast mode [ 13.186813][ T352] veth1.10: entered promiscuous mode [ 13.187302][ T352] veth1: entered promiscuous mode [ 13.427411][ T370] br11: port 1(veth1.11) entered blocking state [ 13.427795][ T370] br11: port 1(veth1.11) entered disabled state [ 13.428431][ T370] veth1.11: entered allmulticast mode [ 13.430072][ T370] veth1.11: entered promiscuous mode [ 13.822343][ T401] br10: port 2(veth2.10) entered blocking state [ 13.822365][ T401] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.822368][ T401] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 401, name: ip [ 13.822370][ T401] preempt_count: 1, expected: 0 [ 13.822372][ T401] RCU nest depth: 0, expected: 0 [ 13.822373][ T401] locks held by ip/401: 5, last CPU#0: [ 13.822376][ T401] #0: ffffffff99ad2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.822391][ T401] #1: ffffffff99369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.822400][ T401] #2: ffffffff99369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.822405][ T401] #3: ffffffff99289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.822411][ T401] #4: ffffffff99289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.822418][ T401] irq event stamp: 19984 [ 13.822419][ T401] hardirqs last enabled at (19983): [] __down_trylock_console_sem+0x86/0xa0 [ 13.822423][ T401] hardirqs last disabled at (19984): [] console_emit_next_record+0x3d4/0x4c0 [ 13.822426][ T401] softirqs last enabled at (19926): [] __alloc_skb+0x4c2/0x5f0 [ 13.822430][ T401] softirqs last disabled at (19924): [] __alloc_skb+0x4c2/0x5f0 [ 13.822434][ T401] Preemption disabled at: [ 13.822435][ T401] [] vprintk_emit+0x31b/0x3e0 [ 13.822442][ T401] CPU: 0 UID: 0 PID: 401 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.822447][ T401] Tainted: [W]=WARN [ 13.822448][ T401] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.822450][ T401] Call Trace: [ 13.822453][ T401] [ 13.822455][ T401] dump_stack_lvl+0x6f/0xa0 [ 13.822462][ T401] ? vprintk_emit+0x31b/0x3e0 [ 13.822465][ T401] __might_resched.cold+0x1fe/0x2c1 [ 13.822471][ T401] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.822477][ T401] ? __kmalloc_noprof+0xdb/0x760 [ 13.822484][ T401] __kmalloc_noprof+0x443/0x760 [ 13.822488][ T401] ? alloc_buf.isra.0+0x4b/0x260 [ 13.822496][ T401] ? do_raw_spin_unlock+0x59/0x250 [ 13.822499][ T401] alloc_buf.isra.0+0x4b/0x260 [ 13.822505][ T401] put_chars+0x1e1/0x2f0 [ 13.822507][ T401] ? prb_final_commit+0x50/0x50 [ 13.822510][ T401] ? __send_to_port+0x420/0x420 [ 13.822515][ T401] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.822521][ T401] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.822525][ T401] ? validate_chain+0x38b/0xc20 [ 13.822531][ T401] hvc_console_print+0x292/0x780 [ 13.822535][ T401] ? mark_usage+0x61/0x170 [ 13.822538][ T401] ? __lock_acquire+0x518/0xc20 [ 13.822540][ T401] ? __lock_acquire+0x518/0xc20 [ 13.822547][ T401] ? hvc_write+0x3a0/0x3a0 [ 13.822550][ T401] ? console_emit_next_record+0x1df/0x4c0 [ 13.822555][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.822559][ T401] ? lock_acquire+0x13c/0x160 [ 13.822566][ T401] console_emit_next_record+0x22f/0x4c0 [ 13.822572][ T401] ? devkmsg_read+0x4b0/0x4b0 [ 13.822574][ T401] ? console_flush_one_record+0x106/0x710 [ 13.822579][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.822583][ T401] ? lock_acquire+0x13c/0x160 [ 13.822590][ T401] console_flush_one_record+0x46f/0x710 [ 13.822596][ T401] ? console_emit_next_record+0x4c0/0x4c0 [ 13.822599][ T401] ? __lock_acquire+0x518/0xc20 [ 13.822608][ T401] console_unlock+0xee/0x1f0 [ 13.822612][ T401] ? console_flush_one_record+0x710/0x710 [ 13.822615][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.822618][ T401] ? lock_acquire+0x60/0x160 [ 13.822624][ T401] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.822627][ T401] ? vprintk_emit+0x320/0x3e0 [ 13.822632][ T401] vprintk_emit+0x37c/0x3e0 [ 13.822639][ T401] ? wake_up_klogd_work_func+0x90/0x90 [ 13.822642][ T401] ? __lock_release.isra.0+0x69/0x1a0 [ 13.822651][ T401] _printk+0xc7/0x100 [ 13.822656][ T401] ? snapshot_read.cold+0x21/0x21 [ 13.822662][ T401] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.822680][ T401] ? do_setlink.isra.0+0xa31/0x2750 [ 13.822682][ T401] ? rtnl_newlink+0x9f1/0x11c0 [ 13.822684][ T401] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.822692][ T401] br_set_state+0x22f/0x430 [bridge] [ 13.822709][ T401] br_init_port+0xc4/0x200 [bridge] [ 13.822723][ T401] new_nbp+0x39c/0x580 [bridge] [ 13.822739][ T401] br_add_if+0x212/0x1320 [bridge] [ 13.822751][ T401] ? is_bpf_text_address+0x72/0x110 [ 13.822756][ T401] ? kernel_text_address+0x149/0x170 [ 13.822760][ T401] ? __kernel_text_address+0x12/0x30 [ 13.822767][ T401] do_set_master+0x357/0x580 [ 13.822775][ T401] do_setlink.isra.0+0xa31/0x2750 [ 13.822780][ T401] ? stack_trace_save+0x93/0xc0 [ 13.822784][ T401] ? rtnl_link_get_size+0x350/0x350 [ 13.822786][ T401] ? rcu_read_lock_any_held+0x66/0x90 [ 13.822789][ T401] ? stack_depot_save_flags+0x38e/0x790 [ 13.822793][ T401] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.822797][ T401] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.822800][ T401] ? validate_chain+0x38b/0xc20 [ 13.822804][ T401] ? kasan_save_stack+0x3d/0x50 [ 13.822808][ T401] ? kasan_save_stack+0x2f/0x50 [ 13.822810][ T401] ? kasan_save_track+0x14/0x30 [ 13.822814][ T401] ? __lock_acquire+0x518/0xc20 [ 13.822817][ T401] ? netlink_seq_next+0xe/0x60 [ 13.822821][ T401] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.822828][ T401] ? lock_acquire.part.0+0xd4/0x280 [ 13.822831][ T401] ? rtnl_newlink+0x9a8/0x11c0 [ 13.822840][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.822844][ T401] ? lock_acquire+0x13c/0x160 [ 13.822846][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.822849][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.822852][ T401] ? trace_contention_end+0xb3/0x180 [ 13.822857][ T401] ? __mutex_lock+0x1db/0x1ea0 [ 13.822861][ T401] ? __mutex_lock+0x9a3/0x1ea0 [ 13.822865][ T401] ? rtnl_newlink+0x9a8/0x11c0 [ 13.822870][ T401] ? ww_mutex_lock+0x160/0x160 [ 13.822873][ T401] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.822882][ T401] ? __rtnl_newlink+0x3fa/0xa50 [ 13.822891][ T401] rtnl_newlink+0x9f1/0x11c0 [ 13.822901][ T401] ? rtnl_bridge_getlink+0x850/0x850 [ 13.822904][ T401] ? __lock_acquire+0x518/0xc20 [ 13.822911][ T401] ? lock_acquire.part.0+0xd4/0x280 [ 13.822914][ T401] ? find_held_lock+0x2b/0x80 [ 13.822917][ T401] ? rtnl_bridge_getlink+0x850/0x850 [ 13.822920][ T401] ? __lock_release.isra.0+0x69/0x1a0 [ 13.822926][ T401] ? rtnl_bridge_getlink+0x850/0x850 [ 13.822930][ T401] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.822935][ T401] ? rtnl_link_fill+0x920/0x920 [ 13.822937][ T401] ? __lock_acquire+0x518/0xc20 [ 13.822945][ T401] ? lock_acquire.part.0+0xd4/0x280 [ 13.822948][ T401] ? find_held_lock+0x2b/0x80 [ 13.822954][ T401] netlink_rcv_skb+0x14e/0x3a0 [ 13.822957][ T401] ? rtnl_link_fill+0x920/0x920 [ 13.822962][ T401] ? netlink_ack+0xcf0/0xcf0 [ 13.822973][ T401] ? netlink_deliver_tap+0xc5/0x330 [ 13.822975][ T401] ? netlink_deliver_tap+0x13c/0x330 [ 13.822983][ T401] netlink_unicast+0x486/0x750 [ 13.822989][ T401] ? netlink_attachskb+0x810/0x810 [ 13.822993][ T401] ? __lock_acquire+0x518/0xc20 [ 13.823001][ T401] netlink_sendmsg+0x735/0xc60 [ 13.823007][ T401] ? netlink_unicast+0x750/0x750 [ 13.823014][ T401] ? __might_fault+0x97/0x140 [ 13.823022][ T401] ____sys_sendmsg+0x415/0x880 [ 13.823026][ T401] ? copy_msghdr_from_user+0x279/0x420 [ 13.823029][ T401] ? get_timestamp.constprop.0+0x390/0x390 [ 13.823031][ T401] ? move_addr_to_kernel+0x40/0x40 [ 13.823044][ T401] ___sys_sendmsg+0x14e/0x1d0 [ 13.823049][ T401] ? copy_msghdr_from_user+0x420/0x420 [ 13.823079][ T401] __sys_sendmsg+0x12c/0x1d0 [ 13.823083][ T401] ? __sys_sendmsg_sock+0x20/0x20 [ 13.823094][ T401] ? rcu_is_watching+0x16/0xd0 [ 13.823100][ T401] do_syscall_64+0xff/0x530 [ 13.823106][ T401] ? exc_page_fault+0xee/0x100 [ 13.823111][ T401] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.823114][ T401] RIP: 0033:0x7f234ca0354e [ 13.823119][ T401] 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.823121][ T401] RSP: 002b:00007ffd3200a120 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.823125][ T401] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f234ca0354e [ 13.823127][ T401] RDX: 0000000000000000 RSI: 00007ffd3200a1d0 RDI: 0000000000000005 [ 13.823129][ T401] RBP: 00007ffd3200a130 R08: 0000000000000000 R09: 0000000000000000 [ 13.823130][ T401] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b2f2 [ 13.823131][ T401] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd3200a890 [ 13.823144][ T401] [ 13.894243][ T401] br10: port 2(veth2.10) entered disabled state [ 13.894599][ T401] veth2.10: entered allmulticast mode [ 13.894815][ T401] veth2: entered allmulticast mode [ 13.896528][ T401] veth2.10: entered promiscuous mode [ 13.896776][ T401] veth2: entered promiscuous mode [ 13.903542][ T401] ip (401) used greatest stack depth: 23336 bytes left [ 14.102625][ T418] br11: port 2(veth2.11) entered blocking state [ 14.102971][ T418] br11: port 2(veth2.11) entered disabled state [ 14.103312][ T418] veth2.11: entered allmulticast mode [ 14.105231][ T418] veth2.11: entered promiscuous mode [ 14.290062][ T430] br10: port 2(veth2.10) entered blocking state [ 14.290733][ T430] br10: port 2(veth2.10) entered forwarding state [ 14.291338][ T430] br10: port 1(veth1.10) entered blocking state [ 14.291655][ T430] br10: port 1(veth1.10) entered forwarding state [ 14.343176][ T435] br11: port 2(veth2.11) entered blocking state [ 14.343537][ T435] br11: port 2(veth2.11) entered forwarding state [ 14.344003][ T435] br11: port 1(veth1.11) entered blocking state [ 14.344317][ T435] br11: port 1(veth1.11) entered forwarding state [ 29.545167][ C3] [ 29.545184][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.545187][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 494, name: mausezahn [ 29.545189][ C3] preempt_count: 103, expected: 0 [ 29.545189][ C3] RCU nest depth: 0, expected: 0 [ 29.545190][ C3] INFO: lockdep is turned off. [ 29.545191][ C3] irq event stamp: 4169066 [ 29.545192][ C3] hardirqs last enabled at (4169066): [] _raw_spin_unlock_irq+0x28/0x50 [ 29.545201][ C3] hardirqs last disabled at (4169065): [] _raw_spin_lock_irq+0x4a/0x50 [ 29.545203][ C3] softirqs last enabled at (4169058): [] handle_softirqs+0x67c/0x900 [ 29.545206][ C3] softirqs last disabled at (4169063): [] __irq_exit_rcu+0x145/0x1c0 [ 29.545208][ C3] Preemption disabled at: [ 29.545208][ C3] [] handle_softirqs+0xf6/0x900 [ 29.545214][ C3] CPU: 3 UID: 0 PID: 494 Comm: mausezahn Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.545218][ C3] Tainted: [W]=WARN [ 29.545219][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.545221][ C3] Call Trace: [ 29.545222][ C3] [ 29.545224][ C3] dump_stack_lvl+0x6f/0xa0 [ 29.545229][ C3] ? handle_softirqs+0xf6/0x900 [ 29.545231][ C3] __might_resched.cold+0x1fe/0x2c1 [ 29.545235][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.545238][ C3] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 29.545240][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545244][ C3] __kmalloc_noprof+0x443/0x760 [ 29.545247][ C3] ? __rwlock_init+0x150/0x150 [ 29.545250][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 29.545253][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 29.545255][ C3] alloc_buf.isra.0+0x4b/0x260 [ 29.545257][ C3] put_chars+0x1e1/0x2f0 [ 29.545258][ C3] ? prb_final_commit+0x50/0x50 [ 29.545260][ C3] ? __send_to_port+0x420/0x420 [ 29.545261][ C3] ? printk_get_next_message+0x240/0x650 [ 29.545265][ C3] hvc_console_print+0x292/0x780 [ 29.545268][ C3] ? hvc_write+0x3a0/0x3a0 [ 29.545270][ C3] ? do_raw_spin_lock+0x131/0x280 [ 29.545271][ C3] ? vsnprintf+0x668/0x1700 [ 29.545274][ C3] ? __rwlock_init+0x150/0x150 [ 29.545275][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545277][ C3] ? lock_acquire+0x13c/0x160 [ 29.545280][ C3] console_emit_next_record+0x22f/0x4c0 [ 29.545282][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 29.545284][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545286][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545288][ C3] ? lock_acquire+0x13c/0x160 [ 29.545290][ C3] ? console_flush_one_record+0x111/0x710 [ 29.545291][ C3] console_flush_one_record+0x46f/0x710 [ 29.545293][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 29.545296][ C3] console_unlock+0xee/0x1f0 [ 29.545298][ C3] ? lock_acquire+0x13c/0x160 [ 29.545299][ C3] ? console_flush_one_record+0x710/0x710 [ 29.545301][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545303][ C3] ? lock_release+0x184/0x1f0 [ 29.545304][ C3] ? lock_acquire+0x60/0x160 [ 29.545306][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.545308][ C3] ? vprintk_emit+0x320/0x3e0 [ 29.545310][ C3] vprintk_emit+0x37c/0x3e0 [ 29.545312][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 29.545314][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545316][ C3] ? lock_release+0x184/0x1f0 [ 29.545318][ C3] _printk+0xc7/0x100 [ 29.545321][ C3] ? snapshot_read.cold+0x21/0x21 [ 29.545322][ C3] ? arch_stack_walk+0xed/0x130 [ 29.545327][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 29.545329][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 29.545330][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 29.545333][ C3] mark_lock_irq+0x989/0x9c0 [ 29.545336][ C3] mark_lock+0x1d7/0xa00 [ 29.545338][ C3] mark_usage+0x42/0x170 [ 29.545339][ C3] __lock_acquire+0x388/0xc20 [ 29.545341][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.545343][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.545356][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545358][ C3] ? lock_acquire+0x13c/0x160 [ 29.545360][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.545366][ C3] _raw_spin_lock+0x33/0x40 [ 29.545368][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.545374][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.545380][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.545386][ C3] call_timer_fn+0x160/0x4d0 [ 29.545390][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 29.545391][ C3] ? debug_object_active_state+0x430/0x430 [ 29.545395][ C3] ? find_held_lock+0x2b/0x80 [ 29.545397][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 29.545399][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545401][ C3] __run_timers+0x68f/0xaa0 [ 29.545403][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.545409][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.545411][ C3] ? __lock_acquire+0x518/0xc20 [ 29.545414][ C3] ? __rwlock_init+0x150/0x150 [ 29.545416][ C3] run_timer_softirq+0xf0/0x160 [ 29.545418][ C3] ? __run_timers+0xaa0/0xaa0 [ 29.545420][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.545422][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.545424][ C3] handle_softirqs+0x1d3/0x900 [ 29.545426][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 29.545428][ C3] ? _local_bh_enable+0xc0/0xc0 [ 29.545430][ C3] __irq_exit_rcu+0x145/0x1c0 [ 29.545431][ C3] irq_exit_rcu+0xe/0x30 [ 29.545432][ C3] sysvec_apic_timer_interrupt+0x4c/0xe0 [ 29.545435][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.545438][ C3] RIP: 0033:0x7fce862e08c4 [ 29.545441][ C3] Code: 00 00 00 00 00 66 66 2e 0f 1f 84 00 00 00 00 00 66 66 2e 0f 1f 84 00 00 00 00 00 66 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 6f 00 <48> 83 c0 10 66 0f 6f d8 66 0f 69 c2 66 0f 61 da 66 0f fe cb 66 0f [ 29.545442][ C3] RSP: 002b:00007ffeaf016e48 EFLAGS: 00000287 [ 29.545446][ C3] RAX: 000055f8f6826644 RBX: 000055f8f6826604 RCX: 0000000000001f48 [ 29.545448][ C3] RDX: 000055f8f6828544 RSI: 00000000000001f4 RDI: 000055f8f6826604 [ 29.545448][ C3] RBP: 00007ffeaf016e80 R08: 0000000000000fa3 R09: 0000000000001f46 [ 29.545449][ C3] R10: 0000000000000fa4 R11: 0000000000001f48 R12: 000055f8f6823830 [ 29.545450][ C3] R13: 0000000000000010 R14: 0000000000001f48 R15: 0000000000005da3 [ 29.545452][ C3] [ 29.561927][ C3] ======================================================== [ 29.562156][ C3] WARNING: possible irq lock inversion dependency detected [ 29.562347][ C3] 7.2.0-virtme #1 Tainted: G W [ 29.562541][ C3] -------------------------------------------------------- [ 29.562766][ C3] mausezahn/494 just changed the state of lock: [ 29.562927][ C3] ff1100000d9c2e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.563230][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 29.563459][ C3] (fs_reclaim){+.+.}-{0:0} [ 29.563462][ C3] [ 29.563462][ C3] [ 29.563462][ C3] and interrupts could create inverse lock ordering between them. [ 29.563462][ C3] [ 29.564001][ C3] [ 29.564001][ C3] other info that might help us debug this: [ 29.564185][ C3] Chain exists of: [ 29.564185][ C3] &br->lock --> console_owner --> fs_reclaim [ 29.564185][ C3] [ 29.564510][ C3] Possible interrupt unsafe locking scenario: [ 29.564510][ C3] [ 29.564741][ C3] CPU0 CPU1 [ 29.564871][ C3] ---- ---- [ 29.565044][ C3] lock(fs_reclaim); [ 29.565141][ C3] local_irq_disable(); [ 29.569179][ C3] lock(&br->lock); [ 29.569392][ C3] lock(console_owner); [ 29.569603][ C3] [ 29.569704][ C3] lock(&br->lock); [ 29.569806][ C3] [ 29.569806][ C3] *** DEADLOCK *** [ 29.569806][ C3] [ 29.570038][ C3] locks held by mausezahn/494: 1, last CPU#3: [ 29.570234][ C3] #0: ffa0000000577bc0 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 29.570530][ C3] [ 29.570530][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 29.570786][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 29.570921][ C3] HARDIRQ-ON-W at: [ 29.571020][ C3] __lock_acquire+0x388/0xc20 [ 29.571262][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.571569][ C3] fs_reclaim_acquire+0xd5/0x120 [ 29.571810][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 29.572172][ C3] kthread_create_worker_on_node+0xea/0x210 [ 29.572515][ C3] workqueue_init+0x2a/0x680 [ 29.572683][ C3] kernel_init_freeable+0x2fe/0x630 [ 29.572921][ C3] kernel_init+0x21/0x150 [ 29.573125][ C3] ret_from_fork+0x474/0x6b0 [ 29.573283][ C3] ret_from_fork_asm+0x11/0x20 [ 29.573485][ C3] SOFTIRQ-ON-W at: [ 29.573580][ C3] __lock_acquire+0x388/0xc20 [ 29.573778][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.573984][ C3] fs_reclaim_acquire+0xd5/0x120 [ 29.574145][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 29.574373][ C3] kthread_create_worker_on_node+0xea/0x210 [ 29.574603][ C3] workqueue_init+0x2a/0x680 [ 29.574756][ C3] kernel_init_freeable+0x2fe/0x630 [ 29.574989][ C3] kernel_init+0x21/0x150 [ 29.575191][ C3] ret_from_fork+0x474/0x6b0 [ 29.575346][ C3] ret_from_fork_asm+0x11/0x20 [ 29.575544][ C3] INITIAL USE at: [ 29.575637][ C3] __lock_acquire+0x388/0xc20 [ 29.575837][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.576044][ C3] fs_reclaim_acquire+0xd5/0x120 [ 29.576200][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 29.576434][ C3] kthread_create_worker_on_node+0xea/0x210 [ 29.576660][ C3] workqueue_init+0x2a/0x680 [ 29.576817][ C3] kernel_init_freeable+0x2fe/0x630 [ 29.577026][ C3] kernel_init+0x21/0x150 [ 29.577228][ C3] ret_from_fork+0x474/0x6b0 [ 29.577381][ C3] ret_from_fork_asm+0x11/0x20 [ 29.577580][ C3] } [ 29.577646][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 29.577876][ C3] ... acquired at: [ 29.577980][ C3] __lock_acquire+0x518/0xc20 [ 29.578153][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.578277][ C3] fs_reclaim_acquire+0xd5/0x120 [ 29.578442][ C3] __kmalloc_noprof+0xd3/0x760 [ 29.578567][ C3] alloc_buf.isra.0+0x4b/0x260 [ 29.578736][ C3] put_chars+0x1e1/0x2f0 [ 29.578860][ C3] hvc_console_print+0x292/0x780 [ 29.579034][ C3] console_emit_next_record+0x22f/0x4c0 [ 29.579159][ C3] console_flush_one_record+0x46f/0x710 [ 29.579325][ C3] console_unlock+0xee/0x1f0 [ 29.579449][ C3] vprintk_emit+0x37c/0x3e0 [ 29.579612][ C3] _printk+0xc7/0x100 [ 29.579707][ C3] sock_register+0x12d/0x290 [ 29.579874][ C3] inet6_init+0x1a6/0x3e0 [ 29.580006][ C3] do_one_initcall+0x124/0x4f0 [ 29.580178][ C3] kernel_init_freeable+0x596/0x630 [ 29.580302][ C3] kernel_init+0x21/0x150 [ 29.580472][ C3] ret_from_fork+0x474/0x6b0 [ 29.580596][ C3] ret_from_fork_asm+0x11/0x20 [ 29.580762][ C3] [ 29.580827][ C3] -> (console_owner){....}-{0:0} { [ 29.580961][ C3] INITIAL USE at: [ 29.581098][ C3] } [ 29.581165][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 29.581388][ C3] ... acquired at: [ 29.581480][ C3] __lock_acquire+0x518/0xc20 [ 29.581646][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.581771][ C3] console_lock_spinning_enable+0x5c/0x60 [ 29.581971][ C3] console_emit_next_record+0x1d1/0x4c0 [ 29.582093][ C3] console_flush_one_record+0x46f/0x710 [ 29.582268][ C3] console_unlock+0xee/0x1f0 [ 29.582392][ C3] vprintk_emit+0x37c/0x3e0 [ 29.582556][ C3] _printk+0xc7/0x100 [ 29.582650][ C3] br_set_state+0x22f/0x430 [bridge] [ 29.582829][ C3] br_init_port+0xc4/0x200 [bridge] [ 29.582962][ C3] br_stp_enable_bridge+0x1fc/0x340 [bridge] [ 29.583161][ C3] br_dev_open+0x94/0x120 [bridge] [ 29.583293][ C3] __dev_open+0x2f3/0x610 [ 29.583463][ C3] __dev_change_flags+0x4a4/0x660 [ 29.583588][ C3] netif_change_flags+0x87/0x190 [ 29.583753][ C3] do_setlink.isra.0+0x1cc3/0x2750 [ 29.583875][ C3] rtnl_newlink+0x9f1/0x11c0 [ 29.584048][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.584174][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 29.584339][ C3] netlink_unicast+0x486/0x750 [ 29.584463][ C3] netlink_sendmsg+0x735/0xc60 [ 29.584633][ C3] ____sys_sendmsg+0x415/0x880 [ 29.584758][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 29.584933][ C3] __sys_sendmsg+0x12c/0x1d0 [ 29.585060][ C3] do_syscall_64+0xff/0x530 [ 29.585186][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.585346][ C3] [ 29.585449][ C3] -> (&br->lock){+.-.}-{3:3} { [ 29.585575][ C3] HARDIRQ-ON-W at: [ 29.585710][ C3] __lock_acquire+0x388/0xc20 [ 29.585869][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.586073][ C3] _raw_spin_lock_bh+0x38/0x50 [ 29.586229][ C3] br_add_if+0x8c1/0x1320 [bridge] [ 29.586444][ C3] do_set_master+0x357/0x580 [ 29.586641][ C3] do_setlink.isra.0+0xa31/0x2750 [ 29.586797][ C3] rtnl_newlink+0x9f1/0x11c0 [ 29.587006][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.587205][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 29.587362][ C3] netlink_unicast+0x486/0x750 [ 29.587568][ C3] netlink_sendmsg+0x735/0xc60 [ 29.587725][ C3] ____sys_sendmsg+0x415/0x880 [ 29.587931][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 29.588131][ C3] __sys_sendmsg+0x12c/0x1d0 [ 29.588288][ C3] do_syscall_64+0xff/0x530 [ 29.588489][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.588724][ C3] IN-SOFTIRQ-W at: [ 29.588819][ C3] __lock_acquire+0x388/0xc20 [ 29.589022][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.589195][ C3] _raw_spin_lock+0x33/0x40 [ 29.589395][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.589630][ C3] call_timer_fn+0x160/0x4d0 [ 29.589792][ C3] __run_timers+0x68f/0xaa0 [ 29.590007][ C3] run_timer_softirq+0xf0/0x160 [ 29.590208][ C3] handle_softirqs+0x1d3/0x900 [ 29.590366][ C3] __irq_exit_rcu+0x145/0x1c0 [ 29.590567][ C3] irq_exit_rcu+0xe/0x30 [ 29.590725][ C3] sysvec_apic_timer_interrupt+0x4c/0xe0 [ 29.590964][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.591195][ C3] INITIAL USE at: [ 29.591289][ C3] __lock_acquire+0x388/0xc20 [ 29.591491][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.591690][ C3] _raw_spin_lock_bh+0x38/0x50 [ 29.591855][ C3] br_add_if+0x8c1/0x1320 [bridge] [ 29.592064][ C3] do_set_master+0x357/0x580 [ 29.592223][ C3] do_setlink.isra.0+0xa31/0x2750 [ 29.592425][ C3] rtnl_newlink+0x9f1/0x11c0 [ 29.592625][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.592781][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 29.593002][ C3] netlink_unicast+0x486/0x750 [ 29.593200][ C3] netlink_sendmsg+0x735/0xc60 [ 29.593355][ C3] ____sys_sendmsg+0x415/0x880 [ 29.593556][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 29.593715][ C3] __sys_sendmsg+0x12c/0x1d0 [ 29.593923][ C3] do_syscall_64+0xff/0x530 [ 29.594125][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.594310][ C3] } [ 29.594416][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 29.594609][ C3] ... acquired at: [ 29.594746][ C3] mark_lock+0x1d7/0xa00 [ 29.594874][ C3] mark_usage+0x42/0x170 [ 29.595086][ C3] __lock_acquire+0x388/0xc20 [ 29.595269][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.595443][ C3] _raw_spin_lock+0x33/0x40 [ 29.595569][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.595770][ C3] call_timer_fn+0x160/0x4d0 [ 29.595901][ C3] __run_timers+0x68f/0xaa0 [ 29.596071][ C3] run_timer_softirq+0xf0/0x160 [ 29.596195][ C3] handle_softirqs+0x1d3/0x900 [ 29.596364][ C3] __irq_exit_rcu+0x145/0x1c0 [ 29.596490][ C3] irq_exit_rcu+0xe/0x30 [ 29.596655][ C3] sysvec_apic_timer_interrupt+0x4c/0xe0 [ 29.596853][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.597015][ C3] [ 29.597077][ C3] [ 29.597077][ C3] stack backtrace: [ 29.597239][ C3] CPU: 3 UID: 0 PID: 494 Comm: mausezahn Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.597243][ C3] Tainted: [W]=WARN [ 29.597244][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.597245][ C3] Call Trace: [ 29.597247][ C3] [ 29.597248][ C3] dump_stack_lvl+0x6f/0xa0 [ 29.597253][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 29.597256][ C3] mark_lock_irq+0x989/0x9c0 [ 29.597259][ C3] mark_lock+0x1d7/0xa00 [ 29.597261][ C3] mark_usage+0x42/0x170 [ 29.597263][ C3] __lock_acquire+0x388/0xc20 [ 29.597265][ C3] lock_acquire.part.0+0xd4/0x280 [ 29.597266][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.597273][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.597276][ C3] ? lock_acquire+0x13c/0x160 [ 29.597278][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.597284][ C3] _raw_spin_lock+0x33/0x40 [ 29.597286][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.597292][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.597298][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.597304][ C3] call_timer_fn+0x160/0x4d0 [ 29.597306][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 29.597308][ C3] ? debug_object_active_state+0x430/0x430 [ 29.597312][ C3] ? find_held_lock+0x2b/0x80 [ 29.597314][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 29.597316][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.597318][ C3] __run_timers+0x68f/0xaa0 [ 29.597319][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.597326][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.597328][ C3] ? __lock_acquire+0x518/0xc20 [ 29.597331][ C3] ? __rwlock_init+0x150/0x150 [ 29.597333][ C3] run_timer_softirq+0xf0/0x160 [ 29.597335][ C3] ? __run_timers+0xaa0/0xaa0 [ 29.597337][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.597339][ C3] ? rcu_is_watching+0x16/0xd0 [ 29.597341][ C3] handle_softirqs+0x1d3/0x900 [ 29.597343][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 29.597344][ C3] ? _local_bh_enable+0xc0/0xc0 [ 29.597346][ C3] __irq_exit_rcu+0x145/0x1c0 [ 29.597348][ C3] irq_exit_rcu+0xe/0x30 [ 29.597349][ C3] sysvec_apic_timer_interrupt+0x4c/0xe0 [ 29.597351][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.597353][ C3] RIP: 0033:0x7fce862e08c4 [ 29.597355][ C3] Code: 00 00 00 00 00 66 66 2e 0f 1f 84 00 00 00 00 00 66 66 2e 0f 1f 84 00 00 00 00 00 66 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 6f 00 <48> 83 c0 10 66 0f 6f d8 66 0f 69 c2 66 0f 61 da 66 0f fe cb 66 0f [ 29.597356][ C3] RSP: 002b:00007ffeaf016e48 EFLAGS: 00000287 [ 29.597359][ C3] RAX: 000055f8f6826644 RBX: 000055f8f6826604 RCX: 0000000000001f48 [ 29.597361][ C3] RDX: 000055f8f6828544 RSI: 00000000000001f4 RDI: 000055f8f6826604 [ 29.597361][ C3] RBP: 00007ffeaf016e80 R08: 0000000000000fa3 R09: 0000000000001f46 [ 29.597362][ C3] R10: 0000000000000fa4 R11: 0000000000001f48 R12: 000055f8f6823830 [ 29.597363][ C3] R13: 0000000000000010 R14: 0000000000001f48 R15: 0000000000005da3 [ 29.597365][ C3] [ 88.006155][ T786] br11: port 2(veth2.11) entered disabled state [ 88.006173][ T786] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 88.006176][ T786] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 786, name: ip [ 88.006177][ T786] preempt_count: 202, expected: 0 [ 88.006178][ T786] RCU nest depth: 0, expected: 0 [ 88.006179][ T786] INFO: lockdep is turned off. [ 88.006181][ T786] irq event stamp: 0 [ 88.006181][ T786] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 88.006186][ T786] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 88.006192][ T786] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 88.006194][ T786] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 88.006195][ T786] Preemption disabled at: [ 88.006196][ T786] [<0000000000000000>] 0x0 [ 88.006201][ T786] CPU: 2 UID: 0 PID: 786 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 88.006205][ T786] Tainted: [W]=WARN [ 88.006206][ T786] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 88.006207][ T786] Call Trace: [ 88.006209][ T786] [ 88.006210][ T786] dump_stack_lvl+0x6f/0xa0 [ 88.006215][ T786] __might_resched.cold+0x1fe/0x2c1 [ 88.006219][ T786] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 88.006223][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006227][ T786] __kmalloc_noprof+0x443/0x760 [ 88.006230][ T786] ? __rwlock_init+0x150/0x150 [ 88.006233][ T786] ? alloc_buf.isra.0+0x4b/0x260 [ 88.006237][ T786] ? do_raw_spin_unlock+0x59/0x250 [ 88.006238][ T786] alloc_buf.isra.0+0x4b/0x260 [ 88.006240][ T786] put_chars+0x1e1/0x2f0 [ 88.006242][ T786] ? desc_read_finalized_seq+0x79/0x120 [ 88.006244][ T786] ? __send_to_port+0x420/0x420 [ 88.006246][ T786] ? console_prepend_replay+0x20/0x20 [ 88.006249][ T786] hvc_console_print+0x292/0x780 [ 88.006252][ T786] ? hvc_write+0x3a0/0x3a0 [ 88.006253][ T786] ? do_raw_spin_lock+0x131/0x280 [ 88.006255][ T786] ? __rwlock_init+0x150/0x150 [ 88.006256][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006258][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006259][ T786] ? lock_acquire+0x13c/0x160 [ 88.006261][ T786] console_emit_next_record+0x22f/0x4c0 [ 88.006264][ T786] ? devkmsg_read+0x4b0/0x4b0 [ 88.006266][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006267][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006269][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006271][ T786] ? lock_acquire+0x13c/0x160 [ 88.006272][ T786] ? console_flush_one_record+0x111/0x710 [ 88.006274][ T786] console_flush_one_record+0x46f/0x710 [ 88.006276][ T786] ? console_emit_next_record+0x4c0/0x4c0 [ 88.006279][ T786] console_unlock+0xee/0x1f0 [ 88.006280][ T786] ? lock_acquire+0x13c/0x160 [ 88.006282][ T786] ? console_flush_one_record+0x710/0x710 [ 88.006283][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006285][ T786] ? lock_release+0x184/0x1f0 [ 88.006286][ T786] ? lock_acquire+0x60/0x160 [ 88.006288][ T786] ? __rcu_read_lock+0x30/0x70 [ 88.006290][ T786] ? __down_trylock_console_sem+0x5e/0xa0 [ 88.006292][ T786] ? vprintk_emit+0x320/0x3e0 [ 88.006294][ T786] vprintk_emit+0x37c/0x3e0 [ 88.006298][ T786] ? wake_up_klogd_work_func+0x90/0x90 [ 88.006300][ T786] ? debug_object_assert_init+0x1d9/0x360 [ 88.006304][ T786] ? debug_object_activate+0x4a0/0x4a0 [ 88.006306][ T786] _printk+0xc7/0x100 [ 88.006309][ T786] ? snapshot_read.cold+0x21/0x21 [ 88.006311][ T786] ? __rwlock_init+0x150/0x150 [ 88.006313][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006314][ T786] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 88.006318][ T786] br_set_state+0x22f/0x430 [bridge] [ 88.006331][ T786] br_stp_disable_port+0x11f/0x390 [bridge] [ 88.006339][ T786] br_stp_disable_bridge+0xb9/0x180 [bridge] [ 88.006345][ T786] br_dev_stop+0x28/0x1a0 [bridge] [ 88.006354][ T786] ? rtnl_is_locked+0x15/0x20 [ 88.006357][ T786] __dev_close_many+0x1c8/0x530 [ 88.006361][ T786] ? netif_open+0x120/0x120 [ 88.006362][ T786] ? trace_irq_enable.constprop.0+0x30/0x160 [ 88.006364][ T786] __dev_change_flags+0x288/0x660 [ 88.006366][ T786] ? netif_set_allmulti+0x250/0x250 [ 88.006368][ T786] ? __kernel_text_address+0x12/0x30 [ 88.006370][ T786] ? write_profile+0xf0/0xf0 [ 88.006373][ T786] ? unwind_get_return_address+0x67/0xd0 [ 88.006376][ T786] netif_change_flags+0x87/0x190 [ 88.006378][ T786] do_setlink.isra.0+0x1cc3/0x2750 [ 88.006380][ T786] ? stack_trace_save+0x93/0xc0 [ 88.006381][ T786] ? rtnl_link_get_size+0x350/0x350 [ 88.006382][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006384][ T786] ? stack_depot_save_flags+0x1bc/0x790 [ 88.006387][ T786] ? kasan_save_stack+0x3d/0x50 [ 88.006390][ T786] ? kasan_save_stack+0x2f/0x50 [ 88.006391][ T786] ? kasan_save_track+0x14/0x30 [ 88.006393][ T786] ? __kasan_kmalloc+0x7b/0x90 [ 88.006394][ T786] ? __kmalloc_cache_noprof+0x29a/0x620 [ 88.006396][ T786] ? rtnl_newlink+0x124/0x11c0 [ 88.006397][ T786] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 88.006398][ T786] ? netlink_rcv_skb+0x14e/0x3a0 [ 88.006401][ T786] ? netlink_unicast+0x486/0x750 [ 88.006402][ T786] ? netlink_sendmsg+0x735/0xc60 [ 88.006403][ T786] ? ____sys_sendmsg+0x415/0x880 [ 88.006405][ T786] ? ___sys_sendmsg+0x14e/0x1d0 [ 88.006407][ T786] ? __sys_sendmsg+0x12c/0x1d0 [ 88.006408][ T786] ? do_syscall_64+0xff/0x530 [ 88.006411][ T786] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 88.006413][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006415][ T786] ? lock_acquire+0x13c/0x160 [ 88.006416][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006418][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006420][ T786] ? trace_contention_end+0xb3/0x180 [ 88.006423][ T786] ? __mutex_lock+0x1db/0x1ea0 [ 88.006425][ T786] ? __mutex_lock+0x9a3/0x1ea0 [ 88.006426][ T786] ? rtnl_newlink+0x9a8/0x11c0 [ 88.006428][ T786] ? ww_mutex_lock+0x160/0x160 [ 88.006430][ T786] ? __rtnl_newlink+0x3fa/0xa50 [ 88.006432][ T786] rtnl_newlink+0x9f1/0x11c0 [ 88.006434][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006435][ T786] ? rtnl_bridge_getlink+0x850/0x850 [ 88.006437][ T786] ? lock_acquire+0x13c/0x160 [ 88.006438][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006440][ T786] ? is_bpf_text_address+0x3e/0x110 [ 88.006443][ T786] ? is_bpf_text_address+0x72/0x110 [ 88.006445][ T786] ? kernel_text_address+0x149/0x170 [ 88.006446][ T786] ? __kernel_text_address+0x12/0x30 [ 88.006447][ T786] ? write_profile+0xf0/0xf0 [ 88.006448][ T786] ? unwind_get_return_address+0x67/0xd0 [ 88.006450][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006451][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006453][ T786] ? cap_capable+0x1d7/0x3d0 [ 88.006457][ T786] ? lock_acquire+0x13c/0x160 [ 88.006458][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006460][ T786] ? rtnl_bridge_getlink+0x850/0x850 [ 88.006461][ T786] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 88.006463][ T786] ? rtnl_link_fill+0x920/0x920 [ 88.006464][ T786] ? filter_irq_stacks+0xd0/0xd0 [ 88.006465][ T786] ? stack_depot_save_flags+0x1bc/0x790 [ 88.006468][ T786] netlink_rcv_skb+0x14e/0x3a0 [ 88.006469][ T786] ? rtnl_link_fill+0x920/0x920 [ 88.006470][ T786] ? lock_release+0x184/0x1f0 [ 88.006472][ T786] ? netlink_ack+0xcf0/0xcf0 [ 88.006473][ T786] ? __netlink_lookup+0xb9/0x6b0 [ 88.006475][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006477][ T786] ? lock_release+0x184/0x1f0 [ 88.006479][ T786] netlink_unicast+0x486/0x750 [ 88.006481][ T786] ? netlink_attachskb+0x810/0x810 [ 88.006482][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006484][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006486][ T786] ? lock_acquire+0x13c/0x160 [ 88.006487][ T786] netlink_sendmsg+0x735/0xc60 [ 88.006489][ T786] ? netlink_unicast+0x750/0x750 [ 88.006491][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006493][ T786] ? lock_release+0x184/0x1f0 [ 88.006495][ T786] ____sys_sendmsg+0x415/0x880 [ 88.006496][ T786] ? copy_msghdr_from_user+0x279/0x420 [ 88.006498][ T786] ? get_timestamp.constprop.0+0x390/0x390 [ 88.006500][ T786] ? move_addr_to_kernel+0x40/0x40 [ 88.006501][ T786] ? filter_irq_stacks+0xd0/0xd0 [ 88.006503][ T786] ___sys_sendmsg+0x14e/0x1d0 [ 88.006505][ T786] ? copy_msghdr_from_user+0x420/0x420 [ 88.006510][ T786] __sys_sendmsg+0x12c/0x1d0 [ 88.006512][ T786] ? __sys_sendmsg_sock+0x20/0x20 [ 88.006515][ T786] ? rcu_is_watching+0x16/0xd0 [ 88.006517][ T786] do_syscall_64+0xff/0x530 [ 88.006518][ T786] ? exc_page_fault+0xee/0x100 [ 88.006520][ T786] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 88.006521][ T786] RIP: 0033:0x7f26392cf54e [ 88.006523][ T786] 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 [ 88.006525][ T786] RSP: 002b:00007ffc90665a70 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 88.006529][ T786] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f26392cf54e [ 88.006531][ T786] RDX: 0000000000000000 RSI: 00007ffc90665b20 RDI: 0000000000000005 [ 88.006531][ T786] RBP: 00007ffc90665a80 R08: 0000000000000000 R09: 0000000000000000 [ 88.006532][ T786] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b33c [ 88.006533][ T786] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc906661e0 [ 88.006535][ T786] [ 88.041670][ T786] br11: port 1(veth1.11) entered disabled state [ 88.046542][ T786] ip (786) used greatest stack depth: 23144 bytes left [ 88.072330][ T788] br10: port 2(veth2.10) entered disabled state [ 88.072685][ T788] br10: port 1(veth1.10) entered disabled state [ 88.125836][ T792] veth2.11: left allmulticast mode [ 88.126091][ T792] veth2.11: left promiscuous mode [ 88.126569][ T792] br11: port 2(veth2.11) entered disabled state [ 88.218517][ T798] veth2.10: left allmulticast mode [ 88.218735][ T798] veth2: left allmulticast mode [ 88.218944][ T798] veth2.10: left promiscuous mode [ 88.219149][ T798] veth2: left promiscuous mode [ 88.219527][ T798] br10: port 2(veth2.10) entered disabled state [ 88.363654][ T810] veth1.11: left allmulticast mode [ 88.363896][ T810] veth1.11: left promiscuous mode [ 88.364916][ T810] br11: port 1(veth1.11) entered disabled state [ 88.467755][ T816] veth1.10: left allmulticast mode [ 88.468002][ T816] veth1: left allmulticast mode [ 88.468246][ T816] veth1.10: left promiscuous mode [ 88.468462][ T816] veth1: left promiscuous mode [ 88.468918][ T816] br10: port 1(veth1.10) entered disabled state