[ 11.280731][ T257] br1: port 1(veth1) entered blocking state [ 11.280914][ T257] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.280917][ T257] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 257, name: ip [ 11.280919][ T257] preempt_count: 1, expected: 0 [ 11.280920][ T257] RCU nest depth: 0, expected: 0 [ 11.280922][ T257] locks held by ip/257: 5, last CPU#2: [ 11.280924][ T257] #0: ffffffff93ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.280939][ T257] #1: ffffffff93769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.280947][ T257] #2: ffffffff93769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.280953][ T257] #3: ffffffff93689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.280958][ T257] #4: ffffffff93689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.280964][ T257] irq event stamp: 21074 [ 11.280965][ T257] hardirqs last enabled at (21073): [] __down_trylock_console_sem+0x86/0xa0 [ 11.280968][ T257] hardirqs last disabled at (21074): [] console_emit_next_record+0x3d4/0x4c0 [ 11.280971][ T257] softirqs last enabled at (20986): [] __alloc_skb+0x4c2/0x5f0 [ 11.280975][ T257] softirqs last disabled at (20984): [] __alloc_skb+0x4c2/0x5f0 [ 11.280978][ T257] Preemption disabled at: [ 11.280979][ T257] [] vprintk_emit+0x31b/0x3e0 [ 11.280987][ T257] CPU: 2 UID: 0 PID: 257 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.280991][ T257] Tainted: [W]=WARN [ 11.280992][ T257] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.280994][ T257] Call Trace: [ 11.280996][ T257] [ 11.280998][ T257] dump_stack_lvl+0x6f/0xa0 [ 11.281005][ T257] ? vprintk_emit+0x31b/0x3e0 [ 11.281008][ T257] __might_resched.cold+0x1fe/0x2c1 [ 11.281014][ T257] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.281019][ T257] ? __kmalloc_noprof+0xdb/0x760 [ 11.281026][ T257] __kmalloc_noprof+0x443/0x760 [ 11.281030][ T257] ? alloc_buf.isra.0+0x4b/0x260 [ 11.281037][ T257] ? do_raw_spin_unlock+0x59/0x250 [ 11.281040][ T257] alloc_buf.isra.0+0x4b/0x260 [ 11.281045][ T257] put_chars+0x1e1/0x2f0 [ 11.281049][ T257] ? __send_to_port+0x420/0x420 [ 11.281056][ T257] ? validate_chain+0x34a/0xc20 [ 11.281061][ T257] hvc_console_print+0x292/0x780 [ 11.281065][ T257] ? mark_usage+0x61/0x170 [ 11.281068][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281070][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281076][ T257] ? hvc_write+0x3a0/0x3a0 [ 11.281079][ T257] ? console_emit_next_record+0x1df/0x4c0 [ 11.281084][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281089][ T257] ? lock_acquire+0x13c/0x160 [ 11.281095][ T257] console_emit_next_record+0x22f/0x4c0 [ 11.281101][ T257] ? devkmsg_read+0x4b0/0x4b0 [ 11.281103][ T257] ? console_flush_one_record+0x106/0x710 [ 11.281108][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281111][ T257] ? lock_acquire+0x13c/0x160 [ 11.281117][ T257] console_flush_one_record+0x46f/0x710 [ 11.281124][ T257] ? console_emit_next_record+0x4c0/0x4c0 [ 11.281126][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281134][ T257] console_unlock+0xee/0x1f0 [ 11.281138][ T257] ? console_flush_one_record+0x710/0x710 [ 11.281141][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281144][ T257] ? lock_acquire+0x60/0x160 [ 11.281150][ T257] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.281152][ T257] ? vprintk_emit+0x320/0x3e0 [ 11.281157][ T257] vprintk_emit+0x37c/0x3e0 [ 11.281163][ T257] ? wake_up_klogd_work_func+0x90/0x90 [ 11.281167][ T257] ? __lock_release.isra.0+0x69/0x1a0 [ 11.281169][ T257] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.281174][ T257] ? mark_held_locks+0x40/0x70 [ 11.281180][ T257] _printk+0xc7/0x100 [ 11.281184][ T257] ? snapshot_read.cold+0x21/0x21 [ 11.281190][ T257] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.281206][ T257] ? do_setlink.isra.0+0xa31/0x2750 [ 11.281208][ T257] ? rtnl_newlink+0x9f1/0x11c0 [ 11.281210][ T257] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.281218][ T257] br_set_state+0x22f/0x430 [bridge] [ 11.281233][ T257] br_init_port+0xc4/0x200 [bridge] [ 11.281246][ T257] new_nbp+0x39c/0x580 [bridge] [ 11.281261][ T257] br_add_if+0x212/0x1320 [bridge] [ 11.281272][ T257] ? is_bpf_text_address+0x72/0x110 [ 11.281277][ T257] ? kernel_text_address+0x149/0x170 [ 11.281281][ T257] ? __kernel_text_address+0x12/0x30 [ 11.281287][ T257] do_set_master+0x357/0x580 [ 11.281294][ T257] do_setlink.isra.0+0xa31/0x2750 [ 11.281299][ T257] ? stack_trace_save+0x93/0xc0 [ 11.281303][ T257] ? rtnl_link_get_size+0x350/0x350 [ 11.281305][ T257] ? rcu_read_lock_any_held+0x66/0x90 [ 11.281309][ T257] ? stack_depot_save_flags+0x38e/0x790 [ 11.281313][ T257] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.281316][ T257] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.281319][ T257] ? validate_chain+0x38b/0xc20 [ 11.281323][ T257] ? kasan_save_stack+0x3d/0x50 [ 11.281327][ T257] ? kasan_save_stack+0x2f/0x50 [ 11.281329][ T257] ? kasan_save_track+0x14/0x30 [ 11.281333][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281335][ T257] ? netlink_seq_next+0xe/0x60 [ 11.281339][ T257] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.281346][ T257] ? lock_acquire.part.0+0xd4/0x280 [ 11.281349][ T257] ? rtnl_newlink+0x9a8/0x11c0 [ 11.281353][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281357][ T257] ? lock_acquire+0x13c/0x160 [ 11.281359][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281361][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281365][ T257] ? trace_contention_end+0xb3/0x180 [ 11.281369][ T257] ? __mutex_lock+0x1db/0x1ea0 [ 11.281372][ T257] ? __mutex_lock+0x9a3/0x1ea0 [ 11.281375][ T257] ? rtnl_newlink+0x9a8/0x11c0 [ 11.281381][ T257] ? ww_mutex_lock+0x160/0x160 [ 11.281383][ T257] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.281391][ T257] ? __rtnl_newlink+0x3fa/0xa50 [ 11.281400][ T257] rtnl_newlink+0x9f1/0x11c0 [ 11.281408][ T257] ? rtnl_bridge_getlink+0x850/0x850 [ 11.281411][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281418][ T257] ? lock_acquire.part.0+0xd4/0x280 [ 11.281421][ T257] ? find_held_lock+0x2b/0x80 [ 11.281424][ T257] ? rtnl_bridge_getlink+0x850/0x850 [ 11.281427][ T257] ? __lock_release.isra.0+0x69/0x1a0 [ 11.281432][ T257] ? rtnl_bridge_getlink+0x850/0x850 [ 11.281436][ T257] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.281440][ T257] ? rtnl_link_fill+0x920/0x920 [ 11.281442][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281449][ T257] ? lock_acquire.part.0+0xd4/0x280 [ 11.281452][ T257] ? find_held_lock+0x2b/0x80 [ 11.281457][ T257] netlink_rcv_skb+0x14e/0x3a0 [ 11.281460][ T257] ? rtnl_link_fill+0x920/0x920 [ 11.281465][ T257] ? netlink_ack+0xcf0/0xcf0 [ 11.281475][ T257] ? netlink_deliver_tap+0xc5/0x330 [ 11.281477][ T257] ? netlink_deliver_tap+0x13c/0x330 [ 11.281484][ T257] netlink_unicast+0x486/0x750 [ 11.281490][ T257] ? netlink_attachskb+0x810/0x810 [ 11.281494][ T257] ? __lock_acquire+0x518/0xc20 [ 11.281500][ T257] netlink_sendmsg+0x735/0xc60 [ 11.281513][ T257] ? netlink_unicast+0x750/0x750 [ 11.281519][ T257] ? __might_fault+0x97/0x140 [ 11.281527][ T257] ____sys_sendmsg+0x415/0x880 [ 11.281531][ T257] ? copy_msghdr_from_user+0x279/0x420 [ 11.281534][ T257] ? get_timestamp.constprop.0+0x390/0x390 [ 11.281536][ T257] ? move_addr_to_kernel+0x40/0x40 [ 11.281549][ T257] ___sys_sendmsg+0x14e/0x1d0 [ 11.281553][ T257] ? copy_msghdr_from_user+0x420/0x420 [ 11.281579][ T257] __sys_sendmsg+0x12c/0x1d0 [ 11.281584][ T257] ? __sys_sendmsg_sock+0x20/0x20 [ 11.281594][ T257] ? rcu_is_watching+0x16/0xd0 [ 11.281599][ T257] do_syscall_64+0xff/0x530 [ 11.281602][ T257] ? exc_page_fault+0xee/0x100 [ 11.281606][ T257] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.281610][ T257] RIP: 0033:0x7feb386a854e [ 11.281614][ T257] 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.281616][ T257] RSP: 002b:00007ffc5b8ad700 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.281620][ T257] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007feb386a854e [ 11.281622][ T257] RDX: 0000000000000000 RSI: 00007ffc5b8ad7b0 RDI: 0000000000000005 [ 11.281623][ T257] RBP: 00007ffc5b8ad710 R08: 0000000000000000 R09: 0000000000000000 [ 11.281625][ T257] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b55b [ 11.281626][ T257] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc5b8ade70 [ 11.281637][ T257] [ 11.327540][ T257] br1: port 1(veth1) entered disabled state [ 11.327954][ T257] veth1: entered allmulticast mode [ 11.330008][ T257] veth1: entered promiscuous mode [ 11.336926][ T257] ip (257) used greatest stack depth: 23336 bytes left [ 11.370867][ T58] br1: port 1(veth1) entered blocking state [ 11.371365][ T58] br1: port 1(veth1) entered forwarding state [ 11.402286][ T259] br1: port 2(veth2) entered blocking state [ 11.402601][ T259] br1: port 2(veth2) entered disabled state [ 11.402965][ T259] veth2: entered allmulticast mode [ 11.404527][ T259] veth2: entered promiscuous mode [ 11.453098][ T39] br1: port 2(veth2) entered blocking state [ 11.453449][ T39] br1: port 2(veth2) entered forwarding state [ 11.546830][ T263] 8021q: 802.1Q VLAN Support v1.8 [ 11.789835][ T274] GACT probability NOT on [ 17.587263][ T318] Mirror/redirect action on [ 17.587285][ T318] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.587288][ T318] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 318, name: modprobe [ 17.587291][ T318] preempt_count: 1, expected: 0 [ 17.587292][ T318] RCU nest depth: 0, expected: 0 [ 17.587293][ T318] locks held by modprobe/318: 4, last CPU#0: [ 17.587297][ T318] #0: ffffffff93769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.587311][ T318] #1: ffffffff93769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.587318][ T318] #2: ffffffff93689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.587324][ T318] #3: ffffffff93689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.587330][ T318] irq event stamp: 25400 [ 17.587332][ T318] hardirqs last enabled at (25399): [] __down_trylock_console_sem+0x86/0xa0 [ 17.587335][ T318] hardirqs last disabled at (25400): [] console_emit_next_record+0x3d4/0x4c0 [ 17.587338][ T318] softirqs last enabled at (23972): [] handle_softirqs+0x67c/0x900 [ 17.587342][ T318] softirqs last disabled at (23965): [] __irq_exit_rcu+0x145/0x1c0 [ 17.587346][ T318] Preemption disabled at: [ 17.587347][ T318] [] vprintk_emit+0x31b/0x3e0 [ 17.587355][ T318] CPU: 0 UID: 0 PID: 318 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.587359][ T318] Tainted: [W]=WARN [ 17.587361][ T318] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.587363][ T318] Call Trace: [ 17.587366][ T318] [ 17.587368][ T318] dump_stack_lvl+0x6f/0xa0 [ 17.587375][ T318] ? vprintk_emit+0x31b/0x3e0 [ 17.587378][ T318] __might_resched.cold+0x1fe/0x2c1 [ 17.587384][ T318] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.587390][ T318] ? __kmalloc_noprof+0xdb/0x760 [ 17.587397][ T318] __kmalloc_noprof+0x443/0x760 [ 17.587401][ T318] ? alloc_buf.isra.0+0x4b/0x260 [ 17.587410][ T318] ? do_raw_spin_unlock+0x59/0x250 [ 17.587413][ T318] alloc_buf.isra.0+0x4b/0x260 [ 17.587418][ T318] put_chars+0x1e1/0x2f0 [ 17.587422][ T318] ? __send_to_port+0x420/0x420 [ 17.587424][ T318] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.587431][ T318] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.587434][ T318] ? validate_chain+0x38b/0xc20 [ 17.587442][ T318] hvc_console_print+0x292/0x780 [ 17.587445][ T318] ? __lock_acquire+0x518/0xc20 [ 17.587454][ T318] ? hvc_write+0x3a0/0x3a0 [ 17.587459][ T318] ? rcu_is_watching+0x16/0xd0 [ 17.587467][ T318] console_emit_next_record+0x22f/0x4c0 [ 17.587474][ T318] ? devkmsg_read+0x4b0/0x4b0 [ 17.587476][ T318] ? console_flush_one_record+0x106/0x710 [ 17.587481][ T318] ? rcu_is_watching+0x16/0xd0 [ 17.587484][ T318] ? lock_acquire+0x13c/0x160 [ 17.587491][ T318] console_flush_one_record+0x46f/0x710 [ 17.587498][ T318] ? console_emit_next_record+0x4c0/0x4c0 [ 17.587500][ T318] ? __lock_acquire+0x518/0xc20 [ 17.587510][ T318] console_unlock+0xee/0x1f0 [ 17.587514][ T318] ? console_flush_one_record+0x710/0x710 [ 17.587516][ T318] ? rcu_is_watching+0x16/0xd0 [ 17.587520][ T318] ? lock_acquire+0x60/0x160 [ 17.587526][ T318] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.587534][ T318] ? vprintk_emit+0x320/0x3e0 [ 17.587540][ T318] vprintk_emit+0x37c/0x3e0 [ 17.587546][ T318] ? wake_up_klogd_work_func+0x90/0x90 [ 17.587551][ T318] ? netdev_run_todo+0x2a8/0xfd0 [ 17.587556][ T318] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 17.587560][ T318] ? rcu_is_watching+0x16/0xd0 [ 17.587567][ T318] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.587571][ T318] _printk+0xc7/0x100 [ 17.587576][ T318] ? snapshot_read.cold+0x21/0x21 [ 17.587579][ T318] ? __lock_release.isra.0+0x69/0x1a0 [ 17.587583][ T318] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.587586][ T318] ? __up_write+0x23d/0x5f0 [ 17.587588][ T318] ? __up_write+0x2e5/0x5f0 [ 17.587595][ T318] ? __up_write+0x2c7/0x5f0 [ 17.587599][ T318] mirred_init_module+0x28/0x1000 [act_mirred] [ 17.587603][ T318] do_one_initcall+0x124/0x4f0 [ 17.587608][ T318] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 17.587616][ T318] ? do_init_module+0xae/0x8a0 [ 17.587620][ T318] ? kasan_unpoison+0x44/0x70 [ 17.587628][ T318] do_init_module+0x2ad/0x8a0 [ 17.587634][ T318] ? lockdep_hardirqs_on+0x91/0x130 [ 17.587639][ T318] ? free_module+0x470/0x470 [ 17.587645][ T318] ? kfree+0x123/0x590 [ 17.587652][ T318] load_module+0x1424/0x1bb0 [ 17.587664][ T318] ? layout_and_allocate.isra.0+0x680/0x680 [ 17.587670][ T318] ? __ia32_sys_fsconfig+0x180/0x180 [ 17.587674][ T318] ? ovl_real_file+0x172/0x1e0 [ 17.587680][ T318] ? backing_file_read_iter+0x541/0x790 [ 17.587684][ T318] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.587691][ T318] init_module_from_file+0x11f/0x1a0 [ 17.587696][ T318] ? __do_sys_init_module+0x220/0x220 [ 17.587710][ T318] ? __rwlock_init+0x150/0x150 [ 17.587716][ T318] ? do_raw_spin_unlock+0x59/0x250 [ 17.587721][ T318] idempotent_init_module+0x37b/0x7c0 [ 17.587727][ T318] ? init_module_from_file+0x1a0/0x1a0 [ 17.587738][ T318] ? rcu_is_watching+0x16/0xd0 [ 17.587741][ T318] ? cap_capable+0x1d7/0x3d0 [ 17.587750][ T318] __x64_sys_finit_module+0xdf/0x190 [ 17.587754][ T318] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 17.587759][ T318] do_syscall_64+0xff/0x530 [ 17.587761][ T318] ? exc_page_fault+0xee/0x100 [ 17.587766][ T318] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.587769][ T318] RIP: 0033:0x7f2f8e3c637d [ 17.587774][ T318] 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 [ 17.587776][ T318] RSP: 002b:00007ffed8564d08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 17.587780][ T318] RAX: ffffffffffffffda RBX: 000055571fa0e810 RCX: 00007f2f8e3c637d [ 17.587782][ T318] RDX: 0000000000000000 RSI: 00005556fe282ffd RDI: 0000000000000000 [ 17.587784][ T318] RBP: 00007ffed8564da0 R08: 0000000000000000 R09: 0000000000000000 [ 17.587785][ T318] R10: 0000000000000000 R11: 0000000000000246 R12: 00005556fe282ffd [ 17.587787][ T318] R13: 000055571fa0e960 R14: 0000000000040000 R15: 0000000000000000 [ 17.587799][ T318] [ 26.522939][ C2] [ 26.522953][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.522955][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 26.522957][ C2] preempt_count: 104, expected: 0 [ 26.522958][ C2] RCU nest depth: 0, expected: 0 [ 26.522959][ C2] INFO: lockdep is turned off. [ 26.522960][ C2] irq event stamp: 715024 [ 26.522960][ C2] hardirqs last enabled at (715024): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.522970][ C2] hardirqs last disabled at (715023): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.522971][ C2] softirqs last enabled at (714996): [] handle_softirqs+0x67c/0x900 [ 26.522975][ C2] softirqs last disabled at (715021): [] __irq_exit_rcu+0x145/0x1c0 [ 26.522977][ C2] Preemption disabled at: [ 26.522978][ C2] [<0000000000000000>] 0x0 [ 26.522985][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.522988][ C2] Tainted: [W]=WARN [ 26.522990][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.522991][ C2] Call Trace: [ 26.522993][ C2] [ 26.522994][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.523000][ C2] __might_resched.cold+0x1fe/0x2c1 [ 26.523003][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.523007][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.523011][ C2] ? __asan_memcpy+0x3c/0x60 [ 26.523014][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523016][ C2] __kmalloc_noprof+0x443/0x760 [ 26.523019][ C2] ? __rwlock_init+0x150/0x150 [ 26.523022][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 26.523026][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 26.523027][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.523029][ C2] put_chars+0x1e1/0x2f0 [ 26.523031][ C2] ? __send_to_port+0x420/0x420 [ 26.523033][ C2] ? console_prepend_replay+0x20/0x20 [ 26.523036][ C2] hvc_console_print+0x292/0x780 [ 26.523040][ C2] ? hvc_write+0x3a0/0x3a0 [ 26.523041][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523043][ C2] ? lock_acquire+0x13c/0x160 [ 26.523045][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.523048][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 26.523050][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523051][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523053][ C2] ? lock_acquire+0x13c/0x160 [ 26.523055][ C2] ? console_flush_one_record+0x111/0x710 [ 26.523056][ C2] console_flush_one_record+0x46f/0x710 [ 26.523059][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 26.523061][ C2] console_unlock+0xee/0x1f0 [ 26.523063][ C2] ? lock_acquire+0x13c/0x160 [ 26.523065][ C2] ? console_flush_one_record+0x710/0x710 [ 26.523066][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523068][ C2] ? lock_release+0x184/0x1f0 [ 26.523069][ C2] ? lock_acquire+0x60/0x160 [ 26.523071][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.523073][ C2] ? vprintk_emit+0x320/0x3e0 [ 26.523075][ C2] vprintk_emit+0x37c/0x3e0 [ 26.523078][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 26.523079][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523091][ C2] ? lock_release+0x184/0x1f0 [ 26.523093][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523099][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523105][ C2] ? is_module_text_address+0x154/0x250 [ 26.523109][ C2] _printk+0xc7/0x100 [ 26.523112][ C2] ? snapshot_read.cold+0x21/0x21 [ 26.523114][ C2] ? arch_stack_walk+0xd7/0x130 [ 26.523118][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.523120][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.523121][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.523124][ C2] mark_lock_irq+0x989/0x9c0 [ 26.523127][ C2] mark_lock+0x1d7/0xa00 [ 26.523129][ C2] mark_usage+0x42/0x170 [ 26.523131][ C2] __lock_acquire+0x388/0xc20 [ 26.523133][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.523134][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523141][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523143][ C2] ? lock_acquire+0x13c/0x160 [ 26.523144][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.523150][ C2] _raw_spin_lock+0x33/0x40 [ 26.523152][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523158][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523164][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.523170][ C2] call_timer_fn+0x160/0x4d0 [ 26.523173][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.523175][ C2] ? debug_object_active_state+0x430/0x430 [ 26.523179][ C2] ? find_held_lock+0x2b/0x80 [ 26.523180][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.523182][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523184][ C2] __run_timers+0x68f/0xaa0 [ 26.523186][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.523193][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.523195][ C2] ? __lock_acquire+0x518/0xc20 [ 26.523198][ C2] ? __rwlock_init+0x150/0x150 [ 26.523200][ C2] run_timer_softirq+0xf0/0x160 [ 26.523202][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.523204][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.523206][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523208][ C2] handle_softirqs+0x1d3/0x900 [ 26.523210][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.523211][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.523213][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.523215][ C2] irq_exit_rcu+0xe/0x30 [ 26.523216][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.523219][ C2] [ 26.523220][ C2] [ 26.523221][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.523222][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.523225][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.523227][ C2] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 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.523229][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.523232][ C2] RAX: 00000000000ae90b RBX: ff11000001bec6c0 RCX: ffffffff904f0307 [ 26.523234][ C2] RDX: ff11000001bec6c0 RSI: ffffffff93238af6 RDI: ffffffff92c8d8e0 [ 26.523235][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.523236][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.523237][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.523238][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.523241][ C2] default_idle+0x9/0x10 [ 26.523243][ C2] default_idle_call+0x6e/0xb0 [ 26.523245][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.523246][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.523248][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.523250][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.523252][ C2] do_idle+0xd8/0x190 [ 26.523254][ C2] cpu_startup_entry+0x53/0x70 [ 26.523255][ C2] start_secondary+0x204/0x2b0 [ 26.523257][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.523259][ C2] common_startup_64+0x13e/0x148 [ 26.523263][ C2] [ 26.542826][ C2] ======================================================== [ 26.543028][ C2] WARNING: possible irq lock inversion dependency detected [ 26.543276][ C2] 7.2.0-virtme #1 Tainted: G W [ 26.543492][ C2] -------------------------------------------------------- [ 26.543697][ C2] swapper/2/0 just changed the state of lock: [ 26.543913][ C2] ff1100000c88ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.544240][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.544490][ C2] (fs_reclaim){+.+.}-{0:0} [ 26.544492][ C2] [ 26.544492][ C2] [ 26.544492][ C2] and interrupts could create inverse lock ordering between them. [ 26.544492][ C2] [ 26.545085][ C2] [ 26.545085][ C2] other info that might help us debug this: [ 26.545286][ C2] Chain exists of: [ 26.545286][ C2] &br->lock --> console_owner --> fs_reclaim [ 26.545286][ C2] [ 26.545692][ C2] Possible interrupt unsafe locking scenario: [ 26.545692][ C2] [ 26.545899][ C2] CPU0 CPU1 [ 26.546081][ C2] ---- ---- [ 26.546216][ C2] lock(fs_reclaim); [ 26.546372][ C2] local_irq_disable(); [ 26.546540][ C2] lock(&br->lock); [ 26.546761][ C2] lock(console_owner); [ 26.546980][ C2] [ 26.547083][ C2] lock(&br->lock); [ 26.547188][ C2] [ 26.547188][ C2] *** DEADLOCK *** [ 26.547188][ C2] [ 26.547439][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 26.547664][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.547943][ C2] [ 26.547943][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 26.548225][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 26.548368][ C2] HARDIRQ-ON-W at: [ 26.548477][ C2] __lock_acquire+0x388/0xc20 [ 26.548709][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.548929][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.549103][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.549354][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.549616][ C2] workqueue_init+0x2a/0x680 [ 26.549787][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.550042][ C2] kernel_init+0x21/0x150 [ 26.550264][ C2] ret_from_fork+0x474/0x6b0 [ 26.550437][ C2] ret_from_fork_asm+0x11/0x20 [ 26.550659][ C2] SOFTIRQ-ON-W at: [ 26.550764][ C2] __lock_acquire+0x388/0xc20 [ 26.550984][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.551203][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.551373][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.551628][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.551882][ C2] workqueue_init+0x2a/0x680 [ 26.552051][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.552303][ C2] kernel_init+0x21/0x150 [ 26.552521][ C2] ret_from_fork+0x474/0x6b0 [ 26.552697][ C2] ret_from_fork_asm+0x11/0x20 [ 26.552919][ C2] INITIAL USE at: [ 26.553021][ C2] __lock_acquire+0x388/0xc20 [ 26.553239][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.553458][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.553632][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.553882][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.554134][ C2] workqueue_init+0x2a/0x680 [ 26.554303][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.554522][ C2] kernel_init+0x21/0x150 [ 26.554750][ C2] ret_from_fork+0x474/0x6b0 [ 26.554920][ C2] ret_from_fork_asm+0x11/0x20 [ 26.555140][ C2] } [ 26.555211][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.555462][ C2] ... acquired at: [ 26.555571][ C2] __lock_acquire+0x518/0xc20 [ 26.555757][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.555891][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.556074][ C2] __kmalloc_noprof+0xd3/0x760 [ 26.556207][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.556391][ C2] put_chars+0x1e1/0x2f0 [ 26.556527][ C2] hvc_console_print+0x292/0x780 [ 26.556713][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.556850][ C2] console_flush_one_record+0x46f/0x710 [ 26.556984][ C2] console_unlock+0xee/0x1f0 [ 26.557118][ C2] vprintk_emit+0x37c/0x3e0 [ 26.557305][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 26.557441][ C2] dev_printk_emit+0xb9/0xee [ 26.557629][ C2] _dev_info+0xe2/0x116 [ 26.557730][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 26.557949][ C2] cmos_do_probe+0x73b/0x98a [ 26.558085][ C2] platform_probe+0xfe/0x1f0 [ 26.558269][ C2] call_driver_probe+0x61/0x1c0 [ 26.558406][ C2] really_probe+0x199/0x760 [ 26.558595][ C2] __driver_probe_device+0x24f/0x440 [ 26.558729][ C2] driver_probe_device+0x4a/0xf0 [ 26.558912][ C2] __driver_attach+0x1b8/0x540 [ 26.559048][ C2] bus_for_each_dev+0x130/0x1e0 [ 26.559232][ C2] bus_add_driver+0x2c8/0x530 [ 26.559368][ C2] driver_register+0x1a3/0x390 [ 26.559553][ C2] __platform_driver_probe+0x13f/0x270 [ 26.559695][ C2] cmos_init+0x31/0x40 [ 26.559844][ C2] do_one_initcall+0x124/0x4f0 [ 26.559979][ C2] kernel_init_freeable+0x596/0x630 [ 26.560161][ C2] kernel_init+0x21/0x150 [ 26.560296][ C2] ret_from_fork+0x474/0x6b0 [ 26.560482][ C2] ret_from_fork_asm+0x11/0x20 [ 26.560621][ C2] [ 26.560691][ C2] -> (console_owner){....}-{0:0} { [ 26.560877][ C2] INITIAL USE at: [ 26.560979][ C2] } [ 26.561095][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.561295][ C2] ... acquired at: [ 26.561446][ C2] __lock_acquire+0x518/0xc20 [ 26.561583][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.561765][ C2] console_lock_spinning_enable+0x5c/0x60 [ 26.561935][ C2] console_emit_next_record+0x1d1/0x4c0 [ 26.562117][ C2] console_flush_one_record+0x46f/0x710 [ 26.562252][ C2] console_unlock+0xee/0x1f0 [ 26.562436][ C2] vprintk_emit+0x37c/0x3e0 [ 26.562578][ C2] _printk+0xc7/0x100 [ 26.562727][ C2] br_set_state+0x22f/0x430 [bridge] [ 26.562871][ C2] br_init_port+0xc4/0x200 [bridge] [ 26.563062][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 26.563234][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 26.563458][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 26.563602][ C2] notifier_call_chain+0xae/0x300 [ 26.563786][ C2] netif_state_change+0x139/0x340 [ 26.563922][ C2] __linkwatch_run_queue+0x34c/0x750 [ 26.564106][ C2] linkwatch_event+0x7f/0xb0 [ 26.564241][ C2] process_one_work+0xe3e/0x1560 [ 26.564427][ C2] worker_thread+0x4f1/0xd60 [ 26.564569][ C2] kthread+0x367/0x460 [ 26.564718][ C2] ret_from_fork+0x474/0x6b0 [ 26.564855][ C2] ret_from_fork_asm+0x11/0x20 [ 26.564992][ C2] [ 26.565059][ C2] -> (&br->lock){+.-.}-{3:3} { [ 26.565247][ C2] HARDIRQ-ON-W at: [ 26.565350][ C2] __lock_acquire+0x388/0xc20 [ 26.565574][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.565743][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.565963][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 26.566223][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.566435][ C2] br_changelink+0x575/0x16e0 [bridge] [ 26.566666][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.566899][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.567069][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.567285][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.567451][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.567679][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.567896][ C2] netlink_unicast+0x486/0x750 [ 26.568064][ C2] netlink_sendmsg+0x735/0xc60 [ 26.568281][ C2] ____sys_sendmsg+0x415/0x880 [ 26.568499][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.568672][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.568893][ C2] do_syscall_64+0xff/0x530 [ 26.569059][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.569309][ C2] IN-SOFTIRQ-W at: [ 26.569457][ C2] __lock_acquire+0x388/0xc20 [ 26.569630][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.569844][ C2] _raw_spin_lock+0x33/0x40 [ 26.570014][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.570268][ C2] call_timer_fn+0x160/0x4d0 [ 26.570486][ C2] __run_timers+0x68f/0xaa0 [ 26.570657][ C2] run_timer_softirq+0xf0/0x160 [ 26.570875][ C2] handle_softirqs+0x1d3/0x900 [ 26.571095][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.571265][ C2] irq_exit_rcu+0xe/0x30 [ 26.571483][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.571740][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.571943][ C2] pv_native_safe_halt+0xf/0x10 [ 26.572166][ C2] default_idle+0x9/0x10 [ 26.572384][ C2] default_idle_call+0x6e/0xb0 [ 26.572553][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.572811][ C2] do_idle+0xd8/0x190 [ 26.572994][ C2] cpu_startup_entry+0x53/0x70 [ 26.573166][ C2] start_secondary+0x204/0x2b0 [ 26.573386][ C2] common_startup_64+0x13e/0x148 [ 26.573563][ C2] INITIAL USE at: [ 26.573713][ C2] __lock_acquire+0x388/0xc20 [ 26.573883][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.574054][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.574272][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 26.574484][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.574745][ C2] br_changelink+0x575/0x16e0 [bridge] [ 26.574967][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.575142][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.575362][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.575581][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.575753][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.575973][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.576189][ C2] netlink_unicast+0x486/0x750 [ 26.576358][ C2] netlink_sendmsg+0x735/0xc60 [ 26.576579][ C2] ____sys_sendmsg+0x415/0x880 [ 26.576757][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.576985][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.577209][ C2] do_syscall_64+0xff/0x530 [ 26.577381][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.577638][ C2] } [ 26.577705][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.577958][ C2] ... acquired at: [ 26.578062][ C2] mark_lock+0x1d7/0xa00 [ 26.578246][ C2] mark_usage+0x42/0x170 [ 26.578381][ C2] __lock_acquire+0x388/0xc20 [ 26.578569][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.578704][ C2] _raw_spin_lock+0x33/0x40 [ 26.578883][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.579101][ C2] call_timer_fn+0x160/0x4d0 [ 26.579237][ C2] __run_timers+0x68f/0xaa0 [ 26.579419][ C2] run_timer_softirq+0xf0/0x160 [ 26.579554][ C2] handle_softirqs+0x1d3/0x900 [ 26.579741][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.579874][ C2] irq_exit_rcu+0xe/0x30 [ 26.580053][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.580224][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.580440][ C2] pv_native_safe_halt+0xf/0x10 [ 26.580582][ C2] default_idle+0x9/0x10 [ 26.580762][ C2] default_idle_call+0x6e/0xb0 [ 26.580894][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.581109][ C2] do_idle+0xd8/0x190 [ 26.581211][ C2] cpu_startup_entry+0x53/0x70 [ 26.581393][ C2] start_secondary+0x204/0x2b0 [ 26.581528][ C2] common_startup_64+0x13e/0x148 [ 26.581716][ C2] [ 26.581784][ C2] [ 26.581784][ C2] stack backtrace: [ 26.581955][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.581958][ C2] Tainted: [W]=WARN [ 26.581959][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.581960][ C2] Call Trace: [ 26.581961][ C2] [ 26.581962][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.581965][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.581968][ C2] mark_lock_irq+0x989/0x9c0 [ 26.581971][ C2] mark_lock+0x1d7/0xa00 [ 26.581973][ C2] mark_usage+0x42/0x170 [ 26.581974][ C2] __lock_acquire+0x388/0xc20 [ 26.581977][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.581978][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.581985][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.581988][ C2] ? lock_acquire+0x13c/0x160 [ 26.581991][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.581997][ C2] _raw_spin_lock+0x33/0x40 [ 26.581998][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.582004][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.582011][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.582017][ C2] call_timer_fn+0x160/0x4d0 [ 26.582019][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.582021][ C2] ? debug_object_active_state+0x430/0x430 [ 26.582024][ C2] ? find_held_lock+0x2b/0x80 [ 26.582026][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.582028][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.582030][ C2] __run_timers+0x68f/0xaa0 [ 26.582032][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.582039][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.582041][ C2] ? __lock_acquire+0x518/0xc20 [ 26.582044][ C2] ? __rwlock_init+0x150/0x150 [ 26.582046][ C2] run_timer_softirq+0xf0/0x160 [ 26.582048][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.582050][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.582052][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.582054][ C2] handle_softirqs+0x1d3/0x900 [ 26.582056][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.582058][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.582060][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.582061][ C2] irq_exit_rcu+0xe/0x30 [ 26.582063][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.582065][ C2] [ 26.582065][ C2] [ 26.582066][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.582067][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.582069][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.582071][ C2] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 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.582072][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.582074][ C2] RAX: 00000000000ae90b RBX: ff11000001bec6c0 RCX: ffffffff904f0307 [ 26.582075][ C2] RDX: ff11000001bec6c0 RSI: ffffffff93238af6 RDI: ffffffff92c8d8e0 [ 26.582076][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.582077][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.582077][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.582079][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.582081][ C2] default_idle+0x9/0x10 [ 26.582083][ C2] default_idle_call+0x6e/0xb0 [ 26.582084][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.582086][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.582088][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.582089][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.582091][ C2] do_idle+0xd8/0x190 [ 26.582093][ C2] cpu_startup_entry+0x53/0x70 [ 26.582094][ C2] start_secondary+0x204/0x2b0 [ 26.582096][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.582098][ C2] common_startup_64+0x13e/0x148 [ 26.582100][ C2] [ 44.413227][ T505] br1: port 1(veth1) entered disabled state [ 44.413243][ T505] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 44.413245][ T505] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 505, name: ip [ 44.413247][ T505] preempt_count: 202, expected: 0 [ 44.413247][ T505] RCU nest depth: 0, expected: 0 [ 44.413248][ T505] INFO: lockdep is turned off. [ 44.413250][ T505] irq event stamp: 0 [ 44.413250][ T505] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 44.413255][ T505] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 44.413261][ T505] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 44.413263][ T505] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 44.413265][ T505] Preemption disabled at: [ 44.413266][ T505] [<0000000000000000>] 0x0 [ 44.413270][ T505] CPU: 2 UID: 0 PID: 505 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 44.413274][ T505] Tainted: [W]=WARN [ 44.413275][ T505] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 44.413277][ T505] Call Trace: [ 44.413278][ T505] [ 44.413279][ T505] dump_stack_lvl+0x6f/0xa0 [ 44.413285][ T505] __might_resched.cold+0x1fe/0x2c1 [ 44.413289][ T505] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 44.413292][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413296][ T505] __kmalloc_noprof+0x443/0x760 [ 44.413300][ T505] ? __rwlock_init+0x150/0x150 [ 44.413302][ T505] ? alloc_buf.isra.0+0x4b/0x260 [ 44.413306][ T505] ? do_raw_spin_unlock+0x59/0x250 [ 44.413308][ T505] alloc_buf.isra.0+0x4b/0x260 [ 44.413310][ T505] put_chars+0x1e1/0x2f0 [ 44.413312][ T505] ? __send_to_port+0x420/0x420 [ 44.413313][ T505] ? printk_get_next_message+0x240/0x650 [ 44.413317][ T505] hvc_console_print+0x292/0x780 [ 44.413320][ T505] ? hvc_write+0x3a0/0x3a0 [ 44.413322][ T505] ? __rwlock_init+0x150/0x150 [ 44.413324][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413326][ T505] ? lock_acquire+0x13c/0x160 [ 44.413328][ T505] console_emit_next_record+0x22f/0x4c0 [ 44.413330][ T505] ? devkmsg_read+0x4b0/0x4b0 [ 44.413332][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413334][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413335][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413337][ T505] ? lock_acquire+0x13c/0x160 [ 44.413339][ T505] ? console_flush_one_record+0x111/0x710 [ 44.413340][ T505] console_flush_one_record+0x46f/0x710 [ 44.413343][ T505] ? console_emit_next_record+0x4c0/0x4c0 [ 44.413345][ T505] console_unlock+0xee/0x1f0 [ 44.413347][ T505] ? lock_acquire+0x13c/0x160 [ 44.413349][ T505] ? console_flush_one_record+0x710/0x710 [ 44.413350][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413352][ T505] ? lock_release+0x184/0x1f0 [ 44.413353][ T505] ? lock_acquire+0x60/0x160 [ 44.413355][ T505] ? __rcu_read_lock+0x30/0x70 [ 44.413357][ T505] ? __down_trylock_console_sem+0x5e/0xa0 [ 44.413359][ T505] ? vprintk_emit+0x320/0x3e0 [ 44.413361][ T505] vprintk_emit+0x37c/0x3e0 [ 44.413363][ T505] ? wake_up_klogd_work_func+0x90/0x90 [ 44.413365][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413366][ T505] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 44.413369][ T505] ? trace_hardirqs_on+0x36/0x40 [ 44.413371][ T505] _printk+0xc7/0x100 [ 44.413374][ T505] ? snapshot_read.cold+0x21/0x21 [ 44.413376][ T505] ? ipv6_mc_down+0x17e/0x2d0 [ 44.413380][ T505] br_set_state+0x22f/0x430 [bridge] [ 44.413393][ T505] br_stp_disable_port+0x11f/0x390 [bridge] [ 44.413401][ T505] br_device_event+0x7b5/0x8f0 [bridge] [ 44.413409][ T505] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 44.413413][ T505] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 44.413415][ T505] ? addrconf_notify+0x2c8/0xf30 [ 44.413419][ T505] notifier_call_chain+0xae/0x300 [ 44.413422][ T505] __dev_notify_flags+0x23c/0x3c0 [ 44.413426][ T505] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 44.413427][ T505] ? netif_change_name+0x8c0/0x8c0 [ 44.413430][ T505] ? netif_set_allmulti+0x250/0x250 [ 44.413432][ T505] ? __kernel_text_address+0x12/0x30 [ 44.413434][ T505] ? write_profile+0xf0/0xf0 [ 44.413436][ T505] ? unwind_get_return_address+0x67/0xd0 [ 44.413439][ T505] netif_change_flags+0xfe/0x190 [ 44.413441][ T505] do_setlink.isra.0+0x1cc3/0x2750 [ 44.413444][ T505] ? stack_trace_save+0x93/0xc0 [ 44.413445][ T505] ? rtnl_link_get_size+0x350/0x350 [ 44.413447][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413448][ T505] ? stack_depot_save_flags+0x1bc/0x790 [ 44.413452][ T505] ? kasan_save_stack+0x3d/0x50 [ 44.413455][ T505] ? kasan_save_stack+0x2f/0x50 [ 44.413456][ T505] ? kasan_save_track+0x14/0x30 [ 44.413458][ T505] ? __kasan_kmalloc+0x7b/0x90 [ 44.413459][ T505] ? __kmalloc_cache_noprof+0x29a/0x620 [ 44.413461][ T505] ? rtnl_newlink+0x124/0x11c0 [ 44.413462][ T505] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 44.413463][ T505] ? netlink_rcv_skb+0x14e/0x3a0 [ 44.413466][ T505] ? netlink_unicast+0x486/0x750 [ 44.413467][ T505] ? netlink_sendmsg+0x735/0xc60 [ 44.413468][ T505] ? ____sys_sendmsg+0x415/0x880 [ 44.413471][ T505] ? ___sys_sendmsg+0x14e/0x1d0 [ 44.413472][ T505] ? __sys_sendmsg+0x12c/0x1d0 [ 44.413474][ T505] ? do_syscall_64+0xff/0x530 [ 44.413476][ T505] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 44.413479][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413481][ T505] ? lock_acquire+0x13c/0x160 [ 44.413482][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413484][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413485][ T505] ? trace_contention_end+0xb3/0x180 [ 44.413489][ T505] ? __mutex_lock+0x1db/0x1ea0 [ 44.413490][ T505] ? __mutex_lock+0x9a3/0x1ea0 [ 44.413491][ T505] ? rtnl_newlink+0x9a8/0x11c0 [ 44.413493][ T505] ? ww_mutex_lock+0x160/0x160 [ 44.413495][ T505] ? __rtnl_newlink+0x3fa/0xa50 [ 44.413497][ T505] rtnl_newlink+0x9f1/0x11c0 [ 44.413499][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413501][ T505] ? rtnl_bridge_getlink+0x850/0x850 [ 44.413502][ T505] ? lock_acquire+0x13c/0x160 [ 44.413503][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413505][ T505] ? is_bpf_text_address+0x3e/0x110 [ 44.413509][ T505] ? is_bpf_text_address+0x72/0x110 [ 44.413510][ T505] ? kernel_text_address+0x149/0x170 [ 44.413512][ T505] ? __kernel_text_address+0x12/0x30 [ 44.413513][ T505] ? write_profile+0xf0/0xf0 [ 44.413514][ T505] ? unwind_get_return_address+0x67/0xd0 [ 44.413515][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413517][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413519][ T505] ? cap_capable+0x1d7/0x3d0 [ 44.413523][ T505] ? lock_acquire+0x13c/0x160 [ 44.413524][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413526][ T505] ? rtnl_bridge_getlink+0x850/0x850 [ 44.413527][ T505] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 44.413529][ T505] ? rtnl_link_fill+0x920/0x920 [ 44.413530][ T505] ? filter_irq_stacks+0xd0/0xd0 [ 44.413532][ T505] ? stack_depot_save_flags+0x1bc/0x790 [ 44.413534][ T505] netlink_rcv_skb+0x14e/0x3a0 [ 44.413535][ T505] ? rtnl_link_fill+0x920/0x920 [ 44.413537][ T505] ? lock_release+0x184/0x1f0 [ 44.413538][ T505] ? netlink_ack+0xcf0/0xcf0 [ 44.413539][ T505] ? __netlink_lookup+0xb9/0x6b0 [ 44.413542][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413543][ T505] ? lock_release+0x184/0x1f0 [ 44.413545][ T505] netlink_unicast+0x486/0x750 [ 44.413547][ T505] ? netlink_attachskb+0x810/0x810 [ 44.413549][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413550][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413552][ T505] ? lock_acquire+0x13c/0x160 [ 44.413554][ T505] netlink_sendmsg+0x735/0xc60 [ 44.413556][ T505] ? netlink_unicast+0x750/0x750 [ 44.413558][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413559][ T505] ? lock_release+0x184/0x1f0 [ 44.413561][ T505] ____sys_sendmsg+0x415/0x880 [ 44.413563][ T505] ? copy_msghdr_from_user+0x279/0x420 [ 44.413564][ T505] ? get_timestamp.constprop.0+0x390/0x390 [ 44.413566][ T505] ? move_addr_to_kernel+0x40/0x40 [ 44.413567][ T505] ? filter_irq_stacks+0xd0/0xd0 [ 44.413570][ T505] ___sys_sendmsg+0x14e/0x1d0 [ 44.413572][ T505] ? copy_msghdr_from_user+0x420/0x420 [ 44.413576][ T505] __sys_sendmsg+0x12c/0x1d0 [ 44.413578][ T505] ? __sys_sendmsg_sock+0x20/0x20 [ 44.413581][ T505] ? rcu_is_watching+0x16/0xd0 [ 44.413583][ T505] do_syscall_64+0xff/0x530 [ 44.413584][ T505] ? exc_page_fault+0xee/0x100 [ 44.413586][ T505] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 44.413588][ T505] RIP: 0033:0x7fd8d456254e [ 44.413590][ T505] 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 [ 44.413591][ T505] RSP: 002b:00007ffc6d5556b0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 44.413595][ T505] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fd8d456254e [ 44.413596][ T505] RDX: 0000000000000000 RSI: 00007ffc6d555760 RDI: 0000000000000005 [ 44.413597][ T505] RBP: 00007ffc6d5556c0 R08: 0000000000000000 R09: 0000000000000000 [ 44.413598][ T505] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b57c [ 44.413599][ T505] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc6d555e20 [ 44.413601][ T505] [ 44.447484][ T505] ip (505) used greatest stack depth: 23016 bytes left [ 44.463293][ T506] br1: port 2(veth2) entered disabled state [ 44.476863][ T507] veth2: left allmulticast mode [ 44.477688][ T507] veth2: left promiscuous mode [ 44.477957][ T507] br1: port 2(veth2) entered disabled state [ 44.478623][ T507] veth1: left allmulticast mode [ 44.478792][ T507] veth1: left promiscuous mode [ 44.479396][ T507] br1: port 1(veth1) entered disabled state