[ 9.091194][ T186] ip (186) used greatest stack depth: 24368 bytes left [ 9.091209][ T186] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.091211][ T186] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 186, name: ip [ 9.091213][ T186] preempt_count: 2, expected: 0 [ 9.091213][ T186] RCU nest depth: 0, expected: 0 [ 9.091214][ T186] locks held by ip/186: 5, last CPU#3: [ 9.091217][ T186] #0: ffffffffa8e027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.091229][ T186] #1: ffffffffa8f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.091235][ T186] #2: ffffffffa8f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.091238][ T186] #3: ffffffffa8e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.091242][ T186] #4: ffffffffa8e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.091246][ T186] irq event stamp: 32792 [ 9.091246][ T186] hardirqs last enabled at (32791): [] __down_trylock_console_sem+0x86/0xa0 [ 9.091249][ T186] hardirqs last disabled at (32792): [] console_emit_next_record+0x3d4/0x4c0 [ 9.091251][ T186] softirqs last enabled at (31522): [] handle_softirqs+0x67c/0x900 [ 9.091252][ T186] softirqs last disabled at (31517): [] __irq_exit_rcu+0x145/0x1c0 [ 9.091255][ T186] Preemption disabled at: [ 9.091256][ T186] [<0000000000000000>] 0x0 [ 9.091263][ T186] CPU: 3 UID: 0 PID: 186 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.091267][ T186] Tainted: [W]=WARN [ 9.091268][ T186] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.091270][ T186] Call Trace: [ 9.091272][ T186] [ 9.091274][ T186] dump_stack_lvl+0x6f/0xa0 [ 9.091280][ T186] __might_resched.cold+0x1fe/0x2c1 [ 9.091285][ T186] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.091289][ T186] ? __kmalloc_noprof+0xdb/0x760 [ 9.091294][ T186] __kmalloc_noprof+0x443/0x760 [ 9.091296][ T186] ? alloc_buf.isra.0+0x4b/0x260 [ 9.091308][ T186] ? do_raw_spin_unlock+0x59/0x250 [ 9.091311][ T186] alloc_buf.isra.0+0x4b/0x260 [ 9.091314][ T186] put_chars+0x1e1/0x2f0 [ 9.091316][ T186] ? __send_to_port+0x420/0x420 [ 9.091317][ T186] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.091322][ T186] ? validate_chain+0x38b/0xc20 [ 9.091327][ T186] hvc_console_print+0x292/0x780 [ 9.091333][ T186] ? hvc_write+0x3a0/0x3a0 [ 9.091335][ T186] ? rcu_is_watching+0x16/0xd0 [ 9.091338][ T186] ? lock_acquire+0x13c/0x160 [ 9.091342][ T186] console_emit_next_record+0x22f/0x4c0 [ 9.091345][ T186] ? devkmsg_read+0x4b0/0x4b0 [ 9.091347][ T186] ? console_flush_one_record+0x106/0x710 [ 9.091350][ T186] ? rcu_is_watching+0x16/0xd0 [ 9.091353][ T186] ? lock_acquire+0x13c/0x160 [ 9.091356][ T186] console_flush_one_record+0x46f/0x710 [ 9.091360][ T186] ? console_emit_next_record+0x4c0/0x4c0 [ 9.091362][ T186] ? __lock_acquire+0x518/0xc20 [ 9.091368][ T186] console_unlock+0xee/0x1f0 [ 9.091372][ T186] ? console_flush_one_record+0x710/0x710 [ 9.091373][ T186] ? rcu_is_watching+0x16/0xd0 [ 9.091376][ T186] ? lock_acquire+0x60/0x160 [ 9.091379][ T186] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.091381][ T186] ? vprintk_emit+0x320/0x3e0 [ 9.091384][ T186] vprintk_emit+0x37c/0x3e0 [ 9.091388][ T186] ? wake_up_klogd_work_func+0x90/0x90 [ 9.091391][ T186] ? __lock_acquire+0x518/0xc20 [ 9.091395][ T186] _printk+0xc7/0x100 [ 9.091399][ T186] ? snapshot_read.cold+0x21/0x21 [ 9.091401][ T186] ? do_raw_spin_lock+0x131/0x280 [ 9.091404][ T186] ? __rwlock_init+0x150/0x150 [ 9.091407][ T186] ? do_raw_spin_lock+0x131/0x280 [ 9.091410][ T186] do_exit.cold+0x82/0x9c [ 9.091414][ T186] ? exit_notify+0x890/0x890 [ 9.091416][ T186] ? __lock_release.isra.0+0x69/0x1a0 [ 9.091418][ T186] ? rcu_is_watching+0x16/0xd0 [ 9.091422][ T186] do_group_exit+0xb8/0x370 [ 9.091425][ T186] __x64_sys_exit_group+0x3c/0x50 [ 9.091427][ T186] x64_sys_call+0x1567/0x1570 [ 9.091429][ T186] do_syscall_64+0xff/0x530 [ 9.091432][ T186] ? exc_page_fault+0xee/0x100 [ 9.091435][ T186] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.091437][ T186] RIP: 0033:0x7f8a1e1911b8 [ 9.091439][ T186] Code: Unable to access opcode bytes at 0x7f8a1e19118e. [ 9.091441][ T186] RSP: 002b:00007fffcf8fe8b8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.091443][ T186] RAX: ffffffffffffffda RBX: 00007f8a1e2c1f88 RCX: 00007f8a1e1911b8 [ 9.091444][ T186] RDX: 00007f8a1dedbfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.091445][ T186] RBP: 00007fffcf8fe910 R08: 0000000000000000 R09: 0000000000008010 [ 9.091446][ T186] R10: 00007fffcf8fe6d0 R11: 0000000000000246 R12: 0000000000000001 [ 9.091447][ T186] R13: 0000000000000000 R14: 00007f8a1e2c0680 R15: 00007f8a1e2c1fa0 [ 9.091454][ T186] [ 9.296780][ T193] ip (193) used greatest stack depth: 23824 bytes left [ 10.988109][ T260] br1: port 1(vx1) entered blocking state [ 10.988180][ T260] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.988182][ T260] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 260, name: ip [ 10.988183][ T260] preempt_count: 1, expected: 0 [ 10.988184][ T260] RCU nest depth: 0, expected: 0 [ 10.988185][ T260] locks held by ip/260: 5, last CPU#2: [ 10.988187][ T260] #0: ffffffffa96d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 10.988199][ T260] #1: ffffffffa8f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.988205][ T260] #2: ffffffffa8f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.988209][ T260] #3: ffffffffa8e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.988212][ T260] #4: ffffffffa8e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.988216][ T260] irq event stamp: 13110 [ 10.988217][ T260] hardirqs last enabled at (13109): [] __down_trylock_console_sem+0x86/0xa0 [ 10.988219][ T260] hardirqs last disabled at (13110): [] console_emit_next_record+0x3d4/0x4c0 [ 10.988221][ T260] softirqs last enabled at (13020): [] __alloc_skb+0x4c2/0x5f0 [ 10.988225][ T260] softirqs last disabled at (13018): [] __alloc_skb+0x4c2/0x5f0 [ 10.988227][ T260] Preemption disabled at: [ 10.988228][ T260] [] vprintk_emit+0x31b/0x3e0 [ 10.988233][ T260] CPU: 2 UID: 0 PID: 260 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.988237][ T260] Tainted: [W]=WARN [ 10.988238][ T260] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.988240][ T260] Call Trace: [ 10.988241][ T260] [ 10.988243][ T260] dump_stack_lvl+0x6f/0xa0 [ 10.988249][ T260] ? vprintk_emit+0x31b/0x3e0 [ 10.988251][ T260] __might_resched.cold+0x1fe/0x2c1 [ 10.988255][ T260] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.988260][ T260] ? __kmalloc_noprof+0xdb/0x760 [ 10.988267][ T260] __kmalloc_noprof+0x443/0x760 [ 10.988270][ T260] ? alloc_buf.isra.0+0x4b/0x260 [ 10.988278][ T260] ? do_raw_spin_unlock+0x59/0x250 [ 10.988281][ T260] alloc_buf.isra.0+0x4b/0x260 [ 10.988284][ T260] put_chars+0x1e1/0x2f0 [ 10.988287][ T260] ? __send_to_port+0x420/0x420 [ 10.988291][ T260] ? validate_chain+0x34a/0xc20 [ 10.988295][ T260] hvc_console_print+0x292/0x780 [ 10.988297][ T260] ? mark_usage+0x61/0x170 [ 10.988299][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988301][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988304][ T260] ? hvc_write+0x3a0/0x3a0 [ 10.988306][ T260] ? console_emit_next_record+0x1df/0x4c0 [ 10.988317][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988321][ T260] ? lock_acquire+0x13c/0x160 [ 10.988325][ T260] console_emit_next_record+0x22f/0x4c0 [ 10.988328][ T260] ? devkmsg_read+0x4b0/0x4b0 [ 10.988330][ T260] ? console_flush_one_record+0x106/0x710 [ 10.988333][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988335][ T260] ? lock_acquire+0x13c/0x160 [ 10.988339][ T260] console_flush_one_record+0x46f/0x710 [ 10.988343][ T260] ? console_emit_next_record+0x4c0/0x4c0 [ 10.988344][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988349][ T260] console_unlock+0xee/0x1f0 [ 10.988352][ T260] ? console_flush_one_record+0x710/0x710 [ 10.988354][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988356][ T260] ? lock_acquire+0x60/0x160 [ 10.988359][ T260] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.988361][ T260] ? vprintk_emit+0x320/0x3e0 [ 10.988365][ T260] vprintk_emit+0x37c/0x3e0 [ 10.988369][ T260] ? wake_up_klogd_work_func+0x90/0x90 [ 10.988373][ T260] ? __lock_release.isra.0+0x69/0x1a0 [ 10.988374][ T260] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 10.988378][ T260] ? mark_held_locks+0x40/0x70 [ 10.988382][ T260] _printk+0xc7/0x100 [ 10.988385][ T260] ? snapshot_read.cold+0x21/0x21 [ 10.988389][ T260] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 10.988401][ T260] ? do_setlink.isra.0+0xa31/0x2750 [ 10.988402][ T260] ? rtnl_newlink+0x9f1/0x11c0 [ 10.988403][ T260] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.988408][ T260] br_set_state+0x22f/0x430 [bridge] [ 10.988417][ T260] br_init_port+0xc4/0x200 [bridge] [ 10.988425][ T260] new_nbp+0x39c/0x580 [bridge] [ 10.988434][ T260] br_add_if+0x212/0x1320 [bridge] [ 10.988442][ T260] ? is_bpf_text_address+0x72/0x110 [ 10.988446][ T260] ? kernel_text_address+0x149/0x170 [ 10.988449][ T260] ? __kernel_text_address+0x12/0x30 [ 10.988452][ T260] do_set_master+0x357/0x580 [ 10.988457][ T260] do_setlink.isra.0+0xa31/0x2750 [ 10.988460][ T260] ? stack_trace_save+0x93/0xc0 [ 10.988463][ T260] ? rtnl_link_get_size+0x350/0x350 [ 10.988464][ T260] ? rcu_read_lock_any_held+0x66/0x90 [ 10.988467][ T260] ? stack_depot_save_flags+0x38e/0x790 [ 10.988470][ T260] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.988473][ T260] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.988475][ T260] ? validate_chain+0x38b/0xc20 [ 10.988477][ T260] ? kasan_save_stack+0x3d/0x50 [ 10.988480][ T260] ? kasan_save_stack+0x2f/0x50 [ 10.988482][ T260] ? kasan_save_track+0x14/0x30 [ 10.988484][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988486][ T260] ? netlink_seq_next+0x4e/0x60 [ 10.988489][ T260] ? ___sys_sendmsg+0xb0/0x1d0 [ 10.988493][ T260] ? lock_acquire.part.0+0xd4/0x280 [ 10.988495][ T260] ? rtnl_newlink+0x9a8/0x11c0 [ 10.988497][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988500][ T260] ? lock_acquire+0x13c/0x160 [ 10.988501][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988503][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988505][ T260] ? trace_contention_end+0xb3/0x180 [ 10.988509][ T260] ? __mutex_lock+0x1db/0x1ea0 [ 10.988510][ T260] ? __mutex_lock+0x9a3/0x1ea0 [ 10.988512][ T260] ? rtnl_newlink+0x9a8/0x11c0 [ 10.988515][ T260] ? ww_mutex_lock+0x160/0x160 [ 10.988517][ T260] ? nla_get_range_signed+0x3d0/0x3d0 [ 10.988522][ T260] ? __rtnl_newlink+0x3fa/0xa50 [ 10.988527][ T260] rtnl_newlink+0x9f1/0x11c0 [ 10.988532][ T260] ? rtnl_bridge_getlink+0x850/0x850 [ 10.988534][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988538][ T260] ? lock_acquire.part.0+0xd4/0x280 [ 10.988540][ T260] ? find_held_lock+0x2b/0x80 [ 10.988542][ T260] ? rtnl_bridge_getlink+0x850/0x850 [ 10.988543][ T260] ? __lock_release.isra.0+0x69/0x1a0 [ 10.988547][ T260] ? rtnl_bridge_getlink+0x850/0x850 [ 10.988549][ T260] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.988552][ T260] ? rtnl_link_fill+0x920/0x920 [ 10.988553][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988557][ T260] ? lock_acquire.part.0+0xd4/0x280 [ 10.988559][ T260] ? find_held_lock+0x2b/0x80 [ 10.988562][ T260] netlink_rcv_skb+0x14e/0x3a0 [ 10.988564][ T260] ? rtnl_link_fill+0x920/0x920 [ 10.988566][ T260] ? netlink_ack+0xcf0/0xcf0 [ 10.988572][ T260] ? netlink_deliver_tap+0xc5/0x330 [ 10.988574][ T260] ? netlink_deliver_tap+0x13c/0x330 [ 10.988578][ T260] netlink_unicast+0x486/0x750 [ 10.988581][ T260] ? netlink_attachskb+0x810/0x810 [ 10.988584][ T260] ? __lock_acquire+0x518/0xc20 [ 10.988588][ T260] netlink_sendmsg+0x735/0xc60 [ 10.988591][ T260] ? netlink_unicast+0x750/0x750 [ 10.988595][ T260] ? __might_fault+0x97/0x140 [ 10.988600][ T260] ____sys_sendmsg+0x415/0x880 [ 10.988602][ T260] ? copy_msghdr_from_user+0x279/0x420 [ 10.988604][ T260] ? get_timestamp.constprop.0+0x390/0x390 [ 10.988606][ T260] ? move_addr_to_kernel+0x40/0x40 [ 10.988613][ T260] ___sys_sendmsg+0x14e/0x1d0 [ 10.988616][ T260] ? copy_msghdr_from_user+0x420/0x420 [ 10.988631][ T260] __sys_sendmsg+0x12c/0x1d0 [ 10.988634][ T260] ? __sys_sendmsg_sock+0x20/0x20 [ 10.988640][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.988643][ T260] do_syscall_64+0xff/0x530 [ 10.988645][ T260] ? exc_page_fault+0xee/0x100 [ 10.988648][ T260] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.988651][ T260] RIP: 0033:0x7f78344da54e [ 10.988654][ T260] 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 [ 10.988656][ T260] RSP: 002b:00007fff08d285e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 10.988659][ T260] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f78344da54e [ 10.988660][ T260] RDX: 0000000000000000 RSI: 00007fff08d28690 RDI: 0000000000000005 [ 10.988661][ T260] RBP: 00007fff08d285f0 R08: 0000000000000000 R09: 0000000000000000 [ 10.988661][ T260] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910138 [ 10.988662][ T260] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff08d28d50 [ 10.988669][ T260] [ 11.041264][ T260] br1: port 1(vx1) entered disabled state [ 11.041638][ T260] vx1: entered allmulticast mode [ 11.043286][ T260] vx1: entered promiscuous mode [ 11.044235][ T260] br1: port 1(vx1) entered blocking state [ 11.044754][ T260] br1: port 1(vx1) entered forwarding state [ 11.051866][ T260] ip (260) used greatest stack depth: 23336 bytes left [ 11.068958][ T261] br1: port 2(veth1) entered blocking state [ 11.069761][ T261] br1: port 2(veth1) entered disabled state [ 11.070046][ T261] veth1: entered allmulticast mode [ 11.071502][ T261] veth1: entered promiscuous mode [ 11.105521][ T38] br1: port 2(veth1) entered blocking state [ 11.105920][ T38] br1: port 2(veth1) entered forwarding state [ 11.154645][ T264] br1: port 3(veth2) entered blocking state [ 11.154948][ T264] br1: port 3(veth2) entered disabled state [ 11.155244][ T264] veth2: entered allmulticast mode [ 11.156725][ T264] veth2: entered promiscuous mode [ 11.180477][ T38] br1: port 3(veth2) entered blocking state [ 11.180771][ T38] br1: port 3(veth2) entered forwarding state [ 12.239397][ T309] br2: port 1(w1) entered blocking state [ 12.239413][ T309] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.239415][ T309] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 309, name: ip [ 12.239417][ T309] preempt_count: 1, expected: 0 [ 12.239418][ T309] RCU nest depth: 0, expected: 0 [ 12.239419][ T309] locks held by ip/309: 5, last CPU#2: [ 12.239421][ T309] #0: ffffffffa96d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.239433][ T309] #1: ffffffffa8f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.239439][ T309] #2: ffffffffa8f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.239443][ T309] #3: ffffffffa8e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.239446][ T309] #4: ffffffffa8e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.239450][ T309] irq event stamp: 12638 [ 12.239451][ T309] hardirqs last enabled at (12637): [] __down_trylock_console_sem+0x86/0xa0 [ 12.239454][ T309] hardirqs last disabled at (12638): [] console_emit_next_record+0x3d4/0x4c0 [ 12.239455][ T309] softirqs last enabled at (12580): [] __alloc_skb+0x4c2/0x5f0 [ 12.239459][ T309] softirqs last disabled at (12578): [] __alloc_skb+0x4c2/0x5f0 [ 12.239461][ T309] Preemption disabled at: [ 12.239462][ T309] [] vprintk_emit+0x31b/0x3e0 [ 12.239468][ T309] CPU: 2 UID: 0 PID: 309 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.239471][ T309] Tainted: [W]=WARN [ 12.239472][ T309] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.239474][ T309] Call Trace: [ 12.239475][ T309] [ 12.239477][ T309] dump_stack_lvl+0x6f/0xa0 [ 12.239483][ T309] ? vprintk_emit+0x31b/0x3e0 [ 12.239485][ T309] __might_resched.cold+0x1fe/0x2c1 [ 12.239490][ T309] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.239494][ T309] ? __kmalloc_noprof+0xdb/0x760 [ 12.239499][ T309] __kmalloc_noprof+0x443/0x760 [ 12.239501][ T309] ? alloc_buf.isra.0+0x4b/0x260 [ 12.239507][ T309] ? do_raw_spin_unlock+0x59/0x250 [ 12.239510][ T309] alloc_buf.isra.0+0x4b/0x260 [ 12.239513][ T309] put_chars+0x1e1/0x2f0 [ 12.239515][ T309] ? prb_final_commit+0x50/0x50 [ 12.239517][ T309] ? __send_to_port+0x420/0x420 [ 12.239519][ T309] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.239524][ T309] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.239526][ T309] ? validate_chain+0x38b/0xc20 [ 12.239530][ T309] hvc_console_print+0x292/0x780 [ 12.239533][ T309] ? mark_usage+0x61/0x170 [ 12.239534][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239536][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239540][ T309] ? hvc_write+0x3a0/0x3a0 [ 12.239542][ T309] ? console_emit_next_record+0x1df/0x4c0 [ 12.239545][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239547][ T309] ? lock_acquire+0x13c/0x160 [ 12.239551][ T309] console_emit_next_record+0x22f/0x4c0 [ 12.239555][ T309] ? devkmsg_read+0x4b0/0x4b0 [ 12.239556][ T309] ? console_flush_one_record+0x106/0x710 [ 12.239559][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239562][ T309] ? lock_acquire+0x13c/0x160 [ 12.239566][ T309] console_flush_one_record+0x46f/0x710 [ 12.239570][ T309] ? console_emit_next_record+0x4c0/0x4c0 [ 12.239571][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239576][ T309] console_unlock+0xee/0x1f0 [ 12.239579][ T309] ? console_flush_one_record+0x710/0x710 [ 12.239581][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239583][ T309] ? lock_acquire+0x60/0x160 [ 12.239587][ T309] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.239588][ T309] ? vprintk_emit+0x320/0x3e0 [ 12.239591][ T309] vprintk_emit+0x37c/0x3e0 [ 12.239595][ T309] ? wake_up_klogd_work_func+0x90/0x90 [ 12.239598][ T309] ? __lock_release.isra.0+0x69/0x1a0 [ 12.239600][ T309] ? mark_held_locks+0x40/0x70 [ 12.239604][ T309] _printk+0xc7/0x100 [ 12.239607][ T309] ? snapshot_read.cold+0x21/0x21 [ 12.239611][ T309] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.239624][ T309] ? do_setlink.isra.0+0xa31/0x2750 [ 12.239625][ T309] ? rtnl_newlink+0x9f1/0x11c0 [ 12.239627][ T309] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.239631][ T309] br_set_state+0x22f/0x430 [bridge] [ 12.239641][ T309] br_init_port+0xc4/0x200 [bridge] [ 12.239649][ T309] new_nbp+0x39c/0x580 [bridge] [ 12.239658][ T309] br_add_if+0x212/0x1320 [bridge] [ 12.239665][ T309] ? is_bpf_text_address+0x72/0x110 [ 12.239669][ T309] ? kernel_text_address+0x149/0x170 [ 12.239672][ T309] ? __kernel_text_address+0x12/0x30 [ 12.239676][ T309] do_set_master+0x357/0x580 [ 12.239680][ T309] do_setlink.isra.0+0xa31/0x2750 [ 12.239683][ T309] ? stack_trace_save+0x93/0xc0 [ 12.239686][ T309] ? rtnl_link_get_size+0x350/0x350 [ 12.239687][ T309] ? rcu_read_lock_any_held+0x66/0x90 [ 12.239689][ T309] ? stack_depot_save_flags+0x38e/0x790 [ 12.239693][ T309] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.239696][ T309] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.239698][ T309] ? validate_chain+0x38b/0xc20 [ 12.239700][ T309] ? kasan_save_stack+0x3d/0x50 [ 12.239703][ T309] ? kasan_save_stack+0x2f/0x50 [ 12.239704][ T309] ? kasan_save_track+0x14/0x30 [ 12.239707][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239709][ T309] ? netlink_seq_next+0x4e/0x60 [ 12.239712][ T309] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.239716][ T309] ? lock_acquire.part.0+0xd4/0x280 [ 12.239718][ T309] ? rtnl_newlink+0x9a8/0x11c0 [ 12.239720][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239723][ T309] ? lock_acquire+0x13c/0x160 [ 12.239724][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239726][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239728][ T309] ? trace_contention_end+0xb3/0x180 [ 12.239732][ T309] ? __mutex_lock+0x1db/0x1ea0 [ 12.239735][ T309] ? __mutex_lock+0x9a3/0x1ea0 [ 12.239737][ T309] ? rtnl_newlink+0x9a8/0x11c0 [ 12.239740][ T309] ? ww_mutex_lock+0x160/0x160 [ 12.239742][ T309] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.239747][ T309] ? __rtnl_newlink+0x3fa/0xa50 [ 12.239752][ T309] rtnl_newlink+0x9f1/0x11c0 [ 12.239757][ T309] ? rtnl_bridge_getlink+0x850/0x850 [ 12.239759][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239763][ T309] ? lock_acquire.part.0+0xd4/0x280 [ 12.239765][ T309] ? find_held_lock+0x2b/0x80 [ 12.239767][ T309] ? rtnl_bridge_getlink+0x850/0x850 [ 12.239769][ T309] ? __lock_release.isra.0+0x69/0x1a0 [ 12.239773][ T309] ? rtnl_bridge_getlink+0x850/0x850 [ 12.239775][ T309] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.239778][ T309] ? rtnl_link_fill+0x920/0x920 [ 12.239779][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239783][ T309] ? lock_acquire.part.0+0xd4/0x280 [ 12.239785][ T309] ? find_held_lock+0x2b/0x80 [ 12.239788][ T309] netlink_rcv_skb+0x14e/0x3a0 [ 12.239790][ T309] ? rtnl_link_fill+0x920/0x920 [ 12.239793][ T309] ? netlink_ack+0xcf0/0xcf0 [ 12.239799][ T309] ? netlink_deliver_tap+0xc5/0x330 [ 12.239801][ T309] ? netlink_deliver_tap+0x13c/0x330 [ 12.239805][ T309] netlink_unicast+0x486/0x750 [ 12.239808][ T309] ? netlink_attachskb+0x810/0x810 [ 12.239811][ T309] ? __lock_acquire+0x518/0xc20 [ 12.239815][ T309] netlink_sendmsg+0x735/0xc60 [ 12.239819][ T309] ? netlink_unicast+0x750/0x750 [ 12.239822][ T309] ? __might_fault+0x97/0x140 [ 12.239828][ T309] ____sys_sendmsg+0x415/0x880 [ 12.239830][ T309] ? copy_msghdr_from_user+0x279/0x420 [ 12.239832][ T309] ? get_timestamp.constprop.0+0x390/0x390 [ 12.239834][ T309] ? move_addr_to_kernel+0x40/0x40 [ 12.239841][ T309] ___sys_sendmsg+0x14e/0x1d0 [ 12.239844][ T309] ? copy_msghdr_from_user+0x420/0x420 [ 12.239859][ T309] __sys_sendmsg+0x12c/0x1d0 [ 12.239861][ T309] ? __sys_sendmsg_sock+0x20/0x20 [ 12.239868][ T309] ? rcu_is_watching+0x16/0xd0 [ 12.239871][ T309] do_syscall_64+0xff/0x530 [ 12.239873][ T309] ? exc_page_fault+0xee/0x100 [ 12.239876][ T309] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.239879][ T309] RIP: 0033:0x7fad27b6854e [ 12.239882][ T309] 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.239884][ T309] RSP: 002b:00007fff7d6ffcf0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.239886][ T309] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fad27b6854e [ 12.239888][ T309] RDX: 0000000000000000 RSI: 00007fff7d6ffda0 RDI: 0000000000000005 [ 12.239889][ T309] RBP: 00007fff7d6ffd00 R08: 0000000000000000 R09: 0000000000000000 [ 12.239890][ T309] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910139 [ 12.239891][ T309] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff7d700460 [ 12.239897][ T309] [ 12.287438][ T309] br2: port 1(w1) entered disabled state [ 12.287809][ T309] w1: entered allmulticast mode [ 12.290003][ T309] w1: entered promiscuous mode [ 12.462697][ T315] br2: port 2(vx2) entered blocking state [ 12.462962][ T315] br2: port 2(vx2) entered disabled state [ 12.463638][ T315] vx2: entered allmulticast mode [ 12.465089][ T315] vx2: entered promiscuous mode [ 12.465676][ T315] br2: port 2(vx2) entered blocking state [ 12.465903][ T315] br2: port 2(vx2) entered forwarding state [ 12.653261][ T38] br2: port 1(w1) entered blocking state [ 12.653613][ T38] br2: port 1(w1) entered forwarding state [ 13.208916][ T349] br2: port 1(w1) entered blocking state [ 13.209809][ T349] br2: port 1(w1) entered disabled state [ 13.210096][ T349] w1: entered allmulticast mode [ 13.211659][ T349] w1: entered promiscuous mode [ 13.366753][ T355] br2: port 2(vx2) entered blocking state [ 13.366763][ T355] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.366766][ T355] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 355, name: ip [ 13.366767][ T355] preempt_count: 1, expected: 0 [ 13.366768][ T355] RCU nest depth: 0, expected: 0 [ 13.366769][ T355] locks held by ip/355: 5, last CPU#0: [ 13.366771][ T355] #0: ffffffffa96d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.366783][ T355] #1: ffffffffa8f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.366789][ T355] #2: ffffffffa8f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.366793][ T355] #3: ffffffffa8e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.366797][ T355] #4: ffffffffa8e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.366801][ T355] irq event stamp: 17696 [ 13.366802][ T355] hardirqs last enabled at (17695): [] __down_trylock_console_sem+0x86/0xa0 [ 13.366804][ T355] hardirqs last disabled at (17696): [] console_emit_next_record+0x3d4/0x4c0 [ 13.366806][ T355] softirqs last enabled at (17638): [] __alloc_skb+0x4c2/0x5f0 [ 13.366809][ T355] softirqs last disabled at (17636): [] __alloc_skb+0x4c2/0x5f0 [ 13.366811][ T355] Preemption disabled at: [ 13.366812][ T355] [] vprintk_emit+0x31b/0x3e0 [ 13.366818][ T355] CPU: 0 UID: 0 PID: 355 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.366821][ T355] Tainted: [W]=WARN [ 13.366822][ T355] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.366824][ T355] Call Trace: [ 13.366825][ T355] [ 13.366827][ T355] dump_stack_lvl+0x6f/0xa0 [ 13.366833][ T355] ? vprintk_emit+0x31b/0x3e0 [ 13.366835][ T355] __might_resched.cold+0x1fe/0x2c1 [ 13.366839][ T355] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.366843][ T355] ? __kmalloc_noprof+0xdb/0x760 [ 13.366849][ T355] __kmalloc_noprof+0x443/0x760 [ 13.366851][ T355] ? alloc_buf.isra.0+0x4b/0x260 [ 13.366857][ T355] ? do_raw_spin_unlock+0x59/0x250 [ 13.366859][ T355] alloc_buf.isra.0+0x4b/0x260 [ 13.366862][ T355] put_chars+0x1e1/0x2f0 [ 13.366864][ T355] ? prb_final_commit+0x50/0x50 [ 13.366866][ T355] ? __send_to_port+0x420/0x420 [ 13.366869][ T355] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.366873][ T355] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.366876][ T355] ? validate_chain+0x38b/0xc20 [ 13.366879][ T355] hvc_console_print+0x292/0x780 [ 13.366882][ T355] ? mark_usage+0x61/0x170 [ 13.366884][ T355] ? __lock_acquire+0x518/0xc20 [ 13.366885][ T355] ? __lock_acquire+0x518/0xc20 [ 13.366889][ T355] ? hvc_write+0x3a0/0x3a0 [ 13.366891][ T355] ? console_emit_next_record+0x1df/0x4c0 [ 13.366894][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.366897][ T355] ? lock_acquire+0x13c/0x160 [ 13.366901][ T355] console_emit_next_record+0x22f/0x4c0 [ 13.366904][ T355] ? devkmsg_read+0x4b0/0x4b0 [ 13.366906][ T355] ? console_flush_one_record+0x106/0x710 [ 13.366909][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.366912][ T355] ? lock_acquire+0x13c/0x160 [ 13.366916][ T355] console_flush_one_record+0x46f/0x710 [ 13.366920][ T355] ? console_emit_next_record+0x4c0/0x4c0 [ 13.366921][ T355] ? __lock_acquire+0x518/0xc20 [ 13.366926][ T355] console_unlock+0xee/0x1f0 [ 13.366929][ T355] ? console_flush_one_record+0x710/0x710 [ 13.366931][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.366933][ T355] ? lock_acquire+0x60/0x160 [ 13.366937][ T355] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.366938][ T355] ? vprintk_emit+0x320/0x3e0 [ 13.366942][ T355] vprintk_emit+0x37c/0x3e0 [ 13.366946][ T355] ? wake_up_klogd_work_func+0x90/0x90 [ 13.366948][ T355] ? __lock_release.isra.0+0x69/0x1a0 [ 13.366951][ T355] ? mark_held_locks+0x40/0x70 [ 13.366954][ T355] _printk+0xc7/0x100 [ 13.366958][ T355] ? snapshot_read.cold+0x21/0x21 [ 13.366961][ T355] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.366974][ T355] ? do_setlink.isra.0+0xa31/0x2750 [ 13.366975][ T355] ? rtnl_newlink+0x9f1/0x11c0 [ 13.366976][ T355] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.366981][ T355] br_set_state+0x22f/0x430 [bridge] [ 13.366990][ T355] br_init_port+0xc4/0x200 [bridge] [ 13.366999][ T355] new_nbp+0x39c/0x580 [bridge] [ 13.367008][ T355] br_add_if+0x212/0x1320 [bridge] [ 13.367014][ T355] ? is_bpf_text_address+0x72/0x110 [ 13.367018][ T355] ? kernel_text_address+0x149/0x170 [ 13.367021][ T355] ? __kernel_text_address+0x12/0x30 [ 13.367025][ T355] do_set_master+0x357/0x580 [ 13.367030][ T355] do_setlink.isra.0+0xa31/0x2750 [ 13.367033][ T355] ? stack_trace_save+0x93/0xc0 [ 13.367036][ T355] ? rtnl_link_get_size+0x350/0x350 [ 13.367037][ T355] ? rcu_read_lock_any_held+0x66/0x90 [ 13.367039][ T355] ? stack_depot_save_flags+0x38e/0x790 [ 13.367043][ T355] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.367045][ T355] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.367047][ T355] ? validate_chain+0x38b/0xc20 [ 13.367049][ T355] ? kasan_save_stack+0x3d/0x50 [ 13.367052][ T355] ? kasan_save_stack+0x2f/0x50 [ 13.367054][ T355] ? kasan_save_track+0x14/0x30 [ 13.367057][ T355] ? __lock_acquire+0x518/0xc20 [ 13.367058][ T355] ? netlink_seq_next+0x4e/0x60 [ 13.367061][ T355] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.367065][ T355] ? lock_acquire.part.0+0xd4/0x280 [ 13.367067][ T355] ? rtnl_newlink+0x9a8/0x11c0 [ 13.367070][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.367072][ T355] ? lock_acquire+0x13c/0x160 [ 13.367073][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.367075][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.367077][ T355] ? trace_contention_end+0xb3/0x180 [ 13.367081][ T355] ? __mutex_lock+0x1db/0x1ea0 [ 13.367084][ T355] ? __mutex_lock+0x9a3/0x1ea0 [ 13.367086][ T355] ? rtnl_newlink+0x9a8/0x11c0 [ 13.367090][ T355] ? ww_mutex_lock+0x160/0x160 [ 13.367091][ T355] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.367096][ T355] ? __rtnl_newlink+0x3fa/0xa50 [ 13.367101][ T355] rtnl_newlink+0x9f1/0x11c0 [ 13.367106][ T355] ? rtnl_bridge_getlink+0x850/0x850 [ 13.367108][ T355] ? __lock_acquire+0x518/0xc20 [ 13.367112][ T355] ? lock_acquire.part.0+0xd4/0x280 [ 13.367114][ T355] ? find_held_lock+0x2b/0x80 [ 13.367116][ T355] ? rtnl_bridge_getlink+0x850/0x850 [ 13.367118][ T355] ? __lock_release.isra.0+0x69/0x1a0 [ 13.367121][ T355] ? rtnl_bridge_getlink+0x850/0x850 [ 13.367124][ T355] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.367126][ T355] ? rtnl_link_fill+0x920/0x920 [ 13.367128][ T355] ? __lock_acquire+0x518/0xc20 [ 13.367132][ T355] ? lock_acquire.part.0+0xd4/0x280 [ 13.367134][ T355] ? find_held_lock+0x2b/0x80 [ 13.367137][ T355] netlink_rcv_skb+0x14e/0x3a0 [ 13.367139][ T355] ? rtnl_link_fill+0x920/0x920 [ 13.367142][ T355] ? netlink_ack+0xcf0/0xcf0 [ 13.367150][ T355] ? netlink_deliver_tap+0xc5/0x330 [ 13.367151][ T355] ? netlink_deliver_tap+0x13c/0x330 [ 13.367155][ T355] netlink_unicast+0x486/0x750 [ 13.367159][ T355] ? netlink_attachskb+0x810/0x810 [ 13.367162][ T355] ? __lock_acquire+0x518/0xc20 [ 13.367166][ T355] netlink_sendmsg+0x735/0xc60 [ 13.367169][ T355] ? netlink_unicast+0x750/0x750 [ 13.367173][ T355] ? __might_fault+0x97/0x140 [ 13.367178][ T355] ____sys_sendmsg+0x415/0x880 [ 13.367180][ T355] ? copy_msghdr_from_user+0x279/0x420 [ 13.367183][ T355] ? get_timestamp.constprop.0+0x390/0x390 [ 13.367184][ T355] ? move_addr_to_kernel+0x40/0x40 [ 13.367191][ T355] ___sys_sendmsg+0x14e/0x1d0 [ 13.367194][ T355] ? copy_msghdr_from_user+0x420/0x420 [ 13.367209][ T355] __sys_sendmsg+0x12c/0x1d0 [ 13.367212][ T355] ? __sys_sendmsg_sock+0x20/0x20 [ 13.367218][ T355] ? rcu_is_watching+0x16/0xd0 [ 13.367222][ T355] do_syscall_64+0xff/0x530 [ 13.367224][ T355] ? exc_page_fault+0xee/0x100 [ 13.367227][ T355] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.367229][ T355] RIP: 0033:0x7fa1753d054e [ 13.367232][ T355] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 13.367234][ T355] RSP: 002b:00007ffce14e24d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.367237][ T355] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fa1753d054e [ 13.367238][ T355] RDX: 0000000000000000 RSI: 00007ffce14e2580 RDI: 0000000000000005 [ 13.367239][ T355] RBP: 00007ffce14e24e0 R08: 0000000000000000 R09: 0000000000000000 [ 13.367240][ T355] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91013a [ 13.367241][ T355] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffce14e2c40 [ 13.367247][ T355] [ 13.408659][ T355] br2: port 2(vx2) entered disabled state [ 13.408893][ T355] vx2: entered allmulticast mode [ 13.410408][ T355] vx2: entered promiscuous mode [ 13.411106][ T355] br2: port 2(vx2) entered blocking state [ 13.411351][ T355] br2: port 2(vx2) entered forwarding state [ 13.605701][ T39] br2: port 1(w1) entered blocking state [ 13.605972][ T39] br2: port 1(w1) entered forwarding state [ 20.629926][ T437] GACT probability NOT on [ 20.629942][ T437] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 20.629944][ T437] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 437, name: modprobe [ 20.629945][ T437] preempt_count: 1, expected: 0 [ 20.629946][ T437] RCU nest depth: 0, expected: 0 [ 20.629947][ T437] locks held by modprobe/437: 4, last CPU#2: [ 20.629949][ T437] #0: ffffffffa8f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 20.629961][ T437] #1: ffffffffa8f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 20.629965][ T437] #2: ffffffffa8e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 20.629969][ T437] #3: ffffffffa8e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 20.629973][ T437] irq event stamp: 27984 [ 20.629974][ T437] hardirqs last enabled at (27983): [] __down_trylock_console_sem+0x86/0xa0 [ 20.629976][ T437] hardirqs last disabled at (27984): [] console_emit_next_record+0x3d4/0x4c0 [ 20.629978][ T437] softirqs last enabled at (26690): [] handle_softirqs+0x67c/0x900 [ 20.629981][ T437] softirqs last disabled at (25787): [] __irq_exit_rcu+0x145/0x1c0 [ 20.629984][ T437] Preemption disabled at: [ 20.629985][ T437] [] vprintk_emit+0x31b/0x3e0 [ 20.629990][ T437] CPU: 2 UID: 0 PID: 437 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 20.629994][ T437] Tainted: [W]=WARN [ 20.629995][ T437] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.629996][ T437] Call Trace: [ 20.629998][ T437] [ 20.629999][ T437] dump_stack_lvl+0x6f/0xa0 [ 20.630005][ T437] ? vprintk_emit+0x31b/0x3e0 [ 20.630007][ T437] __might_resched.cold+0x1fe/0x2c1 [ 20.630012][ T437] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 20.630016][ T437] ? __kmalloc_noprof+0xdb/0x760 [ 20.630021][ T437] __kmalloc_noprof+0x443/0x760 [ 20.630023][ T437] ? alloc_buf.isra.0+0x4b/0x260 [ 20.630029][ T437] ? do_raw_spin_unlock+0x59/0x250 [ 20.630032][ T437] alloc_buf.isra.0+0x4b/0x260 [ 20.630035][ T437] put_chars+0x1e1/0x2f0 [ 20.630037][ T437] ? desc_read_finalized_seq+0x79/0x120 [ 20.630038][ T437] ? __send_to_port+0x420/0x420 [ 20.630041][ T437] ? rcu_read_lock_any_held+0x3c/0x90 [ 20.630045][ T437] ? validate_chain+0x38b/0xc20 [ 20.630049][ T437] hvc_console_print+0x292/0x780 [ 20.630051][ T437] ? __lock_acquire+0x518/0xc20 [ 20.630053][ T437] ? __lock_acquire+0x518/0xc20 [ 20.630057][ T437] ? hvc_write+0x3a0/0x3a0 [ 20.630058][ T437] ? console_emit_next_record+0x1df/0x4c0 [ 20.630061][ T437] ? rcu_is_watching+0x16/0xd0 [ 20.630064][ T437] ? lock_acquire+0x13c/0x160 [ 20.630068][ T437] console_emit_next_record+0x22f/0x4c0 [ 20.630072][ T437] ? devkmsg_read+0x4b0/0x4b0 [ 20.630074][ T437] ? console_flush_one_record+0x106/0x710 [ 20.630077][ T437] ? rcu_is_watching+0x16/0xd0 [ 20.630079][ T437] ? lock_acquire+0x13c/0x160 [ 20.630083][ T437] console_flush_one_record+0x46f/0x710 [ 20.630086][ T437] ? console_emit_next_record+0x4c0/0x4c0 [ 20.630088][ T437] ? __lock_acquire+0x518/0xc20 [ 20.630093][ T437] console_unlock+0xee/0x1f0 [ 20.630096][ T437] ? console_flush_one_record+0x710/0x710 [ 20.630097][ T437] ? rcu_is_watching+0x16/0xd0 [ 20.630100][ T437] ? lock_acquire+0x60/0x160 [ 20.630103][ T437] ? __down_trylock_console_sem+0x5e/0xa0 [ 20.630105][ T437] ? vprintk_emit+0x320/0x3e0 [ 20.630108][ T437] vprintk_emit+0x37c/0x3e0 [ 20.630112][ T437] ? wake_up_klogd_work_func+0x90/0x90 [ 20.630117][ T437] ? llc_exit+0x3e0/0x3e0 [llc] [ 20.630120][ T437] _printk+0xc7/0x100 [ 20.630124][ T437] ? snapshot_read.cold+0x21/0x21 [ 20.630130][ T437] ? do_one_initcall+0x113/0x4f0 [ 20.630133][ T437] gact_init_module+0x15/0x1000 [act_gact] [ 20.630136][ T437] do_one_initcall+0x124/0x4f0 [ 20.630138][ T437] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 20.630143][ T437] ? do_init_module+0xae/0x8a0 [ 20.630146][ T437] ? kasan_unpoison+0x44/0x70 [ 20.630152][ T437] do_init_module+0x2ad/0x8a0 [ 20.630156][ T437] ? lockdep_hardirqs_on+0x91/0x130 [ 20.630160][ T437] ? free_module+0x470/0x470 [ 20.630163][ T437] ? kfree+0x123/0x590 [ 20.630167][ T437] load_module+0x1424/0x1bb0 [ 20.630174][ T437] ? layout_and_allocate.isra.0+0x680/0x680 [ 20.630178][ T437] ? __ia32_sys_fsconfig+0x180/0x180 [ 20.630181][ T437] ? find_held_lock+0x2b/0x80 [ 20.630184][ T437] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 20.630187][ T437] init_module_from_file+0x11f/0x1a0 [ 20.630191][ T437] ? __do_sys_init_module+0x220/0x220 [ 20.630198][ T437] ? __rwlock_init+0x150/0x150 [ 20.630202][ T437] ? do_raw_spin_unlock+0x59/0x250 [ 20.630205][ T437] idempotent_init_module+0x37b/0x7c0 [ 20.630208][ T437] ? init_module_from_file+0x1a0/0x1a0 [ 20.630214][ T437] ? rcu_is_watching+0x16/0xd0 [ 20.630216][ T437] ? cap_capable+0x1d7/0x3d0 [ 20.630222][ T437] __x64_sys_finit_module+0xdf/0x190 [ 20.630224][ T437] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 20.630228][ T437] do_syscall_64+0xff/0x530 [ 20.630230][ T437] ? exc_page_fault+0xee/0x100 [ 20.630233][ T437] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 20.630235][ T437] RIP: 0033:0x7fdf0a4b837d [ 20.630238][ T437] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 20.630240][ T437] RSP: 002b:00007ffdf1002818 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 20.630243][ T437] RAX: ffffffffffffffda RBX: 0000555b86e67810 RCX: 00007fdf0a4b837d [ 20.630244][ T437] RDX: 0000000000000000 RSI: 0000555b7b8ecffd RDI: 0000000000000000 [ 20.630245][ T437] RBP: 00007ffdf10028b0 R08: 0000000000000000 R09: 0000000000000000 [ 20.630246][ T437] R10: 0000000000000000 R11: 0000000000000246 R12: 0000555b7b8ecffd [ 20.630247][ T437] R13: 0000555b86e67960 R14: 0000000000040000 R15: 0000000000000000 [ 20.630253][ T437] [ 26.487185][ C0] [ 26.487196][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.487198][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 26.487199][ C0] preempt_count: 104, expected: 0 [ 26.487200][ C0] RCU nest depth: 0, expected: 0 [ 26.487201][ C0] INFO: lockdep is turned off. [ 26.487202][ C0] irq event stamp: 904786 [ 26.487203][ C0] hardirqs last enabled at (904786): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.487212][ C0] hardirqs last disabled at (904785): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.487214][ C0] softirqs last enabled at (904752): [] handle_softirqs+0x67c/0x900 [ 26.487218][ C0] softirqs last disabled at (904783): [] __irq_exit_rcu+0x145/0x1c0 [ 26.487220][ C0] Preemption disabled at: [ 26.487220][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 26.487226][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.487229][ C0] Tainted: [W]=WARN [ 26.487230][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.487231][ C0] Call Trace: [ 26.487233][ C0] [ 26.487234][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.487239][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 26.487241][ C0] __might_resched.cold+0x1fe/0x2c1 [ 26.487244][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.487248][ C0] ? __asan_memcpy+0x3c/0x60 [ 26.487250][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487254][ C0] __kmalloc_noprof+0x443/0x760 [ 26.487257][ C0] ? __rwlock_init+0x150/0x150 [ 26.487260][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 26.487264][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 26.487266][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.487269][ C0] put_chars+0x1e1/0x2f0 [ 26.487272][ C0] ? __send_to_port+0x420/0x420 [ 26.487274][ C0] ? console_prepend_replay+0x20/0x20 [ 26.487277][ C0] hvc_console_print+0x292/0x780 [ 26.487281][ C0] ? hvc_write+0x3a0/0x3a0 [ 26.487282][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487284][ C0] ? lock_acquire+0x13c/0x160 [ 26.487286][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.487288][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 26.487291][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487292][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487294][ C0] ? lock_acquire+0x13c/0x160 [ 26.487296][ C0] ? console_flush_one_record+0x111/0x710 [ 26.487297][ C0] console_flush_one_record+0x46f/0x710 [ 26.487300][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 26.487302][ C0] console_unlock+0xee/0x1f0 [ 26.487304][ C0] ? lock_acquire+0x13c/0x160 [ 26.487306][ C0] ? console_flush_one_record+0x710/0x710 [ 26.487307][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487309][ C0] ? lock_release+0x184/0x1f0 [ 26.487310][ C0] ? lock_acquire+0x60/0x160 [ 26.487312][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.487314][ C0] ? vprintk_emit+0x320/0x3e0 [ 26.487316][ C0] vprintk_emit+0x37c/0x3e0 [ 26.487318][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 26.487320][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487322][ C0] ? lock_release+0x184/0x1f0 [ 26.487323][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 26.487328][ C0] _printk+0xc7/0x100 [ 26.487331][ C0] ? snapshot_read.cold+0x21/0x21 [ 26.487333][ C0] ? arch_stack_walk+0xd7/0x130 [ 26.487337][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.487339][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.487341][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.487343][ C0] mark_lock_irq+0x989/0x9c0 [ 26.487346][ C0] mark_lock+0x1d7/0xa00 [ 26.487348][ C0] mark_usage+0x42/0x170 [ 26.487349][ C0] __lock_acquire+0x388/0xc20 [ 26.487352][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.487353][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.487372][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487374][ C0] ? lock_acquire+0x13c/0x160 [ 26.487376][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.487383][ C0] _raw_spin_lock+0x33/0x40 [ 26.487384][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.487391][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.487397][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.487403][ C0] call_timer_fn+0x160/0x4d0 [ 26.487406][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.487408][ C0] ? debug_object_active_state+0x430/0x430 [ 26.487412][ C0] ? find_held_lock+0x2b/0x80 [ 26.487414][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.487415][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487418][ C0] __run_timers+0x68f/0xaa0 [ 26.487419][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.487426][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.487428][ C0] ? __lock_acquire+0x518/0xc20 [ 26.487430][ C0] ? __rwlock_init+0x150/0x150 [ 26.487433][ C0] run_timer_softirq+0xf0/0x160 [ 26.487435][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.487436][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.487439][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487440][ C0] handle_softirqs+0x1d3/0x900 [ 26.487442][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.487444][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.487446][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.487447][ C0] irq_exit_rcu+0xe/0x30 [ 26.487449][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.487452][ C0] [ 26.487452][ C0] [ 26.487453][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.487455][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.487457][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.487459][ C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 26.487461][ C0] RSP: 0018:ffffffffa8c07cf8 EFLAGS: 00000296 [ 26.487464][ C0] RAX: 00000000000dce4d RBX: ffffffffa8c1c600 RCX: ffffffffa5cf0307 [ 26.487466][ C0] RDX: ffffffffa8c1c600 RSI: ffffffffa8a38b01 RDI: ffffffffa848d8e0 [ 26.487466][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.487467][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5180fa2 [ 26.487468][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.487470][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.487473][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.487474][ C0] default_idle+0x9/0x10 [ 26.487476][ C0] default_idle_call+0x6e/0xb0 [ 26.487478][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.487479][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.487481][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.487483][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487485][ C0] do_idle+0xd8/0x190 [ 26.487487][ C0] cpu_startup_entry+0x53/0x70 [ 26.487488][ C0] rest_init+0x279/0x280 [ 26.487490][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.487493][ C0] ? rest_init+0x280/0x280 [ 26.487495][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.487498][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.487501][ C0] ? acpi_enable+0x1e4/0x330 [ 26.487504][ C0] start_kernel+0x3af/0x3b0 [ 26.487506][ C0] x86_64_start_reservations+0x24/0x30 [ 26.487507][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.487509][ C0] common_startup_64+0x13e/0x148 [ 26.487513][ C0] [ 26.508205][ C0] ======================================================== [ 26.508428][ C0] WARNING: possible irq lock inversion dependency detected [ 26.508689][ C0] 7.2.0-virtme #1 Tainted: G W [ 26.508917][ C0] -------------------------------------------------------- [ 26.509181][ C0] swapper/0/0 just changed the state of lock: [ 26.509365][ C0] ff1100000c5c2e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.513580][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.513797][ C0] (fs_reclaim){+.+.}-{0:0} [ 26.513799][ C0] [ 26.513799][ C0] [ 26.513799][ C0] and interrupts could create inverse lock ordering between them. [ 26.513799][ C0] [ 26.514432][ C0] [ 26.514432][ C0] other info that might help us debug this: [ 26.514700][ C0] Chain exists of: [ 26.514700][ C0] &br->lock --> console_owner --> fs_reclaim [ 26.514700][ C0] [ 26.515080][ C0] Possible interrupt unsafe locking scenario: [ 26.515080][ C0] [ 26.515361][ C0] CPU0 CPU1 [ 26.515505][ C0] ---- ---- [ 26.515700][ C0] lock(fs_reclaim); [ 26.515811][ C0] local_irq_disable(); [ 26.516041][ C0] lock(&br->lock); [ 26.516219][ C0] lock(console_owner); [ 26.516459][ C0] [ 26.516568][ C0] lock(&br->lock); [ 26.516729][ C0] [ 26.516729][ C0] *** DEADLOCK *** [ 26.516729][ C0] [ 26.516988][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 26.517169][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.517523][ C0] [ 26.517523][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 26.517818][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 26.518018][ C0] HARDIRQ-ON-W at: [ 26.518131][ C0] __lock_acquire+0x388/0xc20 [ 26.518371][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.518553][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.518786][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.519055][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.519278][ C0] workqueue_init+0x2a/0x680 [ 26.519512][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.519778][ C0] kernel_init+0x21/0x150 [ 26.519962][ C0] ret_from_fork+0x474/0x6b0 [ 26.520192][ C0] ret_from_fork_asm+0x11/0x20 [ 26.520430][ C0] SOFTIRQ-ON-W at: [ 26.520536][ C0] __lock_acquire+0x388/0xc20 [ 26.520764][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.520941][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.521173][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.521445][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.521658][ C0] workqueue_init+0x2a/0x680 [ 26.521888][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.522151][ C0] kernel_init+0x21/0x150 [ 26.522336][ C0] ret_from_fork+0x474/0x6b0 [ 26.522574][ C0] ret_from_fork_asm+0x11/0x20 [ 26.522806][ C0] INITIAL USE at: [ 26.522912][ C0] __lock_acquire+0x388/0xc20 [ 26.523140][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.523325][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.523559][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.523825][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.524042][ C0] workqueue_init+0x2a/0x680 [ 26.524275][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.524510][ C0] kernel_init+0x21/0x150 [ 26.524687][ C0] ret_from_fork+0x474/0x6b0 [ 26.524915][ C0] ret_from_fork_asm+0x11/0x20 [ 26.525091][ C0] } [ 26.525221][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.525446][ C0] ... acquired at: [ 26.525605][ C0] __lock_acquire+0x518/0xc20 [ 26.525754][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.525947][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.526090][ C0] __kmalloc_noprof+0xd3/0x760 [ 26.526232][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.526385][ C0] put_chars+0x1e1/0x2f0 [ 26.526579][ C0] hvc_console_print+0x292/0x780 [ 26.526725][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.526920][ C0] console_flush_one_record+0x46f/0x710 [ 26.527064][ C0] console_unlock+0xee/0x1f0 [ 26.527258][ C0] vprintk_emit+0x37c/0x3e0 [ 26.527410][ C0] _printk+0xc7/0x100 [ 26.527569][ C0] dm_interface_init+0x50/0x60 [ 26.527714][ C0] dm_init+0x51/0xd0 [ 26.527874][ C0] do_one_initcall+0x124/0x4f0 [ 26.528020][ C0] kernel_init_freeable+0x596/0x630 [ 26.528161][ C0] kernel_init+0x21/0x150 [ 26.528310][ C0] ret_from_fork+0x474/0x6b0 [ 26.528509][ C0] ret_from_fork_asm+0x11/0x20 [ 26.528653][ C0] [ 26.528725][ C0] -> (console_owner){....}-{0:0} { [ 26.528923][ C0] INITIAL USE at: [ 26.529031][ C0] } [ 26.529104][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.529378][ C0] ... acquired at: [ 26.529534][ C0] __lock_acquire+0x518/0xc20 [ 26.529681][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.529874][ C0] console_lock_spinning_enable+0x5c/0x60 [ 26.530055][ C0] console_emit_next_record+0x1d1/0x4c0 [ 26.530197][ C0] console_flush_one_record+0x46f/0x710 [ 26.530347][ C0] console_unlock+0xee/0x1f0 [ 26.530546][ C0] vprintk_emit+0x37c/0x3e0 [ 26.530693][ C0] _printk+0xc7/0x100 [ 26.530854][ C0] br_set_state+0x22f/0x430 [bridge] [ 26.531004][ C0] br_init_port+0xc4/0x200 [bridge] [ 26.531200][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 26.531395][ C0] br_add_if+0xde2/0x1320 [bridge] [ 26.531596][ C0] do_set_master+0x357/0x580 [ 26.531742][ C0] do_setlink.isra.0+0xa31/0x2750 [ 26.531938][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.532080][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.532224][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.532381][ C0] netlink_unicast+0x486/0x750 [ 26.532574][ C0] netlink_sendmsg+0x735/0xc60 [ 26.532718][ C0] ____sys_sendmsg+0x415/0x880 [ 26.532913][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.533057][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.533202][ C0] do_syscall_64+0xff/0x530 [ 26.533349][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.533585][ C0] [ 26.533656][ C0] -> (&br->lock){+.-.}-{3:3} { [ 26.533852][ C0] HARDIRQ-ON-W at: [ 26.533963][ C0] __lock_acquire+0x388/0xc20 [ 26.534196][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.534386][ C0] _raw_spin_lock_bh+0x38/0x50 [ 26.534621][ C0] br_set_mac_address+0xe6/0x3b0 [bridge] [ 26.534893][ C0] netif_set_mac_address+0x282/0x410 [ 26.535072][ C0] do_setlink.isra.0+0x706/0x2750 [ 26.535255][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.535443][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.535674][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.535905][ C0] netlink_unicast+0x486/0x750 [ 26.536083][ C0] netlink_sendmsg+0x735/0xc60 [ 26.536263][ C0] ____sys_sendmsg+0x415/0x880 [ 26.536454][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.536637][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.536868][ C0] do_syscall_64+0xff/0x530 [ 26.537047][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.537318][ C0] IN-SOFTIRQ-W at: [ 26.537483][ C0] __lock_acquire+0x388/0xc20 [ 26.537667][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.537900][ C0] _raw_spin_lock+0x33/0x40 [ 26.538080][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.538352][ C0] call_timer_fn+0x160/0x4d0 [ 26.538590][ C0] __run_timers+0x68f/0xaa0 [ 26.538770][ C0] run_timer_softirq+0xf0/0x160 [ 26.538999][ C0] handle_softirqs+0x1d3/0x900 [ 26.539231][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.539416][ C0] irq_exit_rcu+0xe/0x30 [ 26.539645][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.539912][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.540131][ C0] pv_native_safe_halt+0xf/0x10 [ 26.540372][ C0] default_idle+0x9/0x10 [ 26.540604][ C0] default_idle_call+0x6e/0xb0 [ 26.540786][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.541052][ C0] do_idle+0xd8/0x190 [ 26.541246][ C0] cpu_startup_entry+0x53/0x70 [ 26.541438][ C0] rest_init+0x279/0x280 [ 26.541672][ C0] start_kernel+0x3af/0x3b0 [ 26.541857][ C0] x86_64_start_reservations+0x24/0x30 [ 26.542087][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.542324][ C0] common_startup_64+0x13e/0x148 [ 26.542515][ C0] INITIAL USE at: [ 26.542676][ C0] __lock_acquire+0x388/0xc20 [ 26.542856][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.543084][ C0] _raw_spin_lock_bh+0x38/0x50 [ 26.543318][ C0] br_set_mac_address+0xe6/0x3b0 [bridge] [ 26.543550][ C0] netif_set_mac_address+0x282/0x410 [ 26.543781][ C0] do_setlink.isra.0+0x706/0x2750 [ 26.544010][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.544190][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.544429][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.544609][ C0] netlink_unicast+0x486/0x750 [ 26.544847][ C0] netlink_sendmsg+0x735/0xc60 [ 26.545076][ C0] ____sys_sendmsg+0x415/0x880 [ 26.545255][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.545497][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.545726][ C0] do_syscall_64+0xff/0x530 [ 26.545906][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.546172][ C0] } [ 26.546244][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.546522][ C0] ... acquired at: [ 26.546628][ C0] mark_lock+0x1d7/0xa00 [ 26.546823][ C0] mark_usage+0x42/0x170 [ 26.546966][ C0] __lock_acquire+0x388/0xc20 [ 26.547156][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.547302][ C0] _raw_spin_lock+0x33/0x40 [ 26.547502][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.547682][ C0] call_timer_fn+0x160/0x4d0 [ 26.547872][ C0] __run_timers+0x68f/0xaa0 [ 26.548014][ C0] run_timer_softirq+0xf0/0x160 [ 26.548208][ C0] handle_softirqs+0x1d3/0x900 [ 26.548354][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.548552][ C0] irq_exit_rcu+0xe/0x30 [ 26.548694][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.548926][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.549154][ C0] pv_native_safe_halt+0xf/0x10 [ 26.549300][ C0] default_idle+0x9/0x10 [ 26.549497][ C0] default_idle_call+0x6e/0xb0 [ 26.549639][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.549864][ C0] do_idle+0xd8/0x190 [ 26.549970][ C0] cpu_startup_entry+0x53/0x70 [ 26.550167][ C0] rest_init+0x279/0x280 [ 26.550314][ C0] start_kernel+0x3af/0x3b0 [ 26.550510][ C0] x86_64_start_reservations+0x24/0x30 [ 26.550652][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.550848][ C0] common_startup_64+0x13e/0x148 [ 26.550988][ C0] [ 26.551058][ C0] [ 26.551058][ C0] stack backtrace: [ 26.551291][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.551293][ C0] Tainted: [W]=WARN [ 26.551294][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.551295][ C0] Call Trace: [ 26.551297][ C0] [ 26.551297][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.551301][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.551303][ C0] mark_lock_irq+0x989/0x9c0 [ 26.551306][ C0] mark_lock+0x1d7/0xa00 [ 26.551308][ C0] mark_usage+0x42/0x170 [ 26.551310][ C0] __lock_acquire+0x388/0xc20 [ 26.551312][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.551313][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.551320][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.551323][ C0] ? lock_acquire+0x13c/0x160 [ 26.551325][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.551331][ C0] _raw_spin_lock+0x33/0x40 [ 26.551333][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.551338][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.551344][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.551351][ C0] call_timer_fn+0x160/0x4d0 [ 26.551353][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.551354][ C0] ? debug_object_active_state+0x430/0x430 [ 26.551362][ C0] ? find_held_lock+0x2b/0x80 [ 26.551364][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.551366][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.551368][ C0] __run_timers+0x68f/0xaa0 [ 26.551370][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.551377][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.551379][ C0] ? __lock_acquire+0x518/0xc20 [ 26.551381][ C0] ? __rwlock_init+0x150/0x150 [ 26.551383][ C0] run_timer_softirq+0xf0/0x160 [ 26.551385][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.551387][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.551389][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.551391][ C0] handle_softirqs+0x1d3/0x900 [ 26.551393][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.551394][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.551396][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.551397][ C0] irq_exit_rcu+0xe/0x30 [ 26.551399][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.551400][ C0] [ 26.551401][ C0] [ 26.551401][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.551403][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.551405][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.551406][ C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 26.551408][ C0] RSP: 0018:ffffffffa8c07cf8 EFLAGS: 00000296 [ 26.551409][ C0] RAX: 00000000000dce4d RBX: ffffffffa8c1c600 RCX: ffffffffa5cf0307 [ 26.551410][ C0] RDX: ffffffffa8c1c600 RSI: ffffffffa8a38b01 RDI: ffffffffa848d8e0 [ 26.551411][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.551412][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5180fa2 [ 26.551413][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.551414][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.551417][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.551418][ C0] default_idle+0x9/0x10 [ 26.551420][ C0] default_idle_call+0x6e/0xb0 [ 26.551421][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.551423][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.551424][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.551426][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.551428][ C0] do_idle+0xd8/0x190 [ 26.551429][ C0] cpu_startup_entry+0x53/0x70 [ 26.551431][ C0] rest_init+0x279/0x280 [ 26.551433][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.551435][ C0] ? rest_init+0x280/0x280 [ 26.551437][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.551440][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.551442][ C0] ? acpi_enable+0x1e4/0x330 [ 26.551445][ C0] start_kernel+0x3af/0x3b0 [ 26.551446][ C0] x86_64_start_reservations+0x24/0x30 [ 26.551448][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.551450][ C0] common_startup_64+0x13e/0x148 [ 26.551453][ C0] [ 63.857445][ T12] vx2: left allmulticast mode [ 63.857461][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 63.857463][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 63.857465][ T12] preempt_count: 1, expected: 0 [ 63.857466][ T12] RCU nest depth: 0, expected: 0 [ 63.857467][ T12] INFO: lockdep is turned off. [ 63.857468][ T12] irq event stamp: 30352 [ 63.857469][ T12] hardirqs last enabled at (30351): [] _raw_spin_unlock_irq+0x28/0x50 [ 63.857484][ T12] hardirqs last disabled at (30352): [] __schedule+0x1246/0x20e0 [ 63.857486][ T12] softirqs last enabled at (30180): [] __fib6_clean_all+0xff/0x290 [ 63.857490][ T12] softirqs last disabled at (30174): [] __fib6_clean_all+0xd9/0x290 [ 63.857492][ T12] Preemption disabled at: [ 63.857493][ T12] [] vprintk_emit+0x31b/0x3e0 [ 63.857501][ T12] CPU: 1 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 63.857504][ T12] Tainted: [W]=WARN [ 63.857505][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 63.857507][ T12] Workqueue: netns cleanup_net [ 63.857513][ T12] Call Trace: [ 63.857515][ T12] [ 63.857516][ T12] dump_stack_lvl+0x6f/0xa0 [ 63.857522][ T12] ? vprintk_emit+0x31b/0x3e0 [ 63.857524][ T12] __might_resched.cold+0x1fe/0x2c1 [ 63.857527][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 63.857531][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857535][ T12] __kmalloc_noprof+0x443/0x760 [ 63.857538][ T12] ? __rwlock_init+0x150/0x150 [ 63.857541][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 63.857544][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 63.857546][ T12] alloc_buf.isra.0+0x4b/0x260 [ 63.857548][ T12] put_chars+0x1e1/0x2f0 [ 63.857549][ T12] ? desc_read_finalized_seq+0x79/0x120 [ 63.857551][ T12] ? __send_to_port+0x420/0x420 [ 63.857553][ T12] ? console_prepend_replay+0x20/0x20 [ 63.857555][ T12] hvc_console_print+0x292/0x780 [ 63.857558][ T12] ? hvc_write+0x3a0/0x3a0 [ 63.857559][ T12] ? do_raw_spin_lock+0x131/0x280 [ 63.857561][ T12] ? __rwlock_init+0x150/0x150 [ 63.857563][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857564][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857566][ T12] ? lock_acquire+0x13c/0x160 [ 63.857568][ T12] console_emit_next_record+0x22f/0x4c0 [ 63.857570][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 63.857572][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857574][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857575][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857577][ T12] ? lock_acquire+0x13c/0x160 [ 63.857579][ T12] ? console_flush_one_record+0x111/0x710 [ 63.857580][ T12] console_flush_one_record+0x46f/0x710 [ 63.857583][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 63.857585][ T12] console_unlock+0xee/0x1f0 [ 63.857587][ T12] ? lock_acquire+0x13c/0x160 [ 63.857588][ T12] ? console_flush_one_record+0x710/0x710 [ 63.857590][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857592][ T12] ? lock_release+0x184/0x1f0 [ 63.857593][ T12] ? lock_acquire+0x60/0x160 [ 63.857594][ T12] ? __rcu_read_lock+0x30/0x70 [ 63.857596][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 63.857598][ T12] ? vprintk_emit+0x320/0x3e0 [ 63.857600][ T12] vprintk_emit+0x37c/0x3e0 [ 63.857602][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 63.857604][ T12] ? process_one_work+0xe3e/0x1560 [ 63.857607][ T12] ? kthread+0x367/0x460 [ 63.857608][ T12] ? ret_from_fork+0x474/0x6b0 [ 63.857611][ T12] ? ret_from_fork_asm+0x11/0x20 [ 63.857613][ T12] _printk+0xc7/0x100 [ 63.857616][ T12] ? snapshot_read.cold+0x21/0x21 [ 63.857619][ T12] ? netdev_name+0x43/0x60 [ 63.857621][ T12] ? __netdev_printk+0x17f/0x1d0 [ 63.857623][ T12] netdev_info+0xe2/0x116 [ 63.857625][ T12] ? netdev_notice+0x120/0x120 [ 63.857627][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857629][ T12] ? __up_read+0x355/0x9e0 [ 63.857630][ T12] ? trace_hardirqs_on+0x36/0x40 [ 63.857633][ T12] ? down_write_nested+0x200/0x200 [ 63.857634][ T12] ? mutex_is_locked+0x1c/0x50 [ 63.857636][ T12] netif_set_allmulti.cold+0x7c/0x166 [ 63.857639][ T12] dev_set_allmulti+0xa2/0x150 [ 63.857642][ T12] del_nbp+0xb7/0xc10 [bridge] [ 63.857655][ T12] br_dev_delete+0x9d/0x1e0 [bridge] [ 63.857662][ T12] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 63.857669][ T12] ops_undo_list+0x5be/0x8f0 [ 63.857671][ T12] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 63.857673][ T12] ? idr_destroy+0x121/0x250 [ 63.857677][ T12] cleanup_net+0x431/0x890 [ 63.857679][ T12] ? net_passive_dec+0x230/0x230 [ 63.857680][ T12] ? update_load_avg+0x141/0xf00 [ 63.857684][ T12] ? lock_acquire+0x13c/0x160 [ 63.857685][ T12] ? rcu_is_watching+0x16/0xd0 [ 63.857687][ T12] process_one_work+0xe3e/0x1560 [ 63.857690][ T12] ? __queue_delayed_work+0x470/0x470 [ 63.857693][ T12] ? __rwlock_init+0x150/0x150 [ 63.857695][ T12] worker_thread+0x4f1/0xd60 [ 63.857698][ T12] ? rescuer_thread+0x1340/0x1340 [ 63.857699][ T12] kthread+0x367/0x460 [ 63.857701][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 63.857702][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 63.857704][ T12] ret_from_fork+0x474/0x6b0 [ 63.857706][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 63.857707][ T12] ? __switch_to+0x5a3/0xe00 [ 63.857710][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 63.857712][ T12] ret_from_fork_asm+0x11/0x20 [ 63.857715][ T12] [ 63.873042][ T12] vx2: left promiscuous mode [ 63.873259][ T12] br2: port 2(vx2) entered disabled state [ 63.874857][ T12] w1: left allmulticast mode [ 63.875013][ T12] w1: left promiscuous mode [ 63.876229][ T12] br2: port 1(w1) entered disabled state [ 63.961052][ T12] vx2: left allmulticast mode [ 63.965350][ T12] vx2: left promiscuous mode [ 63.965554][ T12] br2: port 2(vx2) entered disabled state [ 63.966755][ T12] w1: left allmulticast mode [ 63.966895][ T12] w1: left promiscuous mode [ 63.967084][ T12] br2: port 1(w1) entered disabled state [ 64.322354][ T569] br1: port 3(veth2) entered disabled state [ 64.340099][ T570] veth2: left allmulticast mode [ 64.340870][ T570] veth2: left promiscuous mode [ 64.341073][ T570] br1: port 3(veth2) entered disabled state [ 64.376660][ T572] br1: port 2(veth1) entered disabled state [ 64.398560][ T573] veth1: left allmulticast mode [ 64.398713][ T573] veth1: left promiscuous mode [ 64.398911][ T573] br1: port 2(veth1) entered disabled state [ 64.417171][ T574] vx1: left allmulticast mode [ 64.417359][ T574] vx1: left promiscuous mode [ 64.417621][ T574] br1: port 1(vx1) entered disabled state