[ 10.444938][ T212] ip (212) used greatest stack depth: 24176 bytes left [ 10.444955][ T212] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.444957][ T212] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 212, name: ip [ 10.444959][ T212] preempt_count: 2, expected: 0 [ 10.444960][ T212] RCU nest depth: 0, expected: 0 [ 10.444961][ T212] locks held by ip/212: 5, last CPU#2: [ 10.444963][ T212] #0: ffffffffb5a167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.444975][ T212] #1: ffffffffb5b7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.444980][ T212] #2: ffffffffb5b7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.444985][ T212] #3: ffffffffb5a9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.444989][ T212] #4: ffffffffb5a9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.444993][ T212] irq event stamp: 40024 [ 10.444994][ T212] hardirqs last enabled at (40023): [] __down_trylock_console_sem+0x86/0xa0 [ 10.444997][ T212] hardirqs last disabled at (40024): [] console_emit_next_record+0x3d4/0x4c0 [ 10.444999][ T212] softirqs last enabled at (39580): [] handle_softirqs+0x67c/0x900 [ 10.445001][ T212] softirqs last disabled at (39575): [] __irq_exit_rcu+0x145/0x1c0 [ 10.445004][ T212] Preemption disabled at: [ 10.445004][ T212] [<0000000000000000>] 0x0 [ 10.445011][ T212] CPU: 2 UID: 0 PID: 212 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.445015][ T212] Tainted: [W]=WARN [ 10.445016][ T212] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.445017][ T212] Call Trace: [ 10.445019][ T212] [ 10.445020][ T212] dump_stack_lvl+0x6f/0xa0 [ 10.445027][ T212] __might_resched.cold+0x1fe/0x2c1 [ 10.445031][ T212] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.445035][ T212] ? __kmalloc_noprof+0xdb/0x760 [ 10.445040][ T212] __kmalloc_noprof+0x443/0x760 [ 10.445042][ T212] ? alloc_buf.isra.0+0x4b/0x260 [ 10.445049][ T212] ? do_raw_spin_unlock+0x59/0x250 [ 10.445052][ T212] alloc_buf.isra.0+0x4b/0x260 [ 10.445056][ T212] put_chars+0x1e1/0x2f0 [ 10.445059][ T212] ? __send_to_port+0x420/0x420 [ 10.445060][ T212] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.445063][ T212] ? validate_chain+0x38b/0xc20 [ 10.445069][ T212] hvc_console_print+0x292/0x780 [ 10.445077][ T212] ? hvc_write+0x3a0/0x3a0 [ 10.445080][ T212] ? rcu_is_watching+0x16/0xd0 [ 10.445082][ T212] ? lock_acquire+0x13c/0x160 [ 10.445087][ T212] console_emit_next_record+0x22f/0x4c0 [ 10.445091][ T212] ? devkmsg_read+0x4b0/0x4b0 [ 10.445093][ T212] ? console_flush_one_record+0x106/0x710 [ 10.445096][ T212] ? rcu_is_watching+0x16/0xd0 [ 10.445098][ T212] ? lock_acquire+0x13c/0x160 [ 10.445102][ T212] console_flush_one_record+0x46f/0x710 [ 10.445106][ T212] ? console_emit_next_record+0x4c0/0x4c0 [ 10.445108][ T212] ? __lock_acquire+0x518/0xc20 [ 10.445114][ T212] console_unlock+0xee/0x1f0 [ 10.445117][ T212] ? console_flush_one_record+0x710/0x710 [ 10.445119][ T212] ? rcu_is_watching+0x16/0xd0 [ 10.445121][ T212] ? lock_acquire+0xe0/0x160 [ 10.445125][ T212] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.445127][ T212] ? vprintk_emit+0x320/0x3e0 [ 10.445130][ T212] vprintk_emit+0x37c/0x3e0 [ 10.445133][ T212] ? wake_up_klogd_work_func+0x90/0x90 [ 10.445136][ T212] ? __lock_acquire+0x518/0xc20 [ 10.445140][ T212] _printk+0xc7/0x100 [ 10.445144][ T212] ? snapshot_read.cold+0x21/0x21 [ 10.445146][ T212] ? do_raw_spin_lock+0x131/0x280 [ 10.445149][ T212] ? __rwlock_init+0x150/0x150 [ 10.445153][ T212] ? do_raw_spin_lock+0x131/0x280 [ 10.445156][ T212] do_exit.cold+0x82/0x9c [ 10.445160][ T212] ? exit_notify+0x890/0x890 [ 10.445161][ T212] ? __lock_release.isra.0+0x69/0x1a0 [ 10.445164][ T212] ? rcu_is_watching+0x16/0xd0 [ 10.445168][ T212] do_group_exit+0xb8/0x370 [ 10.445171][ T212] __x64_sys_exit_group+0x3c/0x50 [ 10.445173][ T212] x64_sys_call+0x1567/0x1570 [ 10.445175][ T212] do_syscall_64+0xff/0x530 [ 10.445180][ T212] ? exc_page_fault+0xee/0x100 [ 10.445183][ T212] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.445185][ T212] RIP: 0033:0x7f342b0d11b8 [ 10.445187][ T212] Code: Unable to access opcode bytes at 0x7f342b0d118e. [ 10.445188][ T212] RSP: 002b:00007ffd216fcb88 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.445191][ T212] RAX: ffffffffffffffda RBX: 00007f342b201f88 RCX: 00007f342b0d11b8 [ 10.445192][ T212] RDX: 00007f342ae1bfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.445193][ T212] RBP: 00007ffd216fcbe0 R08: 0000000000000000 R09: 0000000000008010 [ 10.445194][ T212] R10: 00007ffd216fc9a0 R11: 0000000000000246 R12: 0000000000000001 [ 10.445195][ T212] R13: 0000000000000000 R14: 00007f342b200680 R15: 00007f342b201fa0 [ 10.445202][ T212] [ 11.762100][ T288] br0: port 1(gw_l) entered blocking state [ 11.762181][ T288] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.762183][ T288] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 288, name: ip [ 11.762185][ T288] preempt_count: 1, expected: 0 [ 11.762186][ T288] RCU nest depth: 0, expected: 0 [ 11.762187][ T288] locks held by ip/288: 5, last CPU#2: [ 11.762189][ T288] #0: ffffffffb631b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 11.762201][ T288] #1: ffffffffb5b7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.762207][ T288] #2: ffffffffb5b7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.762212][ T288] #3: ffffffffb5a9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.762216][ T288] #4: ffffffffb5a9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.762220][ T288] irq event stamp: 36272 [ 11.762220][ T288] hardirqs last enabled at (36271): [] __down_trylock_console_sem+0x86/0xa0 [ 11.762224][ T288] hardirqs last disabled at (36272): [] console_emit_next_record+0x3d4/0x4c0 [ 11.762226][ T288] softirqs last enabled at (36182): [] __alloc_skb+0x4c2/0x5f0 [ 11.762229][ T288] softirqs last disabled at (36180): [] __alloc_skb+0x4c2/0x5f0 [ 11.762232][ T288] Preemption disabled at: [ 11.762233][ T288] [] vprintk_emit+0x31b/0x3e0 [ 11.762238][ T288] CPU: 2 UID: 0 PID: 288 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.762241][ T288] Tainted: [W]=WARN [ 11.762242][ T288] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.762244][ T288] Call Trace: [ 11.762245][ T288] [ 11.762247][ T288] dump_stack_lvl+0x6f/0xa0 [ 11.762254][ T288] ? vprintk_emit+0x31b/0x3e0 [ 11.762255][ T288] __might_resched.cold+0x1fe/0x2c1 [ 11.762260][ T288] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.762264][ T288] ? __kmalloc_noprof+0xdb/0x760 [ 11.762269][ T288] __kmalloc_noprof+0x443/0x760 [ 11.762271][ T288] ? alloc_buf.isra.0+0x4b/0x260 [ 11.762277][ T288] ? do_raw_spin_unlock+0x59/0x250 [ 11.762280][ T288] alloc_buf.isra.0+0x4b/0x260 [ 11.762284][ T288] put_chars+0x1e1/0x2f0 [ 11.762287][ T288] ? __send_to_port+0x420/0x420 [ 11.762290][ T288] ? validate_chain+0x34a/0xc20 [ 11.762295][ T288] hvc_console_print+0x292/0x780 [ 11.762303][ T288] ? hvc_write+0x3a0/0x3a0 [ 11.762306][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762309][ T288] ? lock_acquire+0x13c/0x160 [ 11.762313][ T288] console_emit_next_record+0x22f/0x4c0 [ 11.762317][ T288] ? devkmsg_read+0x4b0/0x4b0 [ 11.762319][ T288] ? console_flush_one_record+0x106/0x710 [ 11.762322][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762324][ T288] ? lock_acquire+0x13c/0x160 [ 11.762328][ T288] console_flush_one_record+0x46f/0x710 [ 11.762332][ T288] ? console_emit_next_record+0x4c0/0x4c0 [ 11.762334][ T288] ? __lock_acquire+0x518/0xc20 [ 11.762340][ T288] console_unlock+0xee/0x1f0 [ 11.762342][ T288] ? console_flush_one_record+0x710/0x710 [ 11.762344][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762346][ T288] ? lock_acquire+0xe0/0x160 [ 11.762350][ T288] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.762352][ T288] ? vprintk_emit+0x320/0x3e0 [ 11.762355][ T288] vprintk_emit+0x37c/0x3e0 [ 11.762358][ T288] ? wake_up_klogd_work_func+0x90/0x90 [ 11.762360][ T288] ? __lock_release.isra.0+0x69/0x1a0 [ 11.762361][ T288] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.762366][ T288] ? mark_held_locks+0x40/0x70 [ 11.762369][ T288] _printk+0xc7/0x100 [ 11.762373][ T288] ? snapshot_read.cold+0x21/0x21 [ 11.762381][ T288] ? br_multicast_flood+0x960/0x960 [ 11.762385][ T288] ? do_setlink.isra.0+0x9ec/0x2a60 [ 11.762386][ T288] ? rtnl_newlink+0x9f1/0x11b0 [ 11.762388][ T288] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.762393][ T288] br_set_state+0x22f/0x430 [ 11.762397][ T288] br_init_port+0xc4/0x200 [ 11.762399][ T288] new_nbp+0x3a0/0x650 [ 11.762403][ T288] br_add_if+0x212/0x1320 [ 11.762406][ T288] ? is_bpf_text_address+0x72/0x110 [ 11.762409][ T288] ? kernel_text_address+0x149/0x170 [ 11.762415][ T288] do_set_master+0x3b2/0x780 [ 11.762419][ T288] do_setlink.isra.0+0x9ec/0x2a60 [ 11.762422][ T288] ? stack_trace_save+0x93/0xc0 [ 11.762426][ T288] ? rtnl_link_get_size+0x350/0x350 [ 11.762427][ T288] ? rcu_read_lock_any_held+0x66/0x90 [ 11.762430][ T288] ? stack_depot_save_flags+0x38e/0x790 [ 11.762434][ T288] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.762436][ T288] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.762438][ T288] ? validate_chain+0x38b/0xc20 [ 11.762440][ T288] ? kasan_save_stack+0x3d/0x50 [ 11.762442][ T288] ? kasan_save_stack+0x2f/0x50 [ 11.762444][ T288] ? kasan_save_track+0x14/0x30 [ 11.762445][ T288] ? __kasan_kmalloc+0x7b/0x90 [ 11.762447][ T288] ? __lock_acquire+0x518/0xc20 [ 11.762449][ T288] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 11.762452][ T288] ? ___sys_sendmsg+0xd0/0x1d0 [ 11.762456][ T288] ? lock_acquire.part.0+0xd4/0x280 [ 11.762458][ T288] ? rtnl_newlink+0x9a8/0x11b0 [ 11.762461][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762463][ T288] ? lock_acquire+0x13c/0x160 [ 11.762465][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762466][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762467][ T288] ? trace_contention_end+0xb3/0x180 [ 11.762470][ T288] ? __mutex_lock+0x1db/0x1ea0 [ 11.762471][ T288] ? __mutex_lock+0x9a3/0x1ea0 [ 11.762473][ T288] ? rtnl_newlink+0x9a8/0x11b0 [ 11.762477][ T288] ? ww_mutex_lock+0x160/0x160 [ 11.762478][ T288] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.762483][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762485][ T288] ? __rtnl_newlink+0x3fa/0xa50 [ 11.762491][ T288] rtnl_newlink+0x9f1/0x11b0 [ 11.762496][ T288] ? rtnl_bridge_getlink+0x850/0x850 [ 11.762498][ T288] ? __lock_acquire+0x518/0xc20 [ 11.762503][ T288] ? lock_acquire.part.0+0xd4/0x280 [ 11.762505][ T288] ? find_held_lock+0x2b/0x80 [ 11.762507][ T288] ? rtnl_bridge_getlink+0x850/0x850 [ 11.762509][ T288] ? __lock_release.isra.0+0x69/0x1a0 [ 11.762513][ T288] ? rtnl_bridge_getlink+0x850/0x850 [ 11.762515][ T288] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.762518][ T288] ? rtnl_link_fill+0x920/0x920 [ 11.762520][ T288] ? __lock_acquire+0x518/0xc20 [ 11.762524][ T288] ? lock_acquire.part.0+0xd4/0x280 [ 11.762526][ T288] ? find_held_lock+0x2b/0x80 [ 11.762530][ T288] netlink_rcv_skb+0x14e/0x3a0 [ 11.762533][ T288] ? rtnl_link_fill+0x920/0x920 [ 11.762536][ T288] ? netlink_ack+0xcf0/0xcf0 [ 11.762543][ T288] ? netlink_deliver_tap+0xc5/0x330 [ 11.762545][ T288] ? netlink_deliver_tap+0x13c/0x330 [ 11.762549][ T288] netlink_unicast+0x486/0x750 [ 11.762554][ T288] ? netlink_attachskb+0x810/0x810 [ 11.762556][ T288] ? find_held_lock+0x2b/0x80 [ 11.762561][ T288] netlink_sendmsg+0x75b/0xc90 [ 11.762565][ T288] ? netlink_unicast+0x750/0x750 [ 11.762569][ T288] ? __lock_release.isra.0+0x69/0x1a0 [ 11.762570][ T288] ? __import_iovec+0x36c/0x620 [ 11.762576][ T288] __sock_sendmsg+0xce/0x190 [ 11.762579][ T288] ____sys_sendmsg+0x61a/0x860 [ 11.762581][ T288] ? copy_msghdr_from_user+0x279/0x420 [ 11.762584][ T288] ? sock_write_iter+0x400/0x400 [ 11.762585][ T288] ? move_addr_to_kernel+0x40/0x40 [ 11.762593][ T288] ___sys_sendmsg+0x14e/0x1d0 [ 11.762596][ T288] ? copy_msghdr_from_user+0x420/0x420 [ 11.762613][ T288] __sys_sendmsg+0x12c/0x1d0 [ 11.762616][ T288] ? __sys_sendmsg_sock+0x20/0x20 [ 11.762622][ T288] ? rcu_is_watching+0x16/0xd0 [ 11.762632][ T288] do_syscall_64+0xff/0x530 [ 11.762635][ T288] ? exc_page_fault+0xee/0x100 [ 11.762638][ T288] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.762641][ T288] RIP: 0033:0x7f492e64f54e [ 11.762645][ T288] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 11.762646][ T288] RSP: 002b:00007ffc6c1a1dd0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.762649][ T288] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f492e64f54e [ 11.762650][ T288] RDX: 0000000000000000 RSI: 00007ffc6c1a1e80 RDI: 0000000000000005 [ 11.762651][ T288] RBP: 00007ffc6c1a1de0 R08: 0000000000000000 R09: 0000000000000000 [ 11.762652][ T288] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a917a9c [ 11.762653][ T288] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc6c1a2540 [ 11.762660][ T288] [ 11.806033][ T288] br0: port 1(gw_l) entered disabled state [ 11.806421][ T288] gw_l: entered allmulticast mode [ 11.816796][ T288] gw_l: entered promiscuous mode [ 11.817816][ T288] br0: port 1(gw_l) entered blocking state [ 11.818215][ T288] br0: port 1(gw_l) entered forwarding state [ 11.824953][ T288] ip (288) used greatest stack depth: 23336 bytes left [ 11.980110][ T290] br0: port 2(amtg) entered blocking state [ 11.980769][ T290] br0: port 2(amtg) entered disabled state [ 11.987087][ T290] amtg: entered allmulticast mode [ 11.988330][ T290] amtg: entered promiscuous mode [ 12.584557][ T301] br0: port 2(amtg) entered blocking state [ 12.584912][ T301] br0: port 2(amtg) entered forwarding state [ 12.948978][ T308] amtr: entered allmulticast mode [ 12.948996][ T308] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.948998][ T308] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 308, name: smcrouted [ 12.948999][ T308] preempt_count: 1, expected: 0 [ 12.949000][ T308] RCU nest depth: 0, expected: 0 [ 12.949001][ T308] locks held by smcrouted/308: 5, last CPU#2: [ 12.949003][ T308] #0: ffffffffb631b740 (rtnl_mutex){+.+.}-{4:4}, at: ip_mroute_setsockopt+0x15c/0x1010 [ 12.949015][ T308] #1: ffffffffb5b7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.949021][ T308] #2: ffffffffb5b7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.949025][ T308] #3: ffffffffb5a9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.949029][ T308] #4: ffffffffb5a9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.949033][ T308] irq event stamp: 5256 [ 12.949034][ T308] hardirqs last enabled at (5255): [] __down_trylock_console_sem+0x86/0xa0 [ 12.949037][ T308] hardirqs last disabled at (5256): [] console_emit_next_record+0x3d4/0x4c0 [ 12.949039][ T308] softirqs last enabled at (5174): [] __alloc_skb+0x4c2/0x5f0 [ 12.949042][ T308] softirqs last disabled at (5172): [] __alloc_skb+0x4c2/0x5f0 [ 12.949045][ T308] Preemption disabled at: [ 12.949045][ T308] [] vprintk_emit+0x31b/0x3e0 [ 12.949050][ T308] CPU: 2 UID: 0 PID: 308 Comm: smcrouted Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.949054][ T308] Tainted: [W]=WARN [ 12.949054][ T308] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.949056][ T308] Call Trace: [ 12.949058][ T308] [ 12.949059][ T308] dump_stack_lvl+0x6f/0xa0 [ 12.949065][ T308] ? vprintk_emit+0x31b/0x3e0 [ 12.949067][ T308] __might_resched.cold+0x1fe/0x2c1 [ 12.949072][ T308] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.949076][ T308] ? __kmalloc_noprof+0xdb/0x760 [ 12.949080][ T308] __kmalloc_noprof+0x443/0x760 [ 12.949082][ T308] ? alloc_buf.isra.0+0x4b/0x260 [ 12.949089][ T308] ? do_raw_spin_unlock+0x59/0x250 [ 12.949092][ T308] alloc_buf.isra.0+0x4b/0x260 [ 12.949095][ T308] put_chars+0x1e1/0x2f0 [ 12.949098][ T308] ? __send_to_port+0x420/0x420 [ 12.949102][ T308] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.949105][ T308] ? validate_chain+0x38b/0xc20 [ 12.949109][ T308] hvc_console_print+0x292/0x780 [ 12.949113][ T308] ? __lock_acquire+0x518/0xc20 [ 12.949115][ T308] ? __lock_acquire+0x518/0xc20 [ 12.949120][ T308] ? hvc_write+0x3a0/0x3a0 [ 12.949124][ T308] ? rcu_is_watching+0x16/0xd0 [ 12.949126][ T308] ? lock_acquire+0x13c/0x160 [ 12.949130][ T308] console_emit_next_record+0x22f/0x4c0 [ 12.949134][ T308] ? devkmsg_read+0x4b0/0x4b0 [ 12.949136][ T308] ? console_flush_one_record+0x106/0x710 [ 12.949140][ T308] ? rcu_is_watching+0x16/0xd0 [ 12.949141][ T308] ? lock_acquire+0x13c/0x160 [ 12.949146][ T308] console_flush_one_record+0x46f/0x710 [ 12.949150][ T308] ? console_emit_next_record+0x4c0/0x4c0 [ 12.949152][ T308] ? __lock_acquire+0x518/0xc20 [ 12.949157][ T308] console_unlock+0xee/0x1f0 [ 12.949160][ T308] ? console_flush_one_record+0x710/0x710 [ 12.949162][ T308] ? rcu_is_watching+0x16/0xd0 [ 12.949164][ T308] ? lock_acquire+0xe0/0x160 [ 12.949168][ T308] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.949170][ T308] ? vprintk_emit+0x320/0x3e0 [ 12.949173][ T308] vprintk_emit+0x37c/0x3e0 [ 12.949176][ T308] ? wake_up_klogd_work_func+0x90/0x90 [ 12.949179][ T308] ? netlink_broadcast_filtered+0x29c/0x3c0 [ 12.949182][ T308] ? kasan_save_stack+0x3d/0x50 [ 12.949184][ T308] ? kasan_save_stack+0x2f/0x50 [ 12.949188][ T308] _printk+0xc7/0x100 [ 12.949191][ T308] ? snapshot_read.cold+0x21/0x21 [ 12.949193][ T308] ? __sys_setsockopt+0xe4/0x150 [ 12.949198][ T308] ? netdev_name+0x43/0x60 [ 12.949202][ T308] ? __netdev_printk+0x17f/0x1d0 [ 12.949206][ T308] netdev_info+0xe2/0x116 [ 12.949209][ T308] ? netdev_notice+0x120/0x120 [ 12.949211][ T308] ? mark_usage+0x61/0x170 [ 12.949217][ T308] ? find_held_lock+0x2b/0x80 [ 12.949220][ T308] ? mutex_is_locked+0x1c/0x50 [ 12.949222][ T308] netif_set_allmulti.cold+0x7c/0x166 [ 12.949226][ T308] dev_set_allmulti+0xa6/0x240 [ 12.949230][ T308] vif_add+0x9da/0xdc0 [ 12.949233][ T308] ? __mutex_lock+0x1db/0x1ea0 [ 12.949235][ T308] ? __mutex_lock+0x9a3/0x1ea0 [ 12.949239][ T308] ? ip_mr_output_finish.isra.0+0x630/0x630 [ 12.949243][ T308] ? lock_acquire.part.0+0xd4/0x280 [ 12.949245][ T308] ? __might_fault+0x97/0x140 [ 12.949250][ T308] ? __lock_release.isra.0+0x69/0x1a0 [ 12.949253][ T308] ? __might_fault+0x97/0x140 [ 12.949257][ T308] ip_mroute_setsockopt+0xd02/0x1010 [ 12.949261][ T308] ? ipmr_rtm_route+0x250/0x250 [ 12.949266][ T308] ? __might_fault+0x97/0x140 [ 12.949268][ T308] ? find_held_lock+0x2b/0x80 [ 12.949273][ T308] ? __might_fault+0x97/0x140 [ 12.949275][ T308] ? __might_fault+0x97/0x140 [ 12.949278][ T308] do_ip_setsockopt+0x27c/0x25f0 [ 12.949282][ T308] ? ip_sock_set_tos+0x40/0x40 [ 12.949285][ T308] ? find_held_lock+0x2b/0x80 [ 12.949288][ T308] ? __lock_release.isra.0+0x69/0x1a0 [ 12.949290][ T308] ? do_syscall_64+0xff/0x530 [ 12.949293][ T308] ? rcu_is_watching+0x16/0xd0 [ 12.949295][ T308] ? do_raw_spin_unlock+0x59/0x250 [ 12.949297][ T308] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.949299][ T308] ? lockdep_hardirqs_on+0x91/0x130 [ 12.949301][ T308] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 12.949303][ T308] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.949307][ T308] ip_setsockopt+0x2f/0x90 [ 12.949310][ T308] raw_setsockopt+0x8c/0x1f0 [ 12.949313][ T308] ? rcu_is_watching+0x16/0xd0 [ 12.949315][ T308] ? raw_bind+0x5b0/0x5b0 [ 12.949320][ T308] do_sock_setsockopt+0x164/0x3b0 [ 12.949323][ T308] ? put_user_ifreq+0x90/0x90 [ 12.949325][ T308] ? __lock_release.isra.0+0x69/0x1a0 [ 12.949328][ T308] ? fd_install+0x18c/0x3d0 [ 12.949332][ T308] ? fd_install+0x19b/0x3d0 [ 12.949337][ T308] __sys_setsockopt+0xe4/0x150 [ 12.949341][ T308] __x64_sys_setsockopt+0xbd/0x180 [ 12.949343][ T308] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.949347][ T308] ? lockdep_hardirqs_on+0x91/0x130 [ 12.949349][ T308] ? do_syscall_64+0xa6/0x530 [ 12.949350][ T308] do_syscall_64+0xff/0x530 [ 12.949352][ T308] ? exc_page_fault+0xee/0x100 [ 12.949355][ T308] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.949357][ T308] RIP: 0033:0x7f135a6e02de [ 12.949361][ T308] 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 [ 12.949363][ T308] RSP: 002b:00007ffc92879ff8 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 12.949365][ T308] RAX: ffffffffffffffda RBX: 0000557da05682c0 RCX: 00007f135a6e02de [ 12.949367][ T308] RDX: 00000000000000ca RSI: 0000000000000000 RDI: 0000000000000007 [ 12.949368][ T308] RBP: 00007ffc9287a0b0 R08: 0000000000000010 R09: 0000000000000001 [ 12.949369][ T308] R10: 00007ffc9287a030 R11: 0000000000000246 R12: 0000557dad1250c4 [ 12.949369][ T308] R13: 00007ffc9287a100 R14: 0000557dad1250b0 R15: 0000000000000000 [ 12.949380][ T308] [ 12.990440][ T308] relay_gw: entered allmulticast mode [ 12.990784][ T308] relay_src: entered allmulticast mode [ 27.096446][ C3] [ 27.096463][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.096465][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 27.096467][ C3] preempt_count: 104, expected: 0 [ 27.096468][ C3] RCU nest depth: 0, expected: 0 [ 27.096469][ C3] INFO: lockdep is turned off. [ 27.096470][ C3] irq event stamp: 665712 [ 27.096470][ C3] hardirqs last enabled at (665712): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.096479][ C3] hardirqs last disabled at (665711): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.096481][ C3] softirqs last enabled at (665690): [] handle_softirqs+0x67c/0x900 [ 27.096485][ C3] softirqs last disabled at (665709): [] __irq_exit_rcu+0x145/0x1c0 [ 27.096487][ C3] Preemption disabled at: [ 27.096488][ C3] [<0000000000000000>] 0x0 [ 27.096495][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.096499][ C3] Tainted: [W]=WARN [ 27.096500][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.096501][ C3] Call Trace: [ 27.096503][ C3] [ 27.096505][ C3] dump_stack_lvl+0x6f/0xa0 [ 27.096511][ C3] __might_resched.cold+0x1fe/0x2c1 [ 27.096514][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.096518][ C3] ? __asan_memcpy+0x3c/0x60 [ 27.096521][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096524][ C3] __kmalloc_noprof+0x443/0x760 [ 27.096527][ C3] ? __rwlock_init+0x150/0x150 [ 27.096530][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 27.096534][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 27.096536][ C3] alloc_buf.isra.0+0x4b/0x260 [ 27.096539][ C3] put_chars+0x1e1/0x2f0 [ 27.096540][ C3] ? __send_to_port+0x420/0x420 [ 27.096542][ C3] ? console_prepend_replay+0x20/0x20 [ 27.096545][ C3] hvc_console_print+0x292/0x780 [ 27.096550][ C3] ? hvc_write+0x3a0/0x3a0 [ 27.096552][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096553][ C3] ? lock_acquire+0x13c/0x160 [ 27.096556][ C3] console_emit_next_record+0x22f/0x4c0 [ 27.096559][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 27.096561][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096562][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096563][ C3] ? lock_acquire+0x13c/0x160 [ 27.096565][ C3] ? console_flush_one_record+0x111/0x710 [ 27.096566][ C3] console_flush_one_record+0x46f/0x710 [ 27.096569][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 27.096572][ C3] console_unlock+0xee/0x1f0 [ 27.096573][ C3] ? lock_acquire+0x13c/0x160 [ 27.096575][ C3] ? console_flush_one_record+0x710/0x710 [ 27.096577][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096578][ C3] ? lock_release+0x184/0x1f0 [ 27.096579][ C3] ? lock_acquire+0xe0/0x160 [ 27.096581][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.096583][ C3] ? vprintk_emit+0x320/0x3e0 [ 27.096585][ C3] vprintk_emit+0x37c/0x3e0 [ 27.096587][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 27.096588][ C3] ? common_startup_64+0x13e/0x148 [ 27.096592][ C3] _printk+0xc7/0x100 [ 27.096595][ C3] ? snapshot_read.cold+0x21/0x21 [ 27.096596][ C3] ? arch_stack_walk+0xd7/0x130 [ 27.096599][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.096601][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.096603][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.096605][ C3] mark_lock_irq+0x989/0x9c0 [ 27.096608][ C3] mark_lock+0x1d7/0xa00 [ 27.096610][ C3] mark_usage+0x42/0x170 [ 27.096612][ C3] __lock_acquire+0x388/0xc20 [ 27.096614][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.096616][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.096619][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096620][ C3] ? lock_acquire+0x13c/0x160 [ 27.096622][ C3] ? br_message_age_timer_expired+0x70/0x70 [ 27.096623][ C3] _raw_spin_lock+0x33/0x40 [ 27.096625][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.096626][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.096628][ C3] ? br_message_age_timer_expired+0x70/0x70 [ 27.096629][ C3] call_timer_fn+0x160/0x4d0 [ 27.096633][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 27.096634][ C3] ? debug_object_active_state+0x430/0x430 [ 27.096637][ C3] ? find_held_lock+0x2b/0x80 [ 27.096639][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.096641][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096643][ C3] __run_timers+0x68f/0xaa0 [ 27.096645][ C3] ? br_message_age_timer_expired+0x70/0x70 [ 27.096647][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.096649][ C3] ? __lock_acquire+0x518/0xc20 [ 27.096651][ C3] ? __rwlock_init+0x150/0x150 [ 27.096654][ C3] run_timer_softirq+0xf0/0x160 [ 27.096656][ C3] ? __run_timers+0xaa0/0xaa0 [ 27.096658][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.096661][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096662][ C3] handle_softirqs+0x1d3/0x900 [ 27.096664][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.096665][ C3] ? _local_bh_enable+0xc0/0xc0 [ 27.096668][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.096669][ C3] irq_exit_rcu+0xe/0x30 [ 27.096671][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.096674][ C3] [ 27.096674][ C3] [ 27.096675][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 27.096676][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.096679][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.096682][ C3] 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.096683][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 27.096687][ C3] RAX: 00000000000a286b RBX: ff11000001cb8040 RCX: ffffffffb1f06247 [ 27.096688][ C3] RDX: ff11000001cb8040 RSI: ffffffffb5111011 RDI: ffffffffb4a949e0 [ 27.096689][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.096690][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 27.096691][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.096692][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.096696][ C3] default_idle+0x9/0x10 [ 27.096697][ C3] default_idle_call+0x6e/0xb0 [ 27.096699][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.096701][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 27.096703][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 27.096705][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.096707][ C3] do_idle+0xd8/0x190 [ 27.096709][ C3] cpu_startup_entry+0x53/0x70 [ 27.096710][ C3] start_secondary+0x204/0x2b0 [ 27.096712][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.096714][ C3] common_startup_64+0x13e/0x148 [ 27.096723][ C3] [ 27.114487][ C3] ======================================================== [ 27.114689][ C3] WARNING: possible irq lock inversion dependency detected [ 27.114942][ C3] 7.2.0-virtme #1 Tainted: G W [ 27.115150][ C3] -------------------------------------------------------- [ 27.115396][ C3] swapper/3/0 just changed the state of lock: [ 27.115562][ C3] ff110000103fae98 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [ 27.115872][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.116121][ C3] (fs_reclaim){+.+.}-{0:0} [ 27.116124][ C3] [ 27.116124][ C3] [ 27.116124][ C3] and interrupts could create inverse lock ordering between them. [ 27.116124][ C3] [ 27.116753][ C3] [ 27.116753][ C3] other info that might help us debug this: [ 27.116952][ C3] Chain exists of: [ 27.116952][ C3] &br->lock --> console_owner --> fs_reclaim [ 27.116952][ C3] [ 27.117345][ C3] Possible interrupt unsafe locking scenario: [ 27.117345][ C3] [ 27.117545][ C3] CPU0 CPU1 [ 27.117729][ C3] ---- ---- [ 27.117867][ C3] lock(fs_reclaim); [ 27.118018][ C3] local_irq_disable(); [ 27.118186][ C3] lock(&br->lock); [ 27.118445][ C3] lock(console_owner); [ 27.118659][ C3] [ 27.121193][ C3] lock(&br->lock); [ 27.121301][ C3] [ 27.121301][ C3] *** DEADLOCK *** [ 27.121301][ C3] [ 27.121545][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 27.121769][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.122087][ C3] [ 27.122087][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 27.122363][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 27.122505][ C3] HARDIRQ-ON-W at: [ 27.122610][ C3] __lock_acquire+0x388/0xc20 [ 27.122840][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.123056][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.123226][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.123473][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.123733][ C3] workqueue_init+0x2a/0x680 [ 27.123905][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.124155][ C3] kernel_init+0x21/0x150 [ 27.124374][ C3] ret_from_fork+0x474/0x6b0 [ 27.124550][ C3] ret_from_fork_asm+0x11/0x20 [ 27.124777][ C3] SOFTIRQ-ON-W at: [ 27.124881][ C3] __lock_acquire+0x388/0xc20 [ 27.125098][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.125316][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.125485][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.125739][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.125988][ C3] workqueue_init+0x2a/0x680 [ 27.126158][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.126409][ C3] kernel_init+0x21/0x150 [ 27.126626][ C3] ret_from_fork+0x474/0x6b0 [ 27.126802][ C3] ret_from_fork_asm+0x11/0x20 [ 27.127019][ C3] INITIAL USE at: [ 27.127125][ C3] __lock_acquire+0x388/0xc20 [ 27.127343][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.127558][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.127733][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.127989][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.128241][ C3] workqueue_init+0x2a/0x680 [ 27.128411][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.128629][ C3] kernel_init+0x21/0x150 [ 27.128854][ C3] ret_from_fork+0x474/0x6b0 [ 27.129030][ C3] ret_from_fork_asm+0x11/0x20 [ 27.129244][ C3] } [ 27.129315][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.129567][ C3] ... acquired at: [ 27.129670][ C3] __lock_acquire+0x518/0xc20 [ 27.129864][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.130001][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.130178][ C3] __kmalloc_noprof+0xd3/0x760 [ 27.130313][ C3] alloc_buf.isra.0+0x4b/0x260 [ 27.130449][ C3] put_chars+0x1e1/0x2f0 [ 27.130585][ C3] hvc_console_print+0x292/0x780 [ 27.130774][ C3] console_emit_next_record+0x22f/0x4c0 [ 27.130961][ C3] console_flush_one_record+0x46f/0x710 [ 27.131144][ C3] console_unlock+0xee/0x1f0 [ 27.131283][ C3] vprintk_emit+0x37c/0x3e0 [ 27.131464][ C3] _printk+0xc7/0x100 [ 27.131567][ C3] i8042_pnp_init+0xf7/0x3c0 [ 27.131757][ C3] i8042_platform_init+0x3f9/0x460 [ 27.131892][ C3] i8042_init+0x45/0x130 [ 27.132074][ C3] do_one_initcall+0x124/0x4f0 [ 27.132207][ C3] kernel_init_freeable+0x596/0x630 [ 27.132387][ C3] kernel_init+0x21/0x150 [ 27.132524][ C3] ret_from_fork+0x474/0x6b0 [ 27.132707][ C3] ret_from_fork_asm+0x11/0x20 [ 27.132849][ C3] [ 27.132919][ C3] -> (console_owner){....}-{0:0} { [ 27.133104][ C3] INITIAL USE at: [ 27.133206][ C3] } [ 27.133322][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.133520][ C3] ... acquired at: [ 27.133713][ C3] __lock_acquire+0x518/0xc20 [ 27.133858][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.134040][ C3] console_lock_spinning_enable+0x5c/0x60 [ 27.134208][ C3] console_emit_next_record+0x1d1/0x4c0 [ 27.134390][ C3] console_flush_one_record+0x46f/0x710 [ 27.134524][ C3] console_unlock+0xee/0x1f0 [ 27.134704][ C3] vprintk_emit+0x37c/0x3e0 [ 27.134847][ C3] _printk+0xc7/0x100 [ 27.135000][ C3] br_set_state+0x22f/0x430 [ 27.135144][ C3] br_init_port+0xc4/0x200 [ 27.135325][ C3] br_stp_enable_port+0x12/0x50 [ 27.135458][ C3] br_add_if+0xde2/0x1320 [ 27.135640][ C3] do_set_master+0x3b2/0x780 [ 27.135835][ C3] do_setlink.isra.0+0x9ec/0x2a60 [ 27.136017][ C3] rtnl_newlink+0x9f1/0x11b0 [ 27.136148][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.136331][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.136465][ C3] netlink_unicast+0x486/0x750 [ 27.136643][ C3] netlink_sendmsg+0x75b/0xc90 [ 27.136788][ C3] __sock_sendmsg+0xce/0x190 [ 27.136976][ C3] ____sys_sendmsg+0x61a/0x860 [ 27.137109][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.137291][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.137426][ C3] do_syscall_64+0xff/0x530 [ 27.137652][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.137823][ C3] [ 27.137935][ C3] -> (&br->lock){+.-.}-{3:3} { [ 27.138071][ C3] HARDIRQ-ON-W at: [ 27.138173][ C3] __lock_acquire+0x388/0xc20 [ 27.138392][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.138609][ C3] _raw_spin_lock_bh+0x38/0x50 [ 27.138790][ C3] br_stp_enable_bridge+0x23/0x340 [ 27.139006][ C3] br_dev_open+0x94/0x120 [ 27.139267][ C3] __dev_open+0x322/0x7a0 [ 27.139438][ C3] __dev_change_flags+0x4a4/0x6d0 [ 27.139657][ C3] netif_change_flags+0x87/0x190 [ 27.139832][ C3] do_setlink.isra.0+0x1d25/0x2a60 [ 27.140046][ C3] rtnl_newlink+0x9f1/0x11b0 [ 27.140262][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.140428][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.140647][ C3] netlink_unicast+0x486/0x750 [ 27.140872][ C3] netlink_sendmsg+0x75b/0xc90 [ 27.141043][ C3] __sock_sendmsg+0xce/0x190 [ 27.141257][ C3] ____sys_sendmsg+0x61a/0x860 [ 27.141428][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.141688][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.141908][ C3] do_syscall_64+0xff/0x530 [ 27.142076][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.142323][ C3] IN-SOFTIRQ-W at: [ 27.142423][ C3] __lock_acquire+0x388/0xc20 [ 27.142636][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.142859][ C3] _raw_spin_lock+0x33/0x40 [ 27.143030][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.143277][ C3] call_timer_fn+0x160/0x4d0 [ 27.143494][ C3] __run_timers+0x68f/0xaa0 [ 27.143712][ C3] run_timer_softirq+0xf0/0x160 [ 27.143934][ C3] handle_softirqs+0x1d3/0x900 [ 27.144152][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.144321][ C3] irq_exit_rcu+0xe/0x30 [ 27.144535][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.144793][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.144994][ C3] pv_native_safe_halt+0xf/0x10 [ 27.145206][ C3] default_idle+0x9/0x10 [ 27.145469][ C3] default_idle_call+0x6e/0xb0 [ 27.145636][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.145892][ C3] do_idle+0xd8/0x190 [ 27.146029][ C3] cpu_startup_entry+0x53/0x70 [ 27.146243][ C3] start_secondary+0x204/0x2b0 [ 27.146461][ C3] common_startup_64+0x13e/0x148 [ 27.146628][ C3] INITIAL USE at: [ 27.146787][ C3] __lock_acquire+0x388/0xc20 [ 27.146960][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.147221][ C3] _raw_spin_lock_bh+0x38/0x50 [ 27.147390][ C3] br_stp_enable_bridge+0x23/0x340 [ 27.147557][ C3] br_dev_open+0x94/0x120 [ 27.147783][ C3] __dev_open+0x322/0x7a0 [ 27.147951][ C3] __dev_change_flags+0x4a4/0x6d0 [ 27.148163][ C3] netif_change_flags+0x87/0x190 [ 27.148376][ C3] do_setlink.isra.0+0x1d25/0x2a60 [ 27.148544][ C3] rtnl_newlink+0x9f1/0x11b0 [ 27.148767][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.148981][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.149147][ C3] netlink_unicast+0x486/0x750 [ 27.149362][ C3] netlink_sendmsg+0x75b/0xc90 [ 27.149529][ C3] __sock_sendmsg+0xce/0x190 [ 27.149752][ C3] ____sys_sendmsg+0x61a/0x860 [ 27.150017][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.150183][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.150398][ C3] do_syscall_64+0xff/0x530 [ 27.150612][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.150819][ C3] } [ 27.150934][ C3] ... key at: [] __key.7+0x0/0x40 [ 27.151103][ C3] ... acquired at: [ 27.151249][ C3] mark_lock+0x1d7/0xa00 [ 27.151383][ C3] mark_usage+0x42/0x170 [ 27.151560][ C3] __lock_acquire+0x388/0xc20 [ 27.151693][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.151924][ C3] _raw_spin_lock+0x33/0x40 [ 27.152056][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.152267][ C3] call_timer_fn+0x160/0x4d0 [ 27.152398][ C3] __run_timers+0x68f/0xaa0 [ 27.152580][ C3] run_timer_softirq+0xf0/0x160 [ 27.152714][ C3] handle_softirqs+0x1d3/0x900 [ 27.152903][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.153035][ C3] irq_exit_rcu+0xe/0x30 [ 27.153214][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.153380][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.153594][ C3] pv_native_safe_halt+0xf/0x10 [ 27.153734][ C3] default_idle+0x9/0x10 [ 27.153960][ C3] default_idle_call+0x6e/0xb0 [ 27.154091][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.154300][ C3] do_idle+0xd8/0x190 [ 27.154403][ C3] cpu_startup_entry+0x53/0x70 [ 27.154584][ C3] start_secondary+0x204/0x2b0 [ 27.154723][ C3] common_startup_64+0x13e/0x148 [ 27.154907][ C3] [ 27.154973][ C3] [ 27.154973][ C3] stack backtrace: [ 27.155183][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.155187][ C3] Tainted: [W]=WARN [ 27.155188][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.155188][ C3] Call Trace: [ 27.155189][ C3] [ 27.155190][ C3] dump_stack_lvl+0x6f/0xa0 [ 27.155194][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.155198][ C3] mark_lock_irq+0x989/0x9c0 [ 27.155201][ C3] mark_lock+0x1d7/0xa00 [ 27.155203][ C3] mark_usage+0x42/0x170 [ 27.155204][ C3] __lock_acquire+0x388/0xc20 [ 27.155207][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.155208][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.155210][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.155212][ C3] ? lock_acquire+0x13c/0x160 [ 27.155214][ C3] ? br_message_age_timer_expired+0x70/0x70 [ 27.155215][ C3] _raw_spin_lock+0x33/0x40 [ 27.155217][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.155218][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.155220][ C3] ? br_message_age_timer_expired+0x70/0x70 [ 27.155221][ C3] call_timer_fn+0x160/0x4d0 [ 27.155223][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 27.155225][ C3] ? debug_object_active_state+0x430/0x430 [ 27.155228][ C3] ? find_held_lock+0x2b/0x80 [ 27.155230][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.155231][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.155233][ C3] __run_timers+0x68f/0xaa0 [ 27.155235][ C3] ? br_message_age_timer_expired+0x70/0x70 [ 27.155237][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.155239][ C3] ? __lock_acquire+0x518/0xc20 [ 27.155241][ C3] ? __rwlock_init+0x150/0x150 [ 27.155244][ C3] run_timer_softirq+0xf0/0x160 [ 27.155246][ C3] ? __run_timers+0xaa0/0xaa0 [ 27.155248][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.155250][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.155252][ C3] handle_softirqs+0x1d3/0x900 [ 27.155254][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.155255][ C3] ? _local_bh_enable+0xc0/0xc0 [ 27.155258][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.155259][ C3] irq_exit_rcu+0xe/0x30 [ 27.155261][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.155262][ C3] [ 27.155263][ C3] [ 27.155264][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 27.155265][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.155267][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.155269][ C3] 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.155270][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 27.155272][ C3] RAX: 00000000000a286b RBX: ff11000001cb8040 RCX: ffffffffb1f06247 [ 27.155273][ C3] RDX: ff11000001cb8040 RSI: ffffffffb5111011 RDI: ffffffffb4a949e0 [ 27.155273][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.155274][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 27.155275][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.155276][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.155279][ C3] default_idle+0x9/0x10 [ 27.155280][ C3] default_idle_call+0x6e/0xb0 [ 27.155282][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.155284][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 27.155285][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 27.155287][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.155289][ C3] do_idle+0xd8/0x190 [ 27.155290][ C3] cpu_startup_entry+0x53/0x70 [ 27.155292][ C3] start_secondary+0x204/0x2b0 [ 27.155294][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.155296][ C3] common_startup_64+0x13e/0x148 [ 27.155299][ C3] [ 519.622805][ T12] br0: port 1(gw_l) entered disabled state [ 519.622823][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 519.622825][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 519.622827][ T12] preempt_count: 202, expected: 0 [ 519.622828][ T12] RCU nest depth: 0, expected: 0 [ 519.622829][ T12] INFO: lockdep is turned off. [ 519.622830][ T12] irq event stamp: 9540 [ 519.622830][ T12] hardirqs last enabled at (9539): [] _raw_spin_unlock_irq+0x28/0x50 [ 519.622839][ T12] hardirqs last disabled at (9540): [] __schedule+0x1246/0x20e0 [ 519.622842][ T12] softirqs last enabled at (9536): [] __fib6_clean_all+0xff/0x290 [ 519.622845][ T12] softirqs last disabled at (9530): [] __fib6_clean_all+0xd9/0x290 [ 519.622847][ T12] Preemption disabled at: [ 519.622848][ T12] [<0000000000000000>] 0x0 [ 519.622854][ T12] CPU: 3 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 519.622858][ T12] Tainted: [W]=WARN [ 519.622859][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 519.622861][ T12] Workqueue: netns cleanup_net [ 519.622867][ T12] Call Trace: [ 519.622869][ T12] [ 519.622871][ T12] dump_stack_lvl+0x6f/0xa0 [ 519.622877][ T12] __might_resched.cold+0x1fe/0x2c1 [ 519.622880][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 519.622884][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.622887][ T12] __kmalloc_noprof+0x443/0x760 [ 519.622890][ T12] ? __rwlock_init+0x150/0x150 [ 519.622893][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 519.622898][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 519.622900][ T12] alloc_buf.isra.0+0x4b/0x260 [ 519.622902][ T12] put_chars+0x1e1/0x2f0 [ 519.622904][ T12] ? __send_to_port+0x420/0x420 [ 519.622905][ T12] ? printk_get_next_message+0x240/0x650 [ 519.622908][ T12] ? console_prepend_replay+0x20/0x20 [ 519.622911][ T12] hvc_console_print+0x292/0x780 [ 519.622915][ T12] ? hvc_write+0x3a0/0x3a0 [ 519.622917][ T12] ? __rwlock_init+0x150/0x150 [ 519.622919][ T12] ? lock_acquire+0x13c/0x160 [ 519.622922][ T12] console_emit_next_record+0x22f/0x4c0 [ 519.622924][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 519.622926][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.622928][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.622929][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.622930][ T12] ? lock_acquire+0x13c/0x160 [ 519.622932][ T12] ? console_flush_one_record+0x111/0x710 [ 519.622933][ T12] console_flush_one_record+0x46f/0x710 [ 519.622936][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 519.622939][ T12] console_unlock+0xee/0x1f0 [ 519.622940][ T12] ? lock_acquire+0x13c/0x160 [ 519.622942][ T12] ? console_flush_one_record+0x710/0x710 [ 519.622944][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.622945][ T12] ? lock_release+0x184/0x1f0 [ 519.622946][ T12] ? lock_acquire+0xe0/0x160 [ 519.622948][ T12] ? __rcu_read_lock+0x70/0x70 [ 519.622949][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 519.622951][ T12] ? vprintk_emit+0x320/0x3e0 [ 519.622953][ T12] vprintk_emit+0x37c/0x3e0 [ 519.622955][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 519.622956][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.622957][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 519.622960][ T12] ? trace_hardirqs_on+0x36/0x40 [ 519.622962][ T12] _printk+0xc7/0x100 [ 519.622965][ T12] ? snapshot_read.cold+0x21/0x21 [ 519.622967][ T12] ? ipv6_mc_down+0x17e/0x2d0 [ 519.622971][ T12] br_set_state+0x22f/0x430 [ 519.622974][ T12] br_stp_disable_port+0x11f/0x390 [ 519.622976][ T12] br_device_event+0x7b5/0x8f0 [ 519.622979][ T12] ? packet_sendmsg_spkt+0x1360/0x1360 [ 519.622981][ T12] ? addrconf_notify+0x2c8/0xf30 [ 519.622984][ T12] notifier_call_chain+0xae/0x300 [ 519.622987][ T12] netif_close_many+0x366/0x770 [ 519.622990][ T12] ? __dev_close_many+0x680/0x680 [ 519.622992][ T12] ? netif_close_many_and_unlock+0x22/0x2c0 [ 519.622994][ T12] unregister_netdevice_many_notify+0x71a/0x1b40 [ 519.622996][ T12] ? ops_undo_list+0x2ce/0x8f0 [ 519.622998][ T12] ? worker_thread+0x4f1/0xd60 [ 519.623001][ T12] ? kthread+0x367/0x460 [ 519.623002][ T12] ? ret_from_fork+0x474/0x6b0 [ 519.623005][ T12] ? ret_from_fork_asm+0x11/0x20 [ 519.623008][ T12] ? unregister_netdevice_queued+0x80/0x80 [ 519.623009][ T12] ? get_rps_cpu+0x980/0x980 [ 519.623012][ T12] default_device_exit_batch+0x38b/0x600 [ 519.623013][ T12] ? __mutex_unlock_slowpath+0x5e5/0x740 [ 519.623015][ T12] ? lock_acquire+0x13c/0x160 [ 519.623017][ T12] ? unregister_netdevice_queue+0x470/0x470 [ 519.623018][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 519.623020][ T12] ? fou_exit_net+0x30/0x110 [ 519.623023][ T12] ops_undo_list+0x2ce/0x8f0 [ 519.623025][ T12] ? netns_install+0x2a0/0x2a0 [ 519.623027][ T12] ? idr_destroy+0x121/0x250 [ 519.623031][ T12] cleanup_net+0x431/0x940 [ 519.623032][ T12] ? net_passive_dec+0x230/0x230 [ 519.623034][ T12] ? lock_acquire+0x13c/0x160 [ 519.623036][ T12] ? rcu_is_watching+0x16/0xd0 [ 519.623037][ T12] process_one_work+0xe3e/0x1560 [ 519.623040][ T12] ? __queue_delayed_work+0x470/0x470 [ 519.623043][ T12] ? find_held_lock+0x2b/0x80 [ 519.623044][ T12] ? __rwlock_init+0x150/0x150 [ 519.623047][ T12] worker_thread+0x4f1/0xd60 [ 519.623050][ T12] ? rescuer_thread+0x1340/0x1340 [ 519.623052][ T12] kthread+0x367/0x460 [ 519.623054][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 519.623055][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 519.623057][ T12] ret_from_fork+0x474/0x6b0 [ 519.623059][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 519.623061][ T12] ? __switch_to+0x5a3/0xe00 [ 519.623064][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 519.623065][ T12] ret_from_fork_asm+0x11/0x20 [ 519.623069][ T12] [ 519.644095][ T12] gw_l (unregistering): left allmulticast mode [ 519.644277][ T12] gw_l (unregistering): left promiscuous mode [ 519.644448][ T12] br0: port 1(gw_l) entered disabled state [ 519.673284][ T12] amtg: left allmulticast mode [ 519.673456][ T12] amtg: left promiscuous mode [ 519.673645][ T12] br0: port 2(amtg) entered disabled state [ 519.682505][ T308] amtr: left allmulticast mode [ 519.682762][ T308] relay_gw: left allmulticast mode [ 519.682972][ T308] relay_src: left allmulticast mode