[   25.040271][  T235] ip (235) used greatest stack depth: 23392 bytes left
[   29.153324][  T284] br0: port 1(veth1) entered blocking state
[   29.154075][  T284] br0: port 1(veth1) entered disabled state
[   29.154487][  T284] veth1: entered allmulticast mode
[   29.156658][  T284] veth1: entered promiscuous mode
[   29.281092][  T286] br0: port 2(veth2) entered blocking state
[   29.281387][  T286] br0: port 2(veth2) entered disabled state
[   29.281678][  T286] veth2: entered allmulticast mode
[   29.283699][  T286] veth2: entered promiscuous mode
[   29.546715][   T64] br0: port 1(veth1) entered blocking state
[   29.547072][   T64] br0: port 1(veth1) entered forwarding state
[   29.667777][   T49] br0: port 2(veth2) entered blocking state
[   29.668090][   T49] br0: port 2(veth2) entered forwarding state
[   38.741527][  T316] veth0: entered promiscuous mode
[   39.380475][  T322] GACT probability NOT on
[   44.868790][    C3] 
[   44.868957][    C3] ========================================================
[   44.869203][    C3] WARNING: possible irq lock inversion dependency detected
[   44.869435][    C3] 6.9.0-rc1-virtme #1 Not tainted
[   44.869626][    C3] --------------------------------------------------------
[   44.869839][    C3] swapper/3/0 just changed the state of lock:
[   44.870044][    C3] ffff8880051cac38 (&br->lock){+.-.}-{2:2}, at: br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.870456][    C3] but this lock took another, SOFTIRQ-unsafe lock in the past:
[   44.870671][    C3]  (&p->alloc_lock){+.+.}-{2:2}
[   44.870675][    C3] 
[   44.870675][    C3] 
[   44.870675][    C3] and interrupts could create inverse lock ordering between them.
[   44.870675][    C3] 
[   44.871214][    C3] 
[   44.871214][    C3] other info that might help us debug this:
[   44.871441][    C3] Chain exists of:
[   44.871441][    C3]   &br->lock --> lweventlist_lock --> &p->alloc_lock
[   44.871441][    C3] 
[   44.871762][    C3]  Possible interrupt unsafe locking scenario:
[   44.871762][    C3] 
[   44.871968][    C3]        CPU0                    CPU1
[   44.872110][    C3]        ----                    ----
[   44.872260][    C3]   lock(&p->alloc_lock);
[   44.872381][    C3]                                local_irq_disable();
[   44.872585][    C3]                                lock(&br->lock);
[   44.872767][    C3]                                lock(lweventlist_lock);
[   44.872941][    C3]   <Interrupt>
[   44.873049][    C3]     lock(&br->lock);
[   44.873157][    C3] 
[   44.873157][    C3]  *** DEADLOCK ***
[   44.873157][    C3] 
[   44.873359][    C3] 1 lock held by swapper/3/0:
[   44.873493][    C3]  #0: ffffc90000288d68 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0xe8/0x230
[   44.873786][    C3] 
[   44.873786][    C3] the shortest dependencies between 2nd lock and 1st lock:
[   44.874023][    C3]   -> (&p->alloc_lock){+.+.}-{2:2} {
[   44.874164][    C3]      HARDIRQ-ON-W at:
[   44.874270][    C3]                         __lock_acquire+0x797/0x1570
[   44.874452][    C3]                         lock_acquire.part.0+0xeb/0x330
[   44.874631][    C3]                         _raw_spin_lock+0x30/0x40
[   44.874807][    C3]                         set_mems_allowed+0x1d/0x210
[   44.875011][    C3]                         kernel_init_freeable+0x72/0x310
[   44.875185][    C3]                         kernel_init+0x20/0x200
[   44.875357][    C3]                         ret_from_fork+0x31/0x70
[   44.875542][    C3]                         ret_from_fork_asm+0x1a/0x30
[   44.875716][    C3]      SOFTIRQ-ON-W at:
[   44.875824][    C3]                         __lock_acquire+0x797/0x1570
[   44.875999][    C3]                         lock_acquire.part.0+0xeb/0x330
[   44.876171][    C3]                         _raw_spin_lock+0x30/0x40
[   44.876350][    C3]                         set_mems_allowed+0x1d/0x210
[   44.876519][    C3]                         kernel_init_freeable+0x72/0x310
[   44.876722][    C3]                         kernel_init+0x20/0x200
[   44.876911][    C3]                         ret_from_fork+0x31/0x70
[   44.877092][    C3]                         ret_from_fork_asm+0x1a/0x30
[   44.877276][    C3]      INITIAL USE at:
[   44.877384][    C3]                        __lock_acquire+0x797/0x1570
[   44.877569][    C3]                        lock_acquire.part.0+0xeb/0x330
[   44.877752][    C3]                        _raw_spin_lock+0x30/0x40
[   44.877946][    C3]                        set_mems_allowed+0x1d/0x210
[   44.878126][    C3]                        kernel_init_freeable+0x72/0x310
[   44.878318][    C3]                        kernel_init+0x20/0x200
[   44.878497][    C3]                        ret_from_fork+0x31/0x70
[   44.878688][    C3]                        ret_from_fork_asm+0x1a/0x30
[   44.878877][    C3]    }
[   44.878950][    C3]    ... key      at: [<ffffffff87bffd20>] __key.387+0x0/0x40
[   44.879171][    C3]    ... acquired at:
[   44.879276][    C3]    __lock_acquire+0xaf0/0x1570
[   44.879418][    C3]    lock_acquire.part.0+0xeb/0x330
[   44.879556][    C3]    _raw_spin_lock+0x30/0x40
[   44.879694][    C3]    __get_task_comm+0x27/0x70
[   44.879837][    C3]    ref_tracker_alloc+0x2ee/0x490
[   44.879981][    C3]    linkwatch_fire_event+0x196/0x200
[   44.880133][    C3]    veth_newlink+0x367/0x830 [veth]
[   44.880289][    C3]    rtnl_newlink_create+0x341/0x850
[   44.880431][    C3]    __rtnl_newlink+0xac9/0xd80
[   44.880574][    C3]    rtnl_newlink+0x63/0xa0
[   44.880712][    C3]    rtnetlink_rcv_msg+0x2fb/0xbf0
[   44.880845][    C3]    netlink_rcv_skb+0x130/0x360
[   44.880997][    C3]    netlink_unicast+0x449/0x710
[   44.881135][    C3]    netlink_sendmsg+0x723/0xbe0
[   44.881291][    C3]    ____sys_sendmsg+0x7b2/0xa10
[   44.881428][    C3]    ___sys_sendmsg+0xee/0x170
[   44.881574][    C3]    __sys_sendmsg+0xcd/0x170
[   44.881714][    C3]    do_syscall_64+0xc6/0x1e0
[   44.881855][    C3]    entry_SYSCALL_64_after_hwframe+0x6d/0x75
[   44.882023][    C3] 
[   44.882096][    C3]  -> (lweventlist_lock){....}-{2:2} {
[   44.882245][    C3]     INITIAL USE at:
[   44.882347][    C3]                      __lock_acquire+0x797/0x1570
[   44.882523][    C3]                      lock_acquire.part.0+0xeb/0x330
[   44.882694][    C3]                      _raw_spin_lock_irqsave+0x3f/0x60
[   44.882877][    C3]                      linkwatch_sync_dev+0x29/0x1f0
[   44.883050][    C3]                      rtnl_getlink+0x4c9/0x970
[   44.883218][    C3]                      rtnetlink_rcv_msg+0x2fb/0xbf0
[   44.883387][    C3]                      netlink_rcv_skb+0x130/0x360
[   44.883562][    C3]                      netlink_unicast+0x449/0x710
[   44.883729][    C3]                      netlink_sendmsg+0x723/0xbe0
[   44.883909][    C3]                      __sys_sendto+0x37d/0x410
[   44.884087][    C3]                      __x64_sys_sendto+0xe0/0x1c0
[   44.884265][    C3]                      do_syscall_64+0xc6/0x1e0
[   44.884435][    C3]                      entry_SYSCALL_64_after_hwframe+0x6d/0x75
[   44.884642][    C3]   }
[   44.884719][    C3]   ... key      at: [<ffffffff86b9a738>] lweventlist_lock+0x18/0x60
[   44.884933][    C3]   ... acquired at:
[   44.885032][    C3]    __lock_acquire+0xaf0/0x1570
[   44.885177][    C3]    lock_acquire.part.0+0xeb/0x330
[   44.885324][    C3]    _raw_spin_lock_irqsave+0x3f/0x60
[   44.885463][    C3]    linkwatch_fire_event+0x74/0x200
[   44.885607][    C3]    br_stp_change_bridge_id+0x25f/0x360 [bridge]
[   44.885840][    C3]    br_set_mac_address+0x1f3/0x260 [bridge]
[   44.886046][    C3]    dev_set_mac_address+0x28a/0x410
[   44.886188][    C3]    dev_set_mac_address_user+0x31/0x50
[   44.886324][    C3]    do_setlink+0x17b4/0x21e0
[   44.886467][    C3]    rtnl_setlink+0x1f7/0x310
[   44.886609][    C3]    rtnetlink_rcv_msg+0x2fb/0xbf0
[   44.886750][    C3]    netlink_rcv_skb+0x130/0x360
[   44.886896][    C3]    netlink_unicast+0x449/0x710
[   44.887037][    C3]    netlink_sendmsg+0x723/0xbe0
[   44.887182][    C3]    __sys_sendto+0x37d/0x410
[   44.887319][    C3]    __x64_sys_sendto+0xe0/0x1c0
[   44.887466][    C3]    do_syscall_64+0xc6/0x1e0
[   44.887606][    C3]    entry_SYSCALL_64_after_hwframe+0x6d/0x75
[   44.887790][    C3] 
[   44.887858][    C3] -> (&br->lock){+.-.}-{2:2} {
[   44.888006][    C3]    HARDIRQ-ON-W at:
[   44.888122][    C3]                     __lock_acquire+0x797/0x1570
[   44.888306][    C3]                     lock_acquire.part.0+0xeb/0x330
[   44.888485][    C3]                     _raw_spin_lock_bh+0x38/0x50
[   44.888666][    C3]                     br_set_ageing_time+0x2d/0xf0 [bridge]
[   44.888917][    C3]                     br_changelink+0x231/0x1360 [bridge]
[   44.889127][    C3]                     br_dev_newlink+0xa5/0x100 [bridge]
[   44.889344][    C3]                     rtnl_newlink_create+0x341/0x850
[   44.889518][    C3]                     __rtnl_newlink+0xac9/0xd80
[   44.889698][    C3]                     rtnl_newlink+0x63/0xa0
[   44.889876][    C3]                     rtnetlink_rcv_msg+0x2fb/0xbf0
[   44.890065][    C3]                     netlink_rcv_skb+0x130/0x360
[   44.890246][    C3]                     netlink_unicast+0x449/0x710
[   44.890416][    C3]                     netlink_sendmsg+0x723/0xbe0
[   44.890587][    C3]                     ____sys_sendmsg+0x7b2/0xa10
[   44.890755][    C3]                     ___sys_sendmsg+0xee/0x170
[   44.890927][    C3]                     __sys_sendmsg+0xcd/0x170
[   44.891097][    C3]                     do_syscall_64+0xc6/0x1e0
[   44.891273][    C3]                     entry_SYSCALL_64_after_hwframe+0x6d/0x75
[   44.891491][    C3]    IN-SOFTIRQ-W at:
[   44.891595][    C3]                     __lock_acquire+0x797/0x1570
[   44.891780][    C3]                     lock_acquire.part.0+0xeb/0x330
[   44.891951][    C3]                     _raw_spin_lock+0x30/0x40
[   44.892123][    C3]                     br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.892374][    C3]                     call_timer_fn+0x13b/0x230
[   44.892556][    C3]                     __run_timers+0x545/0x810
[   44.892726][    C3]                     run_timer_softirq+0xe8/0x1b0
[   44.892920][    C3]                     __do_softirq+0x1f8/0x5df
[   44.893099][    C3]                     irq_exit_rcu+0x97/0xc0
[   44.893285][    C3]                     sysvec_apic_timer_interrupt+0x75/0x80
[   44.893486][    C3]                     asm_sysvec_apic_timer_interrupt+0x1a/0x20
[   44.893700][    C3]                     default_idle+0xf/0x20
[   44.893877][    C3]                     default_idle_call+0x6d/0xb0
[   44.894058][    C3]                     cpuidle_idle_call+0x1f4/0x280
[   44.894237][    C3]                     do_idle+0xf9/0x160
[   44.894377][    C3]                     cpu_startup_entry+0x54/0x60
[   44.894555][    C3]                     start_secondary+0x21c/0x2b0
[   44.894731][    C3]                     common_startup_64+0x12c/0x138
[   44.894920][    C3]    INITIAL USE at:
[   44.895025][    C3]                    __lock_acquire+0x797/0x1570
[   44.895211][    C3]                    lock_acquire.part.0+0xeb/0x330
[   44.895389][    C3]                    _raw_spin_lock_bh+0x38/0x50
[   44.895567][    C3]                    br_set_ageing_time+0x2d/0xf0 [bridge]
[   44.895823][    C3]                    br_changelink+0x231/0x1360 [bridge]
[   44.896040][    C3]                    br_dev_newlink+0xa5/0x100 [bridge]
[   44.896250][    C3]                    rtnl_newlink_create+0x341/0x850
[   44.896438][    C3]                    __rtnl_newlink+0xac9/0xd80
[   44.896617][    C3]                    rtnl_newlink+0x63/0xa0
[   44.896800][    C3]                    rtnetlink_rcv_msg+0x2fb/0xbf0
[   44.896974][    C3]                    netlink_rcv_skb+0x130/0x360
[   44.897150][    C3]                    netlink_unicast+0x449/0x710
[   44.897335][    C3]                    netlink_sendmsg+0x723/0xbe0
[   44.897518][    C3]                    ____sys_sendmsg+0x7b2/0xa10
[   44.897698][    C3]                    ___sys_sendmsg+0xee/0x170
[   44.897881][    C3]                    __sys_sendmsg+0xcd/0x170
[   44.898059][    C3]                    do_syscall_64+0xc6/0x1e0
[   44.898241][    C3]                    entry_SYSCALL_64_after_hwframe+0x6d/0x75
[   44.898473][    C3]  }
[   44.898547][    C3]  ... key      at: [<ffffffffc0531540>] __key.6+0x0/0xfffffffffffe5ac0 [bridge]
[   44.898854][    C3]  ... acquired at:
[   44.899125][    C3]    mark_lock+0x28d/0x3e0
[   44.899400][    C3]    mark_usage+0xd9/0x2a0
[   44.899548][    C3]    __lock_acquire+0x797/0x1570
[   44.899703][    C3]    lock_acquire.part.0+0xeb/0x330
[   44.899854][    C3]    _raw_spin_lock+0x30/0x40
[   44.900083][    C3]    br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.900332][    C3]    call_timer_fn+0x13b/0x230
[   44.900482][    C3]    __run_timers+0x545/0x810
[   44.900619][    C3]    run_timer_softirq+0xe8/0x1b0
[   44.900832][    C3]    __do_softirq+0x1f8/0x5df
[   44.900971][    C3]    irq_exit_rcu+0x97/0xc0
[   44.901119][    C3]    sysvec_apic_timer_interrupt+0x75/0x80
[   44.901294][    C3]    asm_sysvec_apic_timer_interrupt+0x1a/0x20
[   44.901615][    C3]    default_idle+0xf/0x20
[   44.901757][    C3]    default_idle_call+0x6d/0xb0
[   44.901891][    C3]    cpuidle_idle_call+0x1f4/0x280
[   44.902031][    C3]    do_idle+0xf9/0x160
[   44.902226][    C3]    cpu_startup_entry+0x54/0x60
[   44.902360][    C3]    start_secondary+0x21c/0x2b0
[   44.902503][    C3]    common_startup_64+0x12c/0x138
[   44.902647][    C3] 
[   44.902714][    C3] 
[   44.902714][    C3] stack backtrace:
[   44.902966][    C3] CPU: 3 PID: 0 Comm: swapper/3 Not tainted 6.9.0-rc1-virtme #1
[   44.903170][    C3] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[   44.903562][    C3] Call Trace:
[   44.903665][    C3]  <IRQ>
[   44.903742][    C3]  dump_stack_lvl+0x82/0xd0
[   44.903895][    C3]  print_irq_inversion_bug.part.0+0x3d9/0x570
[   44.904068][    C3]  ? common_startup_64+0x12c/0x138
[   44.904292][    C3]  ? __pfx_print_irq_inversion_bug.part.0+0x10/0x10
[   44.904464][    C3]  ? __pfx_usage_skip+0x10/0x10
[   44.904609][    C3]  ? __pfx_usage_match+0x10/0x10
[   44.904748][    C3]  ? arch_stack_walk+0x8c/0xf0
[   44.904982][    C3]  mark_lock_irq+0x4cd/0xa10
[   44.905124][    C3]  ? __pfx_mark_lock_irq+0x10/0x10
[   44.905266][    C3]  ? stack_trace_save+0x94/0xd0
[   44.905407][    C3]  ? save_trace+0x8f/0x5b0
[   44.905631][    C3]  mark_lock+0x28d/0x3e0
[   44.905741][    C3]  mark_usage+0xd9/0x2a0
[   44.905845][    C3]  __lock_acquire+0x797/0x1570
[   44.905997][    C3]  ? __lock_acquire+0xaf0/0x1570
[   44.906136][    C3]  ? __pfx_br_forward_delay_timer_expired+0x10/0x10 [bridge]
[   44.906467][    C3]  lock_acquire.part.0+0xeb/0x330
[   44.906603][    C3]  ? br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.906820][    C3]  ? __pfx_lock_acquire.part.0+0x10/0x10
[   44.907042][    C3]  ? trace_lock_acquire+0x135/0x1c0
[   44.907181][    C3]  ? br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.907401][    C3]  ? lock_acquire+0x32/0xc0
[   44.907539][    C3]  ? br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.907819][    C3]  _raw_spin_lock+0x30/0x40
[   44.907953][    C3]  ? br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.908154][    C3]  br_forward_delay_timer_expired+0x48/0x440 [bridge]
[   44.908359][    C3]  ? __pfx_br_forward_delay_timer_expired+0x10/0x10 [bridge]
[   44.908685][    C3]  call_timer_fn+0x13b/0x230
[   44.908825][    C3]  ? call_timer_fn+0xe8/0x230
[   44.908959][    C3]  ? call_timer_fn+0xe8/0x230
[   44.909091][    C3]  ? __pfx_call_timer_fn+0x10/0x10
[   44.909301][    C3]  ? mark_lock+0x38/0x3e0
[   44.909409][    C3]  __run_timers+0x545/0x810
[   44.909549][    C3]  ? __pfx_br_forward_delay_timer_expired+0x10/0x10 [bridge]
[   44.909794][    C3]  ? __pfx___run_timers+0x10/0x10
[   44.910018][    C3]  ? do_raw_spin_lock+0x131/0x270
[   44.910154][    C3]  ? __pfx_do_raw_spin_lock+0x10/0x10
[   44.910290][    C3]  ? lock_acquire+0x32/0xc0
[   44.910423][    C3]  ? run_timer_softirq+0xe0/0x1b0
[   44.910644][    C3]  run_timer_softirq+0xe8/0x1b0
[   44.910782][    C3]  __do_softirq+0x1f8/0x5df
[   44.910925][    C3]  irq_exit_rcu+0x97/0xc0
[   44.911027][    C3]  sysvec_apic_timer_interrupt+0x75/0x80
[   44.911243][    C3]  </IRQ>
[   44.911326][    C3]  <TASK>
[   44.911394][    C3]  asm_sysvec_apic_timer_interrupt+0x1a/0x20
[   44.911570][    C3] RIP: 0010:default_idle+0xf/0x20
[   44.911718][    C3] Code: 4c 01 c7 4c 29 c2 e9 72 ff ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d a3 30 35 00 fb f4 <fa> c3 cc cc cc cc 66 66 2e 0f 1f 84 00 00 00 00 00 90 90 90 90 90
[   44.912293][    C3] RSP: 0018:ffffc9000016fdf8 EFLAGS: 00000242
[   44.912469][    C3] RAX: 000000000007a29b RBX: 1ffff9200002dfc1 RCX: ffffffff8510e9d5
[   44.912852][    C3] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff82aaa4a4
[   44.913068][    C3] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c3709c
[   44.913277][    C3] R10: ffff8880361b84e3 R11: ffff8880361ad9b0 R12: 0000000000000000
[   44.913485][    C3] R13: ffff888001b88040 R14: dffffc0000000000 R15: 0000000000000000
[   44.913704][    C3]  ? ct_kernel_exit.constprop.0+0xc5/0xf0
[   44.913851][    C3]  ? cpuidle_idle_call+0x1f4/0x280
[   44.914069][    C3]  default_idle_call+0x6d/0xb0
[   44.914215][    C3]  cpuidle_idle_call+0x1f4/0x280
[   44.914354][    C3]  ? __pfx_cpuidle_idle_call+0x10/0x10
[   44.914489][    C3]  ? tsc_verify_tsc_adjust+0x5e/0x2b0
[   44.914720][    C3]  do_idle+0xf9/0x160
[   44.914832][    C3]  cpu_startup_entry+0x54/0x60
[   44.914978][    C3]  start_secondary+0x21c/0x2b0
[   44.915122][    C3]  ? __pfx_start_secondary+0x10/0x10
[   44.915268][    C3]  common_startup_64+0x12c/0x138
[   44.915417][    C3]  </TASK>
[   65.893323][  T350] veth0: left promiscuous mode
[   66.210666][  T354] veth3: entered promiscuous mode
[   68.044017][  T363] veth3: left promiscuous mode
[   68.249959][  T365] veth3: entered promiscuous mode
[   70.097635][  T374] veth3: left promiscuous mode
[   70.319215][  T377] veth3: entered promiscuous mode
[   72.219101][  T386] veth3: left promiscuous mode
[   72.441401][  T388] veth3: entered promiscuous mode
[   74.297293][  T397] veth3: left promiscuous mode
[   74.402164][  T399] br0: port 2(veth2) entered disabled state
[   74.494945][  T400] br0: port 1(veth1) entered disabled state
[   74.592886][  T401] veth2: left allmulticast mode
[   74.593060][  T401] veth2: left promiscuous mode
[   74.593314][  T401] br0: port 2(veth2) entered disabled state
[   74.594291][  T401] veth1: left allmulticast mode
[   74.594460][  T401] veth1: left promiscuous mode
[   74.595553][  T401] br0: port 1(veth1) entered disabled state