[ 10.863783][ T207] ip (207) used greatest stack depth: 24552 bytes left [ 10.863805][ T207] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.863808][ T207] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 207, name: ip [ 10.863810][ T207] preempt_count: 2, expected: 0 [ 10.863811][ T207] RCU nest depth: 0, expected: 0 [ 10.863813][ T207] locks held by ip/207: 5, last CPU#3: [ 10.863815][ T207] #0: ffffffffb94167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.863830][ T207] #1: ffffffffb957ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.863837][ T207] #2: ffffffffb957de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.863845][ T207] #3: ffffffffb949d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.863851][ T207] #4: ffffffffb949d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.863857][ T207] irq event stamp: 22906 [ 10.863859][ T207] hardirqs last enabled at (22905): [] __down_trylock_console_sem+0x86/0xa0 [ 10.863863][ T207] hardirqs last disabled at (22906): [] console_emit_next_record+0x3d4/0x4c0 [ 10.863867][ T207] softirqs last enabled at (22666): [] handle_softirqs+0x67c/0x900 [ 10.863870][ T207] softirqs last disabled at (20963): [] __irq_exit_rcu+0x145/0x1c0 [ 10.863874][ T207] Preemption disabled at: [ 10.863874][ T207] [<0000000000000000>] 0x0 [ 10.863883][ T207] CPU: 3 UID: 0 PID: 207 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.863888][ T207] Tainted: [W]=WARN [ 10.863889][ T207] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.863891][ T207] Call Trace: [ 10.863893][ T207] [ 10.863895][ T207] dump_stack_lvl+0x6f/0xa0 [ 10.863903][ T207] __might_resched.cold+0x1fe/0x2c1 [ 10.863910][ T207] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.863915][ T207] ? __kmalloc_noprof+0xdb/0x760 [ 10.863922][ T207] __kmalloc_noprof+0x443/0x760 [ 10.863925][ T207] ? alloc_buf.isra.0+0x4b/0x260 [ 10.863935][ T207] ? do_raw_spin_unlock+0x59/0x250 [ 10.863938][ T207] alloc_buf.isra.0+0x4b/0x260 [ 10.863944][ T207] put_chars+0x1e1/0x2f0 [ 10.863948][ T207] ? __send_to_port+0x420/0x420 [ 10.863950][ T207] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.863955][ T207] ? validate_chain+0x38b/0xc20 [ 10.863967][ T207] hvc_console_print+0x292/0x780 [ 10.863980][ T207] ? hvc_write+0x3a0/0x3a0 [ 10.863984][ T207] ? rcu_is_watching+0x16/0xd0 [ 10.863988][ T207] ? lock_acquire+0x13c/0x160 [ 10.863995][ T207] console_emit_next_record+0x22f/0x4c0 [ 10.864002][ T207] ? devkmsg_read+0x4b0/0x4b0 [ 10.864005][ T207] ? console_flush_one_record+0x106/0x710 [ 10.864011][ T207] ? rcu_is_watching+0x16/0xd0 [ 10.864014][ T207] ? lock_acquire+0x13c/0x160 [ 10.864021][ T207] console_flush_one_record+0x46f/0x710 [ 10.864033][ T207] ? console_emit_next_record+0x4c0/0x4c0 [ 10.864036][ T207] ? __lock_acquire+0x518/0xc20 [ 10.864045][ T207] console_unlock+0xee/0x1f0 [ 10.864050][ T207] ? console_flush_one_record+0x710/0x710 [ 10.864053][ T207] ? rcu_is_watching+0x16/0xd0 [ 10.864055][ T207] ? lock_acquire+0xe0/0x160 [ 10.864062][ T207] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.864064][ T207] ? vprintk_emit+0x320/0x3e0 [ 10.864069][ T207] vprintk_emit+0x37c/0x3e0 [ 10.864074][ T207] ? wake_up_klogd_work_func+0x90/0x90 [ 10.864079][ T207] ? __lock_acquire+0x518/0xc20 [ 10.864086][ T207] _printk+0xc7/0x100 [ 10.864092][ T207] ? snapshot_read.cold+0x21/0x21 [ 10.864097][ T207] ? do_raw_spin_lock+0x131/0x280 [ 10.864101][ T207] ? __rwlock_init+0x150/0x150 [ 10.864108][ T207] ? do_raw_spin_lock+0x131/0x280 [ 10.864113][ T207] do_exit.cold+0x82/0x9c [ 10.864119][ T207] ? exit_notify+0x890/0x890 [ 10.864121][ T207] ? __lock_release.isra.0+0x69/0x1a0 [ 10.864126][ T207] ? rcu_is_watching+0x16/0xd0 [ 10.864133][ T207] do_group_exit+0xb8/0x370 [ 10.864138][ T207] __x64_sys_exit_group+0x3c/0x50 [ 10.864142][ T207] x64_sys_call+0x1567/0x1570 [ 10.864145][ T207] do_syscall_64+0xff/0x530 [ 10.864151][ T207] ? exc_page_fault+0xee/0x100 [ 10.864155][ T207] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.864159][ T207] RIP: 0033:0x7f18abc791b8 [ 10.864162][ T207] Code: Unable to access opcode bytes at 0x7f18abc7918e. [ 10.864163][ T207] RSP: 002b:00007ffe0b326328 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.864166][ T207] RAX: ffffffffffffffda RBX: 00007f18abda9f88 RCX: 00007f18abc791b8 [ 10.864168][ T207] RDX: 00007f18ab9c3fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.864170][ T207] RBP: 00007ffe0b326380 R08: 0000000000000000 R09: 0000000000000050 [ 10.864171][ T207] R10: 00007ffe0b326140 R11: 0000000000000246 R12: 0000000000000001 [ 10.864173][ T207] R13: 0000000000000000 R14: 00007f18abda8680 R15: 00007f18abda9fa0 [ 10.864186][ T207] [ 10.973090][ T209] ip (209) used greatest stack depth: 23808 bytes left [ 12.469013][ T290] br0: port 1(gw_l) entered blocking state [ 12.469130][ T290] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.469132][ T290] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 290, name: ip [ 12.469134][ T290] preempt_count: 1, expected: 0 [ 12.469135][ T290] RCU nest depth: 0, expected: 0 [ 12.469135][ T290] locks held by ip/290: 5, last CPU#2: [ 12.469137][ T290] #0: ffffffffb9d1b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 12.469150][ T290] #1: ffffffffb957ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.469156][ T290] #2: ffffffffb957de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.469160][ T290] #3: ffffffffb949d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.469164][ T290] #4: ffffffffb949d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.469168][ T290] irq event stamp: 44310 [ 12.469169][ T290] hardirqs last enabled at (44309): [] __down_trylock_console_sem+0x86/0xa0 [ 12.469174][ T290] hardirqs last disabled at (44310): [] console_emit_next_record+0x3d4/0x4c0 [ 12.469177][ T290] softirqs last enabled at (44220): [] __alloc_skb+0x4c2/0x5f0 [ 12.469180][ T290] softirqs last disabled at (44218): [] __alloc_skb+0x4c2/0x5f0 [ 12.469183][ T290] Preemption disabled at: [ 12.469184][ T290] [] vprintk_emit+0x31b/0x3e0 [ 12.469189][ T290] CPU: 2 UID: 0 PID: 290 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.469192][ T290] Tainted: [W]=WARN [ 12.469193][ T290] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.469195][ T290] Call Trace: [ 12.469197][ T290] [ 12.469198][ T290] dump_stack_lvl+0x6f/0xa0 [ 12.469204][ T290] ? vprintk_emit+0x31b/0x3e0 [ 12.469206][ T290] __might_resched.cold+0x1fe/0x2c1 [ 12.469211][ T290] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.469215][ T290] ? __kmalloc_noprof+0xdb/0x760 [ 12.469220][ T290] __kmalloc_noprof+0x443/0x760 [ 12.469221][ T290] ? alloc_buf.isra.0+0x4b/0x260 [ 12.469228][ T290] ? do_raw_spin_unlock+0x59/0x250 [ 12.469231][ T290] alloc_buf.isra.0+0x4b/0x260 [ 12.469235][ T290] put_chars+0x1e1/0x2f0 [ 12.469237][ T290] ? __send_to_port+0x420/0x420 [ 12.469240][ T290] ? validate_chain+0x34a/0xc20 [ 12.469245][ T290] hvc_console_print+0x292/0x780 [ 12.469253][ T290] ? hvc_write+0x3a0/0x3a0 [ 12.469256][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469259][ T290] ? lock_acquire+0x13c/0x160 [ 12.469263][ T290] console_emit_next_record+0x22f/0x4c0 [ 12.469267][ T290] ? devkmsg_read+0x4b0/0x4b0 [ 12.469269][ T290] ? console_flush_one_record+0x106/0x710 [ 12.469272][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469274][ T290] ? lock_acquire+0x13c/0x160 [ 12.469278][ T290] console_flush_one_record+0x46f/0x710 [ 12.469282][ T290] ? console_emit_next_record+0x4c0/0x4c0 [ 12.469284][ T290] ? __lock_acquire+0x518/0xc20 [ 12.469290][ T290] console_unlock+0xee/0x1f0 [ 12.469293][ T290] ? console_flush_one_record+0x710/0x710 [ 12.469294][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469296][ T290] ? lock_acquire+0xe0/0x160 [ 12.469300][ T290] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.469302][ T290] ? vprintk_emit+0x320/0x3e0 [ 12.469305][ T290] vprintk_emit+0x37c/0x3e0 [ 12.469308][ T290] ? wake_up_klogd_work_func+0x90/0x90 [ 12.469310][ T290] ? __lock_release.isra.0+0x69/0x1a0 [ 12.469311][ T290] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.469316][ T290] ? mark_held_locks+0x40/0x70 [ 12.469319][ T290] _printk+0xc7/0x100 [ 12.469323][ T290] ? snapshot_read.cold+0x21/0x21 [ 12.469326][ T290] ? br_multicast_flood+0x960/0x960 [ 12.469330][ T290] ? do_setlink.isra.0+0x9ec/0x2a60 [ 12.469331][ T290] ? rtnl_newlink+0x9f1/0x11b0 [ 12.469332][ T290] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.469337][ T290] br_set_state+0x22f/0x430 [ 12.469341][ T290] br_init_port+0xc4/0x200 [ 12.469343][ T290] new_nbp+0x3a0/0x650 [ 12.469347][ T290] br_add_if+0x212/0x1320 [ 12.469350][ T290] ? is_bpf_text_address+0x72/0x110 [ 12.469354][ T290] ? kernel_text_address+0x149/0x170 [ 12.469359][ T290] do_set_master+0x3b2/0x780 [ 12.469363][ T290] do_setlink.isra.0+0x9ec/0x2a60 [ 12.469366][ T290] ? stack_trace_save+0x93/0xc0 [ 12.469370][ T290] ? rtnl_link_get_size+0x350/0x350 [ 12.469371][ T290] ? rcu_read_lock_any_held+0x66/0x90 [ 12.469374][ T290] ? stack_depot_save_flags+0x38e/0x790 [ 12.469378][ T290] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.469380][ T290] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.469382][ T290] ? validate_chain+0x38b/0xc20 [ 12.469384][ T290] ? kasan_save_stack+0x3d/0x50 [ 12.469387][ T290] ? kasan_save_stack+0x2f/0x50 [ 12.469388][ T290] ? kasan_save_track+0x14/0x30 [ 12.469389][ T290] ? __kasan_kmalloc+0x7b/0x90 [ 12.469392][ T290] ? __lock_acquire+0x518/0xc20 [ 12.469394][ T290] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 12.469397][ T290] ? ___sys_sendmsg+0xd0/0x1d0 [ 12.469401][ T290] ? lock_acquire.part.0+0xd4/0x280 [ 12.469403][ T290] ? rtnl_newlink+0x9a8/0x11b0 [ 12.469405][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469407][ T290] ? lock_acquire+0x13c/0x160 [ 12.469409][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469410][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469412][ T290] ? trace_contention_end+0xb3/0x180 [ 12.469414][ T290] ? __mutex_lock+0x1db/0x1ea0 [ 12.469415][ T290] ? __mutex_lock+0x9a3/0x1ea0 [ 12.469417][ T290] ? rtnl_newlink+0x9a8/0x11b0 [ 12.469421][ T290] ? ww_mutex_lock+0x160/0x160 [ 12.469422][ T290] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.469427][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469429][ T290] ? __rtnl_newlink+0x3fa/0xa50 [ 12.469434][ T290] rtnl_newlink+0x9f1/0x11b0 [ 12.469440][ T290] ? rtnl_bridge_getlink+0x850/0x850 [ 12.469442][ T290] ? __lock_acquire+0x518/0xc20 [ 12.469446][ T290] ? lock_acquire.part.0+0xd4/0x280 [ 12.469448][ T290] ? find_held_lock+0x2b/0x80 [ 12.469451][ T290] ? rtnl_bridge_getlink+0x850/0x850 [ 12.469453][ T290] ? __lock_release.isra.0+0x69/0x1a0 [ 12.469456][ T290] ? rtnl_bridge_getlink+0x850/0x850 [ 12.469459][ T290] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.469462][ T290] ? rtnl_link_fill+0x920/0x920 [ 12.469463][ T290] ? __lock_acquire+0x518/0xc20 [ 12.469468][ T290] ? lock_acquire.part.0+0xd4/0x280 [ 12.469470][ T290] ? find_held_lock+0x2b/0x80 [ 12.469473][ T290] netlink_rcv_skb+0x14e/0x3a0 [ 12.469477][ T290] ? rtnl_link_fill+0x920/0x920 [ 12.469480][ T290] ? netlink_ack+0xcf0/0xcf0 [ 12.469486][ T290] ? netlink_deliver_tap+0xc5/0x330 [ 12.469488][ T290] ? netlink_deliver_tap+0x13c/0x330 [ 12.469493][ T290] netlink_unicast+0x486/0x750 [ 12.469497][ T290] ? netlink_attachskb+0x810/0x810 [ 12.469500][ T290] ? find_held_lock+0x2b/0x80 [ 12.469504][ T290] netlink_sendmsg+0x75b/0xc90 [ 12.469508][ T290] ? netlink_unicast+0x750/0x750 [ 12.469512][ T290] ? __lock_release.isra.0+0x69/0x1a0 [ 12.469514][ T290] ? __import_iovec+0x36c/0x620 [ 12.469519][ T290] __sock_sendmsg+0xce/0x190 [ 12.469522][ T290] ____sys_sendmsg+0x61a/0x860 [ 12.469525][ T290] ? copy_msghdr_from_user+0x279/0x420 [ 12.469527][ T290] ? sock_write_iter+0x400/0x400 [ 12.469529][ T290] ? move_addr_to_kernel+0x40/0x40 [ 12.469536][ T290] ___sys_sendmsg+0x14e/0x1d0 [ 12.469539][ T290] ? copy_msghdr_from_user+0x420/0x420 [ 12.469555][ T290] __sys_sendmsg+0x12c/0x1d0 [ 12.469558][ T290] ? __sys_sendmsg_sock+0x20/0x20 [ 12.469565][ T290] ? rcu_is_watching+0x16/0xd0 [ 12.469568][ T290] do_syscall_64+0xff/0x530 [ 12.469571][ T290] ? exc_page_fault+0xee/0x100 [ 12.469574][ T290] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.469576][ T290] RIP: 0033:0x7ff47d0fb54e [ 12.469580][ T290] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 12.469582][ T290] RSP: 002b:00007ffcbeb7ef10 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.469585][ T290] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007ff47d0fb54e [ 12.469586][ T290] RDX: 0000000000000000 RSI: 00007ffcbeb7efc0 RDI: 0000000000000005 [ 12.469587][ T290] RBP: 00007ffcbeb7ef20 R08: 0000000000000000 R09: 0000000000000000 [ 12.469588][ T290] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912637 [ 12.469589][ T290] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffcbeb7f680 [ 12.469596][ T290] [ 12.516016][ T290] br0: port 1(gw_l) entered disabled state [ 12.516563][ T290] gw_l: entered allmulticast mode [ 12.518571][ T290] gw_l: entered promiscuous mode [ 12.520355][ T290] br0: port 1(gw_l) entered blocking state [ 12.521053][ T290] br0: port 1(gw_l) entered forwarding state [ 12.542727][ T290] ip (290) used greatest stack depth: 23336 bytes left [ 12.739864][ T292] br0: port 2(amtg) entered blocking state [ 12.740414][ T292] br0: port 2(amtg) entered disabled state [ 12.740736][ T292] amtg: entered allmulticast mode [ 12.741854][ T292] amtg: entered promiscuous mode [ 13.375583][ T303] br0: port 2(amtg) entered blocking state [ 13.376141][ T303] br0: port 2(amtg) entered forwarding state [ 13.730536][ T310] amtr: entered allmulticast mode [ 13.730553][ T310] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.730555][ T310] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 310, name: smcrouted [ 13.730557][ T310] preempt_count: 1, expected: 0 [ 13.730558][ T310] RCU nest depth: 0, expected: 0 [ 13.730559][ T310] locks held by smcrouted/310: 5, last CPU#2: [ 13.730561][ T310] #0: ffffffffb9d1b740 (rtnl_mutex){+.+.}-{4:4}, at: ip_mroute_setsockopt+0x15c/0x1010 [ 13.730573][ T310] #1: ffffffffb957ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.730579][ T310] #2: ffffffffb957de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.730583][ T310] #3: ffffffffb949d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.730587][ T310] #4: ffffffffb949d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.730591][ T310] irq event stamp: 5362 [ 13.730592][ T310] hardirqs last enabled at (5361): [] __down_trylock_console_sem+0x86/0xa0 [ 13.730595][ T310] hardirqs last disabled at (5362): [] console_emit_next_record+0x3d4/0x4c0 [ 13.730597][ T310] softirqs last enabled at (5280): [] __alloc_skb+0x4c2/0x5f0 [ 13.730601][ T310] softirqs last disabled at (5278): [] __alloc_skb+0x4c2/0x5f0 [ 13.730603][ T310] Preemption disabled at: [ 13.730604][ T310] [] vprintk_emit+0x31b/0x3e0 [ 13.730609][ T310] CPU: 2 UID: 0 PID: 310 Comm: smcrouted Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.730613][ T310] Tainted: [W]=WARN [ 13.730614][ T310] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.730615][ T310] Call Trace: [ 13.730617][ T310] [ 13.730618][ T310] dump_stack_lvl+0x6f/0xa0 [ 13.730624][ T310] ? vprintk_emit+0x31b/0x3e0 [ 13.730626][ T310] __might_resched.cold+0x1fe/0x2c1 [ 13.730631][ T310] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.730635][ T310] ? __kmalloc_noprof+0xdb/0x760 [ 13.730640][ T310] __kmalloc_noprof+0x443/0x760 [ 13.730642][ T310] ? alloc_buf.isra.0+0x4b/0x260 [ 13.730649][ T310] ? do_raw_spin_unlock+0x59/0x250 [ 13.730652][ T310] alloc_buf.isra.0+0x4b/0x260 [ 13.730655][ T310] put_chars+0x1e1/0x2f0 [ 13.730658][ T310] ? __send_to_port+0x420/0x420 [ 13.730661][ T310] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.730664][ T310] ? validate_chain+0x38b/0xc20 [ 13.730669][ T310] hvc_console_print+0x292/0x780 [ 13.730673][ T310] ? __lock_acquire+0x518/0xc20 [ 13.730675][ T310] ? __lock_acquire+0x518/0xc20 [ 13.730680][ T310] ? hvc_write+0x3a0/0x3a0 [ 13.730683][ T310] ? rcu_is_watching+0x16/0xd0 [ 13.730686][ T310] ? lock_acquire+0x13c/0x160 [ 13.730690][ T310] console_emit_next_record+0x22f/0x4c0 [ 13.730694][ T310] ? devkmsg_read+0x4b0/0x4b0 [ 13.730696][ T310] ? console_flush_one_record+0x106/0x710 [ 13.730699][ T310] ? rcu_is_watching+0x16/0xd0 [ 13.730701][ T310] ? lock_acquire+0x13c/0x160 [ 13.730705][ T310] console_flush_one_record+0x46f/0x710 [ 13.730710][ T310] ? console_emit_next_record+0x4c0/0x4c0 [ 13.730712][ T310] ? __lock_acquire+0x518/0xc20 [ 13.730717][ T310] console_unlock+0xee/0x1f0 [ 13.730720][ T310] ? console_flush_one_record+0x710/0x710 [ 13.730722][ T310] ? rcu_is_watching+0x16/0xd0 [ 13.730724][ T310] ? lock_acquire+0xe0/0x160 [ 13.730728][ T310] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.730730][ T310] ? vprintk_emit+0x320/0x3e0 [ 13.730732][ T310] vprintk_emit+0x37c/0x3e0 [ 13.730736][ T310] ? wake_up_klogd_work_func+0x90/0x90 [ 13.730738][ T310] ? netlink_broadcast_filtered+0x29c/0x3c0 [ 13.730742][ T310] ? kasan_save_stack+0x3d/0x50 [ 13.730744][ T310] ? kasan_save_stack+0x2f/0x50 [ 13.730747][ T310] _printk+0xc7/0x100 [ 13.730751][ T310] ? snapshot_read.cold+0x21/0x21 [ 13.730753][ T310] ? __sys_setsockopt+0xe4/0x150 [ 13.730758][ T310] ? netdev_name+0x43/0x60 [ 13.730762][ T310] ? __netdev_printk+0x17f/0x1d0 [ 13.730766][ T310] netdev_info+0xe2/0x116 [ 13.730769][ T310] ? netdev_notice+0x120/0x120 [ 13.730771][ T310] ? mark_usage+0x61/0x170 [ 13.730777][ T310] ? find_held_lock+0x2b/0x80 [ 13.730779][ T310] ? mutex_is_locked+0x1c/0x50 [ 13.730782][ T310] netif_set_allmulti.cold+0x7c/0x166 [ 13.730786][ T310] dev_set_allmulti+0xa6/0x240 [ 13.730790][ T310] vif_add+0x9da/0xdc0 [ 13.730792][ T310] ? __mutex_lock+0x1db/0x1ea0 [ 13.730795][ T310] ? __mutex_lock+0x9a3/0x1ea0 [ 13.730799][ T310] ? ip_mr_output_finish.isra.0+0x630/0x630 [ 13.730802][ T310] ? lock_acquire.part.0+0xd4/0x280 [ 13.730804][ T310] ? __might_fault+0x97/0x140 [ 13.730809][ T310] ? __lock_release.isra.0+0x69/0x1a0 [ 13.730812][ T310] ? __might_fault+0x97/0x140 [ 13.730816][ T310] ip_mroute_setsockopt+0xd02/0x1010 [ 13.730820][ T310] ? ipmr_rtm_route+0x250/0x250 [ 13.730825][ T310] ? __might_fault+0x97/0x140 [ 13.730827][ T310] ? find_held_lock+0x2b/0x80 [ 13.730831][ T310] ? __might_fault+0x97/0x140 [ 13.730833][ T310] ? __might_fault+0x97/0x140 [ 13.730836][ T310] do_ip_setsockopt+0x27c/0x25f0 [ 13.730840][ T310] ? ip_sock_set_tos+0x40/0x40 [ 13.730843][ T310] ? find_held_lock+0x2b/0x80 [ 13.730846][ T310] ? __lock_release.isra.0+0x69/0x1a0 [ 13.730848][ T310] ? do_syscall_64+0xff/0x530 [ 13.730851][ T310] ? rcu_is_watching+0x16/0xd0 [ 13.730854][ T310] ? do_raw_spin_unlock+0x59/0x250 [ 13.730855][ T310] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 13.730857][ T310] ? lockdep_hardirqs_on+0x91/0x130 [ 13.730859][ T310] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 13.730861][ T310] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.730864][ T310] ip_setsockopt+0x2f/0x90 [ 13.730868][ T310] raw_setsockopt+0x8c/0x1f0 [ 13.730870][ T310] ? rcu_is_watching+0x16/0xd0 [ 13.730873][ T310] ? raw_bind+0x5b0/0x5b0 [ 13.730877][ T310] do_sock_setsockopt+0x164/0x3b0 [ 13.730880][ T310] ? put_user_ifreq+0x90/0x90 [ 13.730882][ T310] ? __lock_release.isra.0+0x69/0x1a0 [ 13.730885][ T310] ? fd_install+0x18c/0x3d0 [ 13.730890][ T310] ? fd_install+0x19b/0x3d0 [ 13.730894][ T310] __sys_setsockopt+0xe4/0x150 [ 13.730901][ T310] __x64_sys_setsockopt+0xbd/0x180 [ 13.730904][ T310] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.730909][ T310] ? lockdep_hardirqs_on+0x91/0x130 [ 13.730911][ T310] ? do_syscall_64+0xa6/0x530 [ 13.730913][ T310] do_syscall_64+0xff/0x530 [ 13.730915][ T310] ? exc_page_fault+0xee/0x100 [ 13.730917][ T310] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.730919][ T310] RIP: 0033:0x7f9c7aecb2de [ 13.730923][ T310] Code: 55 48 63 c9 48 63 ff 45 89 c9 48 89 e5 48 83 ec 08 6a 2c e8 94 72 f7 ff c9 c3 66 90 f3 0f 1e fa 49 89 ca b8 36 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 0a c3 66 0f 1f 84 00 00 00 00 00 48 8b 15 e1 [ 13.730925][ T310] RSP: 002b:00007fff6a6ff4a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 13.730928][ T310] RAX: ffffffffffffffda RBX: 000055674330e2c0 RCX: 00007f9c7aecb2de [ 13.730929][ T310] RDX: 00000000000000ca RSI: 0000000000000000 RDI: 0000000000000007 [ 13.730930][ T310] RBP: 00007fff6a6ff560 R08: 0000000000000010 R09: 0000000000000001 [ 13.730931][ T310] R10: 00007fff6a6ff4e0 R11: 0000000000000246 R12: 0000556765c5b0c4 [ 13.730932][ T310] R13: 00007fff6a6ff5b0 R14: 0000556765c5b0b0 R15: 0000000000000000 [ 13.730939][ T310] [ 13.769956][ T310] relay_gw: entered allmulticast mode [ 13.770431][ T310] relay_src: entered allmulticast mode [ 27.786229][ C2] [ 27.786244][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.786247][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 27.786249][ C2] preempt_count: 104, expected: 0 [ 27.786250][ C2] RCU nest depth: 0, expected: 0 [ 27.786251][ C2] INFO: lockdep is turned off. [ 27.786252][ C2] irq event stamp: 776754 [ 27.786252][ C2] hardirqs last enabled at (776754): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.786261][ C2] hardirqs last disabled at (776753): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.786263][ C2] softirqs last enabled at (776744): [] handle_softirqs+0x67c/0x900 [ 27.786267][ C2] softirqs last disabled at (776751): [] __irq_exit_rcu+0x145/0x1c0 [ 27.786269][ C2] Preemption disabled at: [ 27.786270][ C2] [<0000000000000000>] 0x0 [ 27.786277][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.786280][ C2] Tainted: [W]=WARN [ 27.786282][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.786284][ C2] Call Trace: [ 27.786285][ C2] [ 27.786287][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.786293][ C2] __might_resched.cold+0x1fe/0x2c1 [ 27.786297][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.786300][ C2] ? __asan_memcpy+0x3c/0x60 [ 27.786304][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786307][ C2] __kmalloc_noprof+0x443/0x760 [ 27.786310][ C2] ? __rwlock_init+0x150/0x150 [ 27.786313][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 27.786317][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 27.786319][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.786322][ C2] put_chars+0x1e1/0x2f0 [ 27.786323][ C2] ? __send_to_port+0x420/0x420 [ 27.786325][ C2] ? console_prepend_replay+0x20/0x20 [ 27.786328][ C2] hvc_console_print+0x292/0x780 [ 27.786333][ C2] ? hvc_write+0x3a0/0x3a0 [ 27.786335][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786336][ C2] ? lock_acquire+0x13c/0x160 [ 27.786339][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.786341][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 27.786343][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786344][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786346][ C2] ? lock_acquire+0x13c/0x160 [ 27.786348][ C2] ? console_flush_one_record+0x111/0x710 [ 27.786349][ C2] console_flush_one_record+0x46f/0x710 [ 27.786352][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 27.786354][ C2] console_unlock+0xee/0x1f0 [ 27.786356][ C2] ? lock_acquire+0x13c/0x160 [ 27.786358][ C2] ? console_flush_one_record+0x710/0x710 [ 27.786360][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786361][ C2] ? lock_release+0x184/0x1f0 [ 27.786362][ C2] ? lock_acquire+0xe0/0x160 [ 27.786364][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.786366][ C2] ? vprintk_emit+0x320/0x3e0 [ 27.786368][ C2] vprintk_emit+0x37c/0x3e0 [ 27.786369][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 27.786371][ C2] ? common_startup_64+0x13e/0x148 [ 27.786375][ C2] _printk+0xc7/0x100 [ 27.786378][ C2] ? snapshot_read.cold+0x21/0x21 [ 27.786379][ C2] ? arch_stack_walk+0xd7/0x130 [ 27.786382][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.786384][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.786386][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.786388][ C2] mark_lock_irq+0x989/0x9c0 [ 27.786391][ C2] mark_lock+0x1d7/0xa00 [ 27.786393][ C2] mark_usage+0x42/0x170 [ 27.786395][ C2] __lock_acquire+0x388/0xc20 [ 27.786398][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.786399][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.786403][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786404][ C2] ? lock_acquire+0x13c/0x160 [ 27.786406][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 27.786407][ C2] _raw_spin_lock+0x33/0x40 [ 27.786409][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.786410][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.786411][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 27.786413][ C2] call_timer_fn+0x160/0x4d0 [ 27.786416][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.786418][ C2] ? debug_object_active_state+0x430/0x430 [ 27.786421][ C2] ? find_held_lock+0x2b/0x80 [ 27.786423][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.786425][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786426][ C2] __run_timers+0x68f/0xaa0 [ 27.786428][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 27.786430][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.786432][ C2] ? __lock_acquire+0x518/0xc20 [ 27.786435][ C2] ? __rwlock_init+0x150/0x150 [ 27.786437][ C2] run_timer_softirq+0xf0/0x160 [ 27.786439][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.786441][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.786444][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786445][ C2] handle_softirqs+0x1d3/0x900 [ 27.786447][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.786449][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.786451][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.786452][ C2] irq_exit_rcu+0xe/0x30 [ 27.786454][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.786457][ C2] [ 27.786457][ C2] [ 27.786458][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.786459][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.786462][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.786465][ C2] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.786466][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.786469][ C2] RAX: 00000000000bda2d RBX: ff11000001c1c740 RCX: ffffffffb5906247 [ 27.786471][ C2] RDX: ff11000001c1c740 RSI: ffffffffb8b11027 RDI: ffffffffb84949e0 [ 27.786472][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.786473][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.786473][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.786475][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.786478][ C2] default_idle+0x9/0x10 [ 27.786480][ C2] default_idle_call+0x6e/0xb0 [ 27.786482][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.786484][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.786485][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.786488][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.786489][ C2] do_idle+0xd8/0x190 [ 27.786491][ C2] cpu_startup_entry+0x53/0x70 [ 27.786493][ C2] start_secondary+0x204/0x2b0 [ 27.786494][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.786496][ C2] common_startup_64+0x13e/0x148 [ 27.786500][ C2] [ 27.815598][ C2] ======================================================== [ 27.815868][ C2] WARNING: possible irq lock inversion dependency detected [ 27.816081][ C2] 7.2.0-virtme #1 Tainted: G W [ 27.816352][ C2] -------------------------------------------------------- [ 27.816559][ C2] swapper/2/0 just changed the state of lock: [ 27.816729][ C2] ff1100000dd5ae98 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [ 27.817076][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.817332][ C2] (fs_reclaim){+.+.}-{0:0} [ 27.817335][ C2] [ 27.817335][ C2] [ 27.817335][ C2] and interrupts could create inverse lock ordering between them. [ 27.817335][ C2] [ 27.818024][ C2] [ 27.818024][ C2] other info that might help us debug this: [ 27.818282][ C2] Chain exists of: [ 27.818282][ C2] &br->lock --> console_owner --> fs_reclaim [ 27.818282][ C2] [ 27.818688][ C2] Possible interrupt unsafe locking scenario: [ 27.818688][ C2] [ 27.818946][ C2] CPU0 CPU1 [ 27.819136][ C2] ---- ---- [ 27.819327][ C2] lock(fs_reclaim); [ 27.819439][ C2] local_irq_disable(); [ 27.819700][ C2] lock(&br->lock); [ 27.819876][ C2] lock(console_owner); [ 27.820058][ C2] [ 27.820172][ C2] lock(&br->lock); [ 27.820324][ C2] [ 27.820324][ C2] *** DEADLOCK *** [ 27.820324][ C2] [ 27.820536][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 27.820803][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.821137][ C2] [ 27.821137][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 27.821472][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 27.821621][ C2] HARDIRQ-ON-W at: [ 27.821776][ C2] __lock_acquire+0x388/0xc20 [ 27.821965][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.822194][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.822467][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.822681][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.822992][ C2] workqueue_init+0x2a/0x680 [ 27.823219][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.825360][ C2] kernel_init+0x21/0x150 [ 27.825589][ C2] ret_from_fork+0x474/0x6b0 [ 27.825810][ C2] ret_from_fork_asm+0x11/0x20 [ 27.826001][ C2] SOFTIRQ-ON-W at: [ 27.826161][ C2] __lock_acquire+0x388/0xc20 [ 27.826342][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.826566][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.826788][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.827002][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.827262][ C2] workqueue_init+0x2a/0x680 [ 27.827484][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.827697][ C2] kernel_init+0x21/0x150 [ 27.827923][ C2] ret_from_fork+0x474/0x6b0 [ 27.828148][ C2] ret_from_fork_asm+0x11/0x20 [ 27.828334][ C2] INITIAL USE at: [ 27.828485][ C2] __lock_acquire+0x388/0xc20 [ 27.828661][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.828877][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.829101][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.829313][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.829564][ C2] workqueue_init+0x2a/0x680 [ 27.829786][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.829964][ C2] kernel_init+0x21/0x150 [ 27.830195][ C2] ret_from_fork+0x474/0x6b0 [ 27.830423][ C2] ret_from_fork_asm+0x11/0x20 [ 27.830598][ C2] } [ 27.830675][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.830924][ C2] ... acquired at: [ 27.831071][ C2] __lock_acquire+0x518/0xc20 [ 27.831214][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.831391][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.831527][ C2] __kmalloc_noprof+0xd3/0x760 [ 27.831711][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.831852][ C2] put_chars+0x1e1/0x2f0 [ 27.832037][ C2] hvc_console_print+0x292/0x780 [ 27.832185][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.832376][ C2] console_flush_one_record+0x46f/0x710 [ 27.832515][ C2] console_unlock+0xee/0x1f0 [ 27.832699][ C2] vprintk_emit+0x37c/0x3e0 [ 27.832833][ C2] _printk+0xc7/0x100 [ 27.832941][ C2] tun_init+0x24/0xb0 [ 27.833087][ C2] do_one_initcall+0x124/0x4f0 [ 27.833227][ C2] kernel_init_freeable+0x596/0x630 [ 27.833409][ C2] kernel_init+0x21/0x150 [ 27.833547][ C2] ret_from_fork+0x474/0x6b0 [ 27.833727][ C2] ret_from_fork_asm+0x11/0x20 [ 27.833862][ C2] [ 27.833991][ C2] -> (console_owner){....}-{0:0} { [ 27.834136][ C2] INITIAL USE at: [ 27.834250][ C2] } [ 27.834365][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.834572][ C2] ... acquired at: [ 27.834720][ C2] __lock_acquire+0x518/0xc20 [ 27.834859][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.835049][ C2] console_lock_spinning_enable+0x5c/0x60 [ 27.835225][ C2] console_emit_next_record+0x1d1/0x4c0 [ 27.835410][ C2] console_flush_one_record+0x46f/0x710 [ 27.835546][ C2] console_unlock+0xee/0x1f0 [ 27.835727][ C2] vprintk_emit+0x37c/0x3e0 [ 27.835859][ C2] _printk+0xc7/0x100 [ 27.836013][ C2] br_set_state+0x22f/0x430 [ 27.836158][ C2] br_init_port+0xc4/0x200 [ 27.836340][ C2] br_stp_enable_port+0x12/0x50 [ 27.836480][ C2] br_add_if+0xde2/0x1320 [ 27.836668][ C2] do_set_master+0x3b2/0x780 [ 27.836806][ C2] do_setlink.isra.0+0x9ec/0x2a60 [ 27.836996][ C2] rtnl_newlink+0x9f1/0x11b0 [ 27.837131][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.837322][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.837460][ C2] netlink_unicast+0x486/0x750 [ 27.837639][ C2] netlink_sendmsg+0x75b/0xc90 [ 27.837773][ C2] __sock_sendmsg+0xce/0x190 [ 27.837959][ C2] ____sys_sendmsg+0x61a/0x860 [ 27.838090][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.838276][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.838416][ C2] do_syscall_64+0xff/0x530 [ 27.838598][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.838772][ C2] [ 27.838886][ C2] -> (&br->lock){+.-.}-{3:3} { [ 27.839030][ C2] HARDIRQ-ON-W at: [ 27.839187][ C2] __lock_acquire+0x388/0xc20 [ 27.839359][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.839572][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.839743][ C2] br_stp_enable_bridge+0x23/0x340 [ 27.839961][ C2] br_dev_open+0x94/0x120 [ 27.840184][ C2] __dev_open+0x322/0x7a0 [ 27.840361][ C2] __dev_change_flags+0x4a4/0x6d0 [ 27.840580][ C2] netif_change_flags+0x87/0x190 [ 27.840802][ C2] do_setlink.isra.0+0x1d25/0x2a60 [ 27.840981][ C2] rtnl_newlink+0x9f1/0x11b0 [ 27.841211][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.841385][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.841603][ C2] netlink_unicast+0x486/0x750 [ 27.841820][ C2] netlink_sendmsg+0x75b/0xc90 [ 27.841991][ C2] __sock_sendmsg+0xce/0x190 [ 27.842211][ C2] ____sys_sendmsg+0x61a/0x860 [ 27.842434][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.842606][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.842824][ C2] do_syscall_64+0xff/0x530 [ 27.843005][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.843264][ C2] IN-SOFTIRQ-W at: [ 27.843414][ C2] __lock_acquire+0x388/0xc20 [ 27.843581][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.843803][ C2] _raw_spin_lock+0x33/0x40 [ 27.843976][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.844230][ C2] call_timer_fn+0x160/0x4d0 [ 27.844445][ C2] __run_timers+0x68f/0xaa0 [ 27.844612][ C2] run_timer_softirq+0xf0/0x160 [ 27.844827][ C2] handle_softirqs+0x1d3/0x900 [ 27.845048][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.845224][ C2] irq_exit_rcu+0xe/0x30 [ 27.845443][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.845695][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.845906][ C2] pv_native_safe_halt+0xf/0x10 [ 27.846121][ C2] default_idle+0x9/0x10 [ 27.846350][ C2] default_idle_call+0x6e/0xb0 [ 27.846517][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.846763][ C2] do_idle+0xd8/0x190 [ 27.846949][ C2] cpu_startup_entry+0x53/0x70 [ 27.847116][ C2] start_secondary+0x204/0x2b0 [ 27.847341][ C2] common_startup_64+0x13e/0x148 [ 27.847519][ C2] INITIAL USE at: [ 27.847667][ C2] __lock_acquire+0x388/0xc20 [ 27.847839][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.848061][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.848285][ C2] br_stp_enable_bridge+0x23/0x340 [ 27.848452][ C2] br_dev_open+0x94/0x120 [ 27.848668][ C2] __dev_open+0x322/0x7a0 [ 27.848881][ C2] __dev_change_flags+0x4a4/0x6d0 [ 27.849049][ C2] netif_change_flags+0x87/0x190 [ 27.849298][ C2] do_setlink.isra.0+0x1d25/0x2a60 [ 27.849471][ C2] rtnl_newlink+0x9f1/0x11b0 [ 27.849689][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.849910][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.850086][ C2] netlink_unicast+0x486/0x750 [ 27.850307][ C2] netlink_sendmsg+0x75b/0xc90 [ 27.850522][ C2] __sock_sendmsg+0xce/0x190 [ 27.850691][ C2] ____sys_sendmsg+0x61a/0x860 [ 27.850915][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.851081][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.851304][ C2] do_syscall_64+0xff/0x530 [ 27.851525][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.851730][ C2] } [ 27.851844][ C2] ... key at: [] __key.7+0x0/0x40 [ 27.852023][ C2] ... acquired at: [ 27.852178][ C2] mark_lock+0x1d7/0xa00 [ 27.852319][ C2] mark_usage+0x42/0x170 [ 27.852511][ C2] __lock_acquire+0x388/0xc20 [ 27.852650][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.852834][ C2] _raw_spin_lock+0x33/0x40 [ 27.852980][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.853199][ C2] call_timer_fn+0x160/0x4d0 [ 27.853337][ C2] __run_timers+0x68f/0xaa0 [ 27.853521][ C2] run_timer_softirq+0xf0/0x160 [ 27.853659][ C2] handle_softirqs+0x1d3/0x900 [ 27.853842][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.853993][ C2] irq_exit_rcu+0xe/0x30 [ 27.854181][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.854352][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.854565][ C2] pv_native_safe_halt+0xf/0x10 [ 27.854751][ C2] default_idle+0x9/0x10 [ 27.854886][ C2] default_idle_call+0x6e/0xb0 [ 27.855065][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.855234][ C2] do_idle+0xd8/0x190 [ 27.855381][ C2] cpu_startup_entry+0x53/0x70 [ 27.855516][ C2] start_secondary+0x204/0x2b0 [ 27.855698][ C2] common_startup_64+0x13e/0x148 [ 27.855837][ C2] [ 27.855912][ C2] [ 27.855912][ C2] stack backtrace: [ 27.856128][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.856132][ C2] Tainted: [W]=WARN [ 27.856133][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.856135][ C2] Call Trace: [ 27.856137][ C2] [ 27.856138][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.856143][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.856146][ C2] mark_lock_irq+0x989/0x9c0 [ 27.856154][ C2] mark_lock+0x1d7/0xa00 [ 27.856156][ C2] mark_usage+0x42/0x170 [ 27.856158][ C2] __lock_acquire+0x388/0xc20 [ 27.856160][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.856162][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.856164][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.856166][ C2] ? lock_acquire+0x13c/0x160 [ 27.856168][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 27.856170][ C2] _raw_spin_lock+0x33/0x40 [ 27.856171][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.856172][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.856174][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 27.856175][ C2] call_timer_fn+0x160/0x4d0 [ 27.856178][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.856179][ C2] ? debug_object_active_state+0x430/0x430 [ 27.856183][ C2] ? find_held_lock+0x2b/0x80 [ 27.856184][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.856186][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.856188][ C2] __run_timers+0x68f/0xaa0 [ 27.856190][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 27.856192][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.856193][ C2] ? __lock_acquire+0x518/0xc20 [ 27.856196][ C2] ? __rwlock_init+0x150/0x150 [ 27.856199][ C2] run_timer_softirq+0xf0/0x160 [ 27.856201][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.856203][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.856206][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.856207][ C2] handle_softirqs+0x1d3/0x900 [ 27.856209][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.856211][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.856213][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.856215][ C2] irq_exit_rcu+0xe/0x30 [ 27.856216][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.856218][ C2] [ 27.856218][ C2] [ 27.856219][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.856220][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.856222][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.856225][ C2] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.856227][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.856229][ C2] RAX: 00000000000bda2d RBX: ff11000001c1c740 RCX: ffffffffb5906247 [ 27.856230][ C2] RDX: ff11000001c1c740 RSI: ffffffffb8b11027 RDI: ffffffffb84949e0 [ 27.856231][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.856232][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.856233][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.856234][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.856236][ C2] default_idle+0x9/0x10 [ 27.856238][ C2] default_idle_call+0x6e/0xb0 [ 27.856239][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.856241][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.856243][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.856245][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.856246][ C2] do_idle+0xd8/0x190 [ 27.856248][ C2] cpu_startup_entry+0x53/0x70 [ 27.856249][ C2] start_secondary+0x204/0x2b0 [ 27.856251][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.856253][ C2] common_startup_64+0x13e/0x148 [ 27.856256][ C2] [ 517.112638][ T12] br0: port 1(gw_l) entered disabled state [ 517.112656][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 517.112659][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 517.112661][ T12] preempt_count: 202, expected: 0 [ 517.112662][ T12] RCU nest depth: 0, expected: 0 [ 517.112663][ T12] INFO: lockdep is turned off. [ 517.112664][ T12] irq event stamp: 9528 [ 517.112665][ T12] hardirqs last enabled at (9527): [] _raw_spin_unlock_irq+0x28/0x50 [ 517.112674][ T12] hardirqs last disabled at (9528): [] __schedule+0x1246/0x20e0 [ 517.112677][ T12] softirqs last enabled at (9524): [] __fib6_clean_all+0xff/0x290 [ 517.112680][ T12] softirqs last disabled at (9518): [] __fib6_clean_all+0xd9/0x290 [ 517.112683][ T12] Preemption disabled at: [ 517.112683][ T12] [<0000000000000000>] 0x0 [ 517.112690][ T12] CPU: 3 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 517.112694][ T12] Tainted: [W]=WARN [ 517.112695][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 517.112697][ T12] Workqueue: netns cleanup_net [ 517.112702][ T12] Call Trace: [ 517.112704][ T12] [ 517.112705][ T12] dump_stack_lvl+0x6f/0xa0 [ 517.112711][ T12] __might_resched.cold+0x1fe/0x2c1 [ 517.112715][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 517.112720][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112723][ T12] __kmalloc_noprof+0x443/0x760 [ 517.112726][ T12] ? __rwlock_init+0x150/0x150 [ 517.112730][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 517.112735][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 517.112736][ T12] alloc_buf.isra.0+0x4b/0x260 [ 517.112739][ T12] put_chars+0x1e1/0x2f0 [ 517.112741][ T12] ? __send_to_port+0x420/0x420 [ 517.112742][ T12] ? printk_get_next_message+0x240/0x650 [ 517.112745][ T12] ? console_prepend_replay+0x20/0x20 [ 517.112748][ T12] hvc_console_print+0x292/0x780 [ 517.112753][ T12] ? hvc_write+0x3a0/0x3a0 [ 517.112754][ T12] ? __rwlock_init+0x150/0x150 [ 517.112756][ T12] ? lock_acquire+0x13c/0x160 [ 517.112759][ T12] console_emit_next_record+0x22f/0x4c0 [ 517.112762][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 517.112764][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112765][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112766][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112767][ T12] ? lock_acquire+0x13c/0x160 [ 517.112769][ T12] ? console_flush_one_record+0x111/0x710 [ 517.112771][ T12] console_flush_one_record+0x46f/0x710 [ 517.112773][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 517.112776][ T12] console_unlock+0xee/0x1f0 [ 517.112778][ T12] ? lock_acquire+0x13c/0x160 [ 517.112780][ T12] ? console_flush_one_record+0x710/0x710 [ 517.112781][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112782][ T12] ? lock_release+0x184/0x1f0 [ 517.112784][ T12] ? lock_acquire+0xe0/0x160 [ 517.112786][ T12] ? __rcu_read_lock+0x70/0x70 [ 517.112787][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 517.112789][ T12] ? vprintk_emit+0x320/0x3e0 [ 517.112791][ T12] vprintk_emit+0x37c/0x3e0 [ 517.112793][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 517.112794][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112795][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 517.112798][ T12] ? trace_hardirqs_on+0x36/0x40 [ 517.112800][ T12] _printk+0xc7/0x100 [ 517.112803][ T12] ? snapshot_read.cold+0x21/0x21 [ 517.112805][ T12] ? ipv6_mc_down+0x17e/0x2d0 [ 517.112809][ T12] br_set_state+0x22f/0x430 [ 517.112816][ T12] br_stp_disable_port+0x11f/0x390 [ 517.112818][ T12] br_device_event+0x7b5/0x8f0 [ 517.112820][ T12] ? packet_sendmsg_spkt+0x1360/0x1360 [ 517.112822][ T12] ? addrconf_notify+0x2c8/0xf30 [ 517.112826][ T12] notifier_call_chain+0xae/0x300 [ 517.112829][ T12] netif_close_many+0x366/0x770 [ 517.112832][ T12] ? __dev_close_many+0x680/0x680 [ 517.112834][ T12] ? netif_close_many_and_unlock+0x22/0x2c0 [ 517.112836][ T12] unregister_netdevice_many_notify+0x71a/0x1b40 [ 517.112838][ T12] ? ops_undo_list+0x2ce/0x8f0 [ 517.112840][ T12] ? worker_thread+0x4f1/0xd60 [ 517.112843][ T12] ? kthread+0x367/0x460 [ 517.112845][ T12] ? ret_from_fork+0x474/0x6b0 [ 517.112848][ T12] ? ret_from_fork_asm+0x11/0x20 [ 517.112850][ T12] ? unregister_netdevice_queued+0x80/0x80 [ 517.112852][ T12] ? get_rps_cpu+0x980/0x980 [ 517.112855][ T12] default_device_exit_batch+0x38b/0x600 [ 517.112856][ T12] ? __mutex_unlock_slowpath+0x5e5/0x740 [ 517.112857][ T12] ? lock_acquire+0x13c/0x160 [ 517.112859][ T12] ? unregister_netdevice_queue+0x470/0x470 [ 517.112861][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 517.112863][ T12] ? fou_exit_net+0x30/0x110 [ 517.112865][ T12] ops_undo_list+0x2ce/0x8f0 [ 517.112868][ T12] ? netns_install+0x2a0/0x2a0 [ 517.112869][ T12] ? idr_destroy+0x121/0x250 [ 517.112873][ T12] cleanup_net+0x431/0x940 [ 517.112874][ T12] ? net_passive_dec+0x230/0x230 [ 517.112876][ T12] ? update_load_avg+0x141/0xf00 [ 517.112879][ T12] ? lock_acquire+0x13c/0x160 [ 517.112881][ T12] ? rcu_is_watching+0x16/0xd0 [ 517.112882][ T12] process_one_work+0xe3e/0x1560 [ 517.112885][ T12] ? __queue_delayed_work+0x470/0x470 [ 517.112888][ T12] ? __rwlock_init+0x150/0x150 [ 517.112891][ T12] worker_thread+0x4f1/0xd60 [ 517.112894][ T12] ? rescuer_thread+0x1340/0x1340 [ 517.112896][ T12] kthread+0x367/0x460 [ 517.112899][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 517.112901][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 517.112904][ T12] ret_from_fork+0x474/0x6b0 [ 517.112907][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 517.112909][ T12] ? __switch_to+0x5a3/0xe00 [ 517.112912][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 517.112914][ T12] ret_from_fork_asm+0x11/0x20 [ 517.112918][ T12] [ 517.134069][ T12] gw_l (unregistering): left allmulticast mode [ 517.134347][ T12] gw_l (unregistering): left promiscuous mode [ 517.134527][ T12] br0: port 1(gw_l) entered disabled state [ 517.155426][ T12] amtg: left allmulticast mode [ 517.155595][ T12] amtg: left promiscuous mode [ 517.155777][ T12] br0: port 2(amtg) entered disabled state [ 517.181935][ T12] relay_gw (unregistering): left allmulticast mode [ 517.183111][ T12] amtr (unregistering): left allmulticast mode [ 517.186396][ T310] relay_src: left allmulticast mode