make -C tools/testing/selftests TARGETS=net TEST_PROGS=test_bridge_backup__port.sh TEST_GEN_PROGS="" run_tests
make: Entering directory '/home/virtme/testing-3/tools/testing/selftests'
make[1]: Entering directory '/home/virtme/testing-3/tools/testing/selftests/net'
make[1]: Nothing to be done for 'all'.
make[1]: Leaving directory '/home/virtme/testing-3/tools/testing/selftests/net'
make[1]: Entering directory '/home/virtme/testing-3/tools/testing/selftests/net'
TAP version 13
1..1
# timeout set to 6000
# selftests: net: test_bridge_backup_port.sh
[ 25.437810][ T260] veth0: renamed from veth1
[ 27.037527][ T277] br0: port 1(swp1) entered blocking state
[ 27.037946][ T277] br0: port 1(swp1) entered disabled state
[ 27.038533][ T277] swp1: entered allmulticast mode
[ 27.039909][ T277] swp1: entered promiscuous mode
[ 27.041471][ T277] br0: port 1(swp1) entered blocking state
[ 27.041840][ T277] br0: port 1(swp1) entered forwarding state
[ 27.633224][ T279] br0: port 2(vx0) entered blocking state
[ 27.633558][ T279] br0: port 2(vx0) entered disabled state
[ 27.633898][ T279] vx0: entered allmulticast mode
[ 27.635352][ T279] vx0: entered promiscuous mode
[ 27.635959][ T279] br0: port 2(vx0) entered blocking state
[ 27.636278][ T279] br0: port 2(vx0) entered forwarding state
[ 29.757218][ T293] br0: port 1(swp1) entered blocking state
[ 29.757570][ T293] br0: port 1(swp1) entered disabled state
[ 29.757988][ T293] swp1: entered allmulticast mode
[ 29.759385][ T293] swp1: entered promiscuous mode
[ 29.760932][ T293] br0: port 1(swp1) entered blocking state
[ 29.761260][ T293] br0: port 1(swp1) entered forwarding state
[ 30.077281][ T295] br0: port 2(vx0) entered blocking state
[ 30.077631][ T295] br0: port 2(vx0) entered disabled state
[ 30.077994][ T295] vx0: entered allmulticast mode
[ 30.079399][ T295] vx0: entered promiscuous mode
[ 30.080306][ T295] br0: port 2(vx0) entered blocking state
[ 30.080636][ T295] br0: port 2(vx0) entered forwarding state
#
# Backup port
# -----------
[ 36.571948][ T308] GACT probability NOT on
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 38.770345][ T300] br0: port 1(swp1) entered disabled state
# TEST: swp1 carrier off [ OK ]
# TEST: No forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 40.124598][ T50] br0: port 1(swp1) entered blocking state
[ 40.124961][ T50] br0: port 1(swp1) entered forwarding state
# TEST: swp1 carrier on [ OK ]
# TEST: vx0 configured as backup port of swp1 [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 41.779372][ T47] br0: port 1(swp1) entered disabled state
# TEST: swp1 carrier off [ OK ]
# TEST: No forwarding out of swp1 [ OK ]
# TEST: Forwarding out of vx0 [ OK ]
[ 43.090399][ T47] br0: port 1(swp1) entered blocking state
[ 43.090765][ T47] br0: port 1(swp1) entered forwarding state
# TEST: swp1 carrier on [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
# TEST: vx0 not configured as backup port of swp1 [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 45.910288][ T300] br0: port 1(swp1) entered disabled state
# TEST: swp1 carrier off [ OK ]
# TEST: No forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 47.278555][ T11] vx0: left allmulticast mode
[ 47.278891][ T11] vx0: left promiscuous mode
[ 47.279338][ T11] br0: port 2(vx0) entered disabled state
[ 47.308543][ T11] swp1: left allmulticast mode
[ 47.308833][ T11] swp1: left promiscuous mode
[ 47.309224][ T11] br0: port 1(swp1) entered disabled state
[ 47.413521][ T11] ==================================================================
[ 47.413991][ T11] BUG: KASAN: use-after-free in vxlan_netdevice_event+0x32f/0x340 [vxlan]
[ 47.414479][ T11] Read of size 8 at addr ffff888006458bf0 by task kworker/u8:0/11
[ 47.414920][ T11]
[ 47.415050][ T11] CPU: 0 PID: 11 Comm: kworker/u8:0 Not tainted 6.8.0-rc2-virtme #1
[ 47.415481][ T11] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 47.416145][ T11] Workqueue: netns cleanup_net
[ 47.416451][ T11] Call Trace:
[ 47.416639][ T11]
[ 47.416803][ T11] dump_stack_lvl+0x64/0xb0
[ 47.417057][ T11] print_address_description.constprop.0+0x2c/0x3b0
[ 47.417426][ T11] ? vxlan_netdevice_event+0x32f/0x340 [vxlan]
[ 47.417775][ T11] print_report+0xb5/0x270
[ 47.418026][ T11] ? kasan_addr_to_slab+0x4e/0x90
[ 47.418307][ T11] kasan_report+0xbe/0xf0
[ 47.418549][ T11] ? vxlan_netdevice_event+0x32f/0x340 [vxlan]
[ 47.418901][ T11] vxlan_netdevice_event+0x32f/0x340 [vxlan]
[ 47.419243][ T11] ? __pfx_vxlan_netdevice_event+0x10/0x10 [vxlan]
[ 47.419621][ T11] ? netconsole_netdev_event+0x1b4/0x300
[ 47.419935][ T11] notifier_call_chain+0x9a/0x290
[ 47.420223][ T11] unregister_netdevice_many_notify+0x55a/0x1180
[ 47.420573][ T11] ? mutex_is_locked+0x17/0x50
[ 47.420848][ T11] ? __pfx_unregister_netdevice_many_notify+0x10/0x10
[ 47.421216][ T11] ? __pfx_unregister_netdevice_queue+0x10/0x10
[ 47.421559][ T11] default_device_exit_batch+0x228/0x2c0
[ 47.421875][ T11] ? __pfx_default_device_exit_batch+0x10/0x10
[ 47.422206][ T11] ? mutex_is_locked+0x17/0x50
[ 47.422475][ T11] ? nexthop_net_exit_batch_rtnl+0x83/0x210
[ 47.422807][ T11] cleanup_net+0x4f3/0xb50
[ 47.423056][ T11] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 47.423348][ T11] ? __pfx_cleanup_net+0x10/0x10
[ 47.423603][ T11] ? lock_acquire+0x1c1/0x220
[ 47.423848][ T11] ? process_one_work+0x714/0x1310
[ 47.424116][ T11] process_one_work+0x78c/0x1310
[ 47.424374][ T11] ? hlock_class+0x4e/0x130
[ 47.424611][ T11] ? __pfx_process_one_work+0x10/0x10
[ 47.424900][ T11] ? assign_work+0x16c/0x240
[ 47.425140][ T11] worker_thread+0x73d/0x1010
[ 47.425391][ T11] ? __pfx_worker_thread+0x10/0x10
[ 47.425655][ T11] kthread+0x28f/0x360
[ 47.425866][ T11] ? __pfx_kthread+0x10/0x10
[ 47.426106][ T11] ret_from_fork+0x31/0x70
[ 47.426336][ T11] ? __pfx_kthread+0x10/0x10
[ 47.426575][ T11] ret_from_fork_asm+0x1b/0x30
[ 47.426828][ T11]
[ 47.426987][ T11]
[ 47.427110][ T11] The buggy address belongs to the physical page:
[ 47.427437][ T11] page:ffffea0000191600 refcount:0 mapcount:0 mapping:0000000000000000 index:0x0 pfn:0x6458
[ 47.427951][ T11] flags: 0x80000000000000(node=0|zone=1)
[ 47.428240][ T11] page_type: 0xffffffff()
[ 47.428465][ T11] raw: 0080000000000000 ffff888035401c38 ffff888035401c38 0000000000000000
[ 47.428902][ T11] raw: 0000000000000000 0000000000000000 00000000ffffffff 0000000000000000
[ 47.429338][ T11] page dumped because: kasan: bad access detected
[ 47.429666][ T11]
[ 47.429789][ T11] Memory state around the buggy address:
[ 47.430079][ T11] ffff888006458a80: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 47.430489][ T11] ffff888006458b00: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 47.430896][ T11] >ffff888006458b80: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 47.431307][ T11] ^
[ 47.431699][ T11] ffff888006458c00: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 47.432127][ T11] ffff888006458c80: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 47.432561][ T11] ==================================================================
[ 47.433092][ T11] Disabling lock debugging due to kernel taint
[ 47.719070][ T11] vx0: left allmulticast mode
[ 47.719364][ T11] vx0: left promiscuous mode
[ 47.719746][ T11] br0: port 2(vx0) entered disabled state
[ 47.734863][ T11] swp1: left allmulticast mode
[ 47.735142][ T11] swp1: left promiscuous mode
[ 47.735506][ T11] br0: port 1(swp1) entered disabled state
[ 51.314157][ T437] veth0: renamed from veth1
[ 52.707368][ T454] br0: port 1(swp1) entered blocking state
[ 52.707714][ T454] br0: port 1(swp1) entered disabled state
[ 52.708047][ T454] swp1: entered allmulticast mode
[ 52.709333][ T454] swp1: entered promiscuous mode
[ 52.710440][ T454] br0: port 1(swp1) entered blocking state
[ 52.710750][ T454] br0: port 1(swp1) entered forwarding state
[ 52.966827][ T456] br0: port 2(vx0) entered blocking state
[ 52.967156][ T456] br0: port 2(vx0) entered disabled state
[ 52.967478][ T456] vx0: entered allmulticast mode
[ 52.968518][ T456] vx0: entered promiscuous mode
[ 52.969090][ T456] br0: port 2(vx0) entered blocking state
[ 52.969395][ T456] br0: port 2(vx0) entered forwarding state
[ 54.620550][ T469] br0: port 1(swp1) entered blocking state
[ 54.620942][ T469] br0: port 1(swp1) entered disabled state
[ 54.621305][ T469] swp1: entered allmulticast mode
[ 54.622444][ T469] swp1: entered promiscuous mode
[ 54.623612][ T469] br0: port 1(swp1) entered blocking state
[ 54.623928][ T469] br0: port 1(swp1) entered forwarding state
[ 54.925671][ T471] br0: port 2(vx0) entered blocking state
[ 54.926008][ T471] br0: port 2(vx0) entered disabled state
[ 54.926336][ T471] vx0: entered allmulticast mode
[ 54.927608][ T471] vx0: entered promiscuous mode
[ 54.928344][ T471] br0: port 2(vx0) entered blocking state
[ 54.928730][ T471] br0: port 2(vx0) entered forwarding state
#
# Backup nexthop ID
# -----------------
# TEST: vx0 configured as backup port of swp1 [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 63.537660][ T47] br0: port 1(swp1) entered disabled state
# TEST: swp1 carrier off [ OK ]
# TEST: No forwarding out of swp1 [ OK ]
# TEST: Forwarding out of vx0 [ OK ]
# TEST: No forwarding using backup nexthop ID [ OK ]
# TEST: Forwarding using VXLAN FDB entry [ OK ]
[ 65.176167][ T47] br0: port 1(swp1) entered blocking state
[ 65.176524][ T47] br0: port 1(swp1) entered forwarding state
# TEST: swp1 carrier on [ OK ]
# TEST: nexthop ID 10 configured as backup nexthop ID of swp1 [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
[ 66.423073][ T300] br0: port 1(swp1) entered disabled state
# TEST: swp1 carrier off [ OK ]
# TEST: No forwarding out of swp1 [ OK ]
# TEST: Forwarding out of vx0 [ OK ]
# TEST: Forwarding using backup nexthop ID [ OK ]
# TEST: No forwarding using VXLAN FDB entry [ OK ]
[ 68.059156][ T47] br0: port 1(swp1) entered blocking state
[ 68.059513][ T47] br0: port 1(swp1) entered forwarding state
# TEST: swp1 carrier on [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
# TEST: No forwarding using backup nexthop ID [ OK ]
# TEST: No forwarding using VXLAN FDB entry [ OK ]
# TEST: No backup nexthop ID configured for swp1 [ OK ]
# TEST: Forwarding out of swp1 [ OK ]
# TEST: No forwarding out of vx0 [ OK ]
# TEST: No forwarding using backup nexthop ID [ OK ]
# TEST: No forwarding using VXLAN FDB entry [ OK ]
[ 71.702293][ T8] br0: port 1(swp1) entered disabled state
# TEST: swp1 carrier off [ OK ]
# TEST: No forwarding out of swp1 [ OK ]
# TEST: Forwarding out of vx0 [ OK ]
# TEST: No forwarding using backup nexthop ID [ OK ]
# TEST: Forwarding using VXLAN FDB entry [ OK ]
[ 73.336391][ T11] vx0: left allmulticast mode
[ 73.336672][ T11] vx0: left promiscuous mode
[ 73.337044][ T11] br0: port 2(vx0) entered disabled state
[ 73.358501][ T11] swp1: left allmulticast mode
[ 73.358781][ T11] swp1: left promiscuous mode
[ 73.359132][ T11] br0: port 1(swp1) entered disabled state
[ 73.435732][ T11] general protection fault, probably for non-canonical address 0xeb4b474b4b4b4b4b: 0000 [#1] PREEMPT SMP KASAN NOPTI
[ 73.436445][ T11] KASAN: maybe wild-memory-access in range [0x5a5a5a5a5a5a5a58-0x5a5a5a5a5a5a5a5f]
[ 73.436966][ T11] CPU: 3 PID: 11 Comm: kworker/u8:0 Tainted: G B 6.8.0-rc2-virtme #1
[ 73.437466][ T11] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 73.438086][ T11] Workqueue: netns cleanup_net
[ 73.438340][ T11] RIP: 0010:vxlan_netdevice_event+0x19e/0x340 [vxlan]
[ 73.438701][ T11] Code: 00 00 00 48 b9 00 00 00 00 00 fc ff df 49 89 c0 48 89 44 24 08 49 c1 e8 03 4d 8d 24 08 eb 2c 48 8d 53 30 48 89 d0 48 c1 e8 03 <80> 3c 08 00 0f 85 e0 00 00 00 48 8b 43 30 49 89 dd 48 83 e8 30 49
[ 73.439692][ T11] RSP: 0018:ffffc900000bf980 EFLAGS: 00010206
[ 73.440032][ T11] RAX: 0b4b4b4b4b4b4b4b RBX: 5a5a5a5a5a5a5a2a RCX: dffffc0000000000
[ 73.440476][ T11] RDX: 5a5a5a5a5a5a5a5a RSI: 0000000000000004 RDI: ffff888009bb0c44
[ 73.440900][ T11] RBP: 1ffff92000017f33 R08: 1ffff1100121381a R09: ffffc900000bf9b8
[ 73.441305][ T11] R10: ffffffffa6751397 R11: ffff88800909c000 R12: ffffed100121381a
[ 73.441710][ T11] R13: ffff888009bb0bc0 R14: ffff8880082a1000 R15: ffff88800909c000
[ 73.442117][ T11] FS: 0000000000000000(0000) GS:ffff888035e00000(0000) knlGS:0000000000000000
[ 73.442572][ T11] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 73.442910][ T11] CR2: 00007fff953f97e8 CR3: 0000000002b40001 CR4: 0000000000770ef0
[ 73.443318][ T11] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 73.443722][ T11] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 73.444131][ T11] PKRU: 55555554
[ 73.444314][ T11] Call Trace:
[ 73.444486][ T11]
[ 73.444651][ T11] ? die_addr+0x41/0xa0
[ 73.444869][ T11] ? exc_general_protection+0x149/0x220
[ 73.445159][ T11] ? asm_exc_general_protection+0x26/0x30
[ 73.445459][ T11] ? vxlan_netdevice_event+0x19e/0x340 [vxlan]
[ 73.445790][ T11] ? __pfx_vxlan_netdevice_event+0x10/0x10 [vxlan]
[ 73.446136][ T11] ? netconsole_netdev_event+0x1b4/0x300
[ 73.446427][ T11] ? addrconf_notify+0xd1/0xd40
[ 73.446682][ T11] notifier_call_chain+0x9a/0x290
[ 73.446946][ T11] unregister_netdevice_many_notify+0x55a/0x1180
[ 73.447274][ T11] ? mutex_is_locked+0x17/0x50
[ 73.447526][ T11] ? __pfx_unregister_netdevice_many_notify+0x10/0x10
[ 73.447871][ T11] ? __pfx_unregister_netdevice_queue+0x10/0x10
[ 73.448190][ T11] ? __wake_up+0x44/0x60
[ 73.448417][ T11] default_device_exit_batch+0x228/0x2c0
[ 73.448707][ T11] ? __pfx_default_device_exit_batch+0x10/0x10
[ 73.449024][ T11] ? nexthop_net_exit_batch_rtnl+0x128/0x210
[ 73.449337][ T11] cleanup_net+0x4f3/0xb50
[ 73.449568][ T11] ? lock_acquire+0x1c1/0x220
[ 73.449813][ T11] ? __pfx_cleanup_net+0x10/0x10
[ 73.450070][ T11] ? lock_acquire+0x1c1/0x220
[ 73.450313][ T11] ? process_one_work+0x714/0x1310
[ 73.450580][ T11] process_one_work+0x78c/0x1310
[ 73.450837][ T11] ? hlock_class+0x4e/0x130
[ 73.451072][ T11] ? __pfx_process_one_work+0x10/0x10
[ 73.451351][ T11] ? assign_work+0x16c/0x240
[ 73.451590][ T11] worker_thread+0x73d/0x1010
[ 73.451836][ T11] ? __pfx_worker_thread+0x10/0x10
[ 73.452101][ T11] kthread+0x28f/0x360
[ 73.452312][ T11] ? __pfx_kthread+0x10/0x10
[ 73.452552][ T11] ret_from_fork+0x31/0x70
[ 73.452782][ T11] ? __pfx_kthread+0x10/0x10
[ 73.453019][ T11] ret_from_fork_asm+0x1b/0x30
[ 73.453273][ T11]
[ 73.453432][ T11] Modules linked in: act_gact cls_flower sch_ingress vxlan ip6_udp_tunnel udp_tunnel
[ 73.453942][ T11] ---[ end trace 0000000000000000 ]---
[ 73.454231][ T11] RIP: 0010:vxlan_netdevice_event+0x19e/0x340 [vxlan]
[ 73.455042][ T11] Code: 00 00 00 48 b9 00 00 00 00 00 fc ff df 49 89 c0 48 89 44 24 08 49 c1 e8 03 4d 8d 24 08 eb 2c 48 8d 53 30 48 89 d0 48 c1 e8 03 <80> 3c 08 00 0f 85 e0 00 00 00 48 8b 43 30 49 89 dd 48 83 e8 30 49
[ 73.456044][ T11] RSP: 0018:ffffc900000bf980 EFLAGS: 00010206
[ 73.456366][ T11] RAX: 0b4b4b4b4b4b4b4b RBX: 5a5a5a5a5a5a5a2a RCX: dffffc0000000000
[ 73.456775][ T11] RDX: 5a5a5a5a5a5a5a5a RSI: 0000000000000004 RDI: ffff888009bb0c44
[ 73.457183][ T11] RBP: 1ffff92000017f33 R08: 1ffff1100121381a R09: ffffc900000bf9b8
[ 73.457623][ T11] R10: ffffffffa6751397 R11: ffff88800909c000 R12: ffffed100121381a
[ 73.458091][ T11] R13: ffff888009bb0bc0 R14: ffff8880082a1000 R15: ffff88800909c000
[ 73.458519][ T11] FS: 0000000000000000(0000) GS:ffff888035e00000(0000) knlGS:0000000000000000
[ 73.459013][ T11] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 73.459386][ T11] CR2: 00007fff953f97e8 CR3: 000000001c534002 CR4: 0000000000770ef0
[ 73.459820][ T11] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 73.460257][ T11] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 73.460736][ T11] PKRU: 55555554
[ 73.460938][ T11] Kernel panic - not syncing: Fatal exception
[ 73.461347][ T11] Kernel Offset: 0x20e00000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff)
[ 73.461939][ T11] ---[ end Kernel panic - not syncing: Fatal exception ]---
WAIT TIMEOUT stdout
Ctrl-C stdout
Ctrl-C stdout
WAIT TIMEOUT stdout