make -C tools/testing/selftests TARGETS=drivers/net/bonding TEST_PROGS=modde-2-recovery-updelay.sh TEST_GEN_PROGS="" run_tests
make: Entering directory '/home/virtme/testing-10/tools/testing/selftests'
make[1]: Entering directory '/home/virtme/testing-10/tools/testing/selftests/drivers/net/bonding'
make[1]: Nothing to be done for 'all'.
make[1]: Leaving directory '/home/virtme/testing-10/tools/testing/selftests/drivers/net/bonding'
make[1]: Entering directory '/home/virtme/testing-10/tools/testing/selftests/drivers/net/bonding'
TAP version 13
1..1
# overriding timeout to 2400
# selftests: drivers/net/bonding: mode-2-recovery-updelay.sh
[ 24.128725][ T235] ip (235) used greatest stack depth: 23280 bytes left
[ 24.291690][ T237] eth0: renamed from lag1
[ 24.487551][ T242] eth0: renamed from lag1-end
[ 24.894489][ T250] eth1: renamed from lag1
[ 25.047996][ T253] eth1: renamed from lag1-end
[ 25.750291][ T256] br0: port 1(eth0) entered blocking state
[ 25.750728][ T256] br0: port 1(eth0) entered disabled state
[ 25.751076][ T256] eth0: entered allmulticast mode
[ 25.752501][ T256] eth0: entered promiscuous mode
[ 26.074336][ T257] br0: port 2(eth1) entered blocking state
[ 26.074705][ T257] br0: port 2(eth1) entered disabled state
[ 26.075071][ T257] eth1: entered allmulticast mode
[ 26.077087][ T257] eth1: entered promiscuous mode
[ 27.935329][ T263] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 27.939489][ T47] br0: port 1(eth0) entered blocking state
[ 27.939885][ T47] br0: port 1(eth0) entered forwarding state
[ 28.422314][ T264] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 28.424746][ T48] br0: port 2(eth1) entered blocking state
[ 28.425219][ T48] br0: port 2(eth1) entered forwarding state
[ 28.469793][ T47] ------------[ cut here ]------------
[ 28.470179][ T47] UBSAN: invalid-load in ./include/linux/skbuff.h:4267:9
[ 28.470584][ T47] load of value 107 is not a valid value for type '_Bool'
[ 28.471003][ T47] CPU: 2 PID: 47 Comm: kworker/2:1 Not tainted 6.8.0-rc4-virtme #1
[ 28.471429][ T47] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 28.472096][ T47] Workqueue: mld mld_ifc_work
[ 28.472350][ T47] Call Trace:
[ 28.472542][ T47]
[ 28.472710][ T47] dump_stack_lvl+0x92/0xb0
[ 28.472974][ T47] __ubsan_handle_load_invalid_value+0xa7/0xe0
[ 28.473326][ T47] ? __copy_skb_header+0x306/0x3e0
[ 28.473622][ T47] __br_forward.cold+0xf/0x14
[ 28.473944][ T47] deliver_clone+0x52/0x90
[ 28.474274][ T47] maybe_deliver+0x14f/0x240
[ 28.474594][ T47] br_flood+0xf7/0x310
[ 28.474931][ T47] br_dev_xmit+0x6b0/0x10f0
[ 28.475277][ T47] ? __pfx_br_dev_xmit+0x10/0x10
[ 28.475664][ T47] ? __dev_queue_xmit+0x1e9/0x1790
[ 28.476064][ T47] ? lock_acquire.part.0+0xe5/0x330
[ 28.476485][ T47] ? __pfx_skb_network_protocol+0x10/0x10
[ 28.476927][ T47] ? __pfx_qdisc_pkt_len_init+0x10/0x10
[ 28.477352][ T47] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 28.477795][ T47] dev_hard_start_xmit+0x13d/0x570
[ 28.478208][ T47] __dev_queue_xmit+0xea3/0x1790
[ 28.478595][ T47] ? mark_held_locks+0xa5/0xf0
[ 28.478973][ T47] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.479480][ T47] ? __pfx___dev_queue_xmit+0x10/0x10
[ 28.479922][ T47] ip6_finish_output2+0x53f/0xf00
[ 28.480333][ T47] ip6_finish_output.constprop.0+0x284/0xa40
[ 28.480801][ T47] mld_sendpack+0x64a/0xee0
[ 28.481171][ T47] ? __pfx_mld_sendpack+0x10/0x10
[ 28.481656][ T47] ? mld_send_cr+0x3a1/0x780
[ 28.482076][ T47] mld_ifc_work+0x36/0x200
[ 28.482465][ T47] process_one_work+0x78c/0x1310
[ 28.482888][ T47] ? hlock_class+0x4e/0x130
[ 28.483311][ T47] ? __pfx_process_one_work+0x10/0x10
[ 28.483791][ T47] ? assign_work+0x16c/0x240
[ 28.484167][ T47] worker_thread+0x73d/0x1010
[ 28.484583][ T47] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.485173][ T47] ? __pfx_worker_thread+0x10/0x10
[ 28.485600][ T47] ? __pfx_worker_thread+0x10/0x10
[ 28.486034][ T47] kthread+0x28f/0x360
[ 28.486390][ T47] ? __pfx_kthread+0x10/0x10
[ 28.486815][ T47] ret_from_fork+0x31/0x70
[ 28.487218][ T47] ? __pfx_kthread+0x10/0x10
[ 28.487632][ T47] ret_from_fork_asm+0x1b/0x30
[ 28.488074][ T47]
[ 28.488378][ T47] ---[ end trace ]---
[ 28.488731][ T47] ------------[ cut here ]------------
[ 28.489299][ T47] UBSAN: invalid-load in ./include/linux/skbuff.h:4267:9
[ 28.489855][ T47] load of value 107 is not a valid value for type '_Bool'
[ 28.490458][ T47] CPU: 2 PID: 47 Comm: kworker/2:1 Not tainted 6.8.0-rc4-virtme #1
[ 28.490893][ T47] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 28.491572][ T47] Workqueue: mld mld_ifc_work
[ 28.491878][ T47] Call Trace:
[ 28.492052][ T47]
[ 28.492246][ T47] dump_stack_lvl+0x92/0xb0
[ 28.492483][ T47] __ubsan_handle_load_invalid_value+0xa7/0xe0
[ 28.492844][ T47] skb_scrub_packet.cold+0x10/0x15
[ 28.493139][ T47] __dev_forward_skb2+0x332/0x650
[ 28.493454][ T47] veth_xmit+0x23f/0x7b0
[ 28.493775][ T47] ? __pfx_passthru_features_check+0x10/0x10
[ 28.494106][ T47] dev_hard_start_xmit+0x13d/0x570
[ 28.494457][ T47] __dev_queue_xmit+0xea3/0x1790
[ 28.494818][ T47] ? _printk+0xb7/0xf0
[ 28.495044][ T47] ? __pfx___dev_queue_xmit+0x10/0x10
[ 28.495406][ T47] br_dev_queue_push_xmit+0x114/0x240
[ 28.495722][ T47] ? __pfx_br_dev_queue_push_xmit+0x10/0x10
[ 28.496084][ T47] ? __copy_skb_header+0x306/0x3e0
[ 28.496421][ T47] ? __br_forward.cold+0xf/0x14
[ 28.496761][ T47] deliver_clone+0x52/0x90
[ 28.497014][ T47] maybe_deliver+0x14f/0x240
[ 28.497313][ T47] br_flood+0xf7/0x310
[ 28.497552][ T47] br_dev_xmit+0x6b0/0x10f0
[ 28.497885][ T47] ? __pfx_br_dev_xmit+0x10/0x10
[ 28.498203][ T47] ? __dev_queue_xmit+0x1e9/0x1790
[ 28.498594][ T47] ? lock_acquire.part.0+0xe5/0x330
[ 28.498979][ T47] ? __pfx_skb_network_protocol+0x10/0x10
[ 28.499421][ T47] ? __pfx_qdisc_pkt_len_init+0x10/0x10
[ 28.499845][ T47] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 28.500292][ T47] dev_hard_start_xmit+0x13d/0x570
[ 28.500710][ T47] __dev_queue_xmit+0xea3/0x1790
[ 28.501099][ T47] ? mark_held_locks+0xa5/0xf0
[ 28.501481][ T47] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.501981][ T47] ? __pfx___dev_queue_xmit+0x10/0x10
[ 28.502432][ T47] ip6_finish_output2+0x53f/0xf00
[ 28.502866][ T47] ip6_finish_output.constprop.0+0x284/0xa40
[ 28.503362][ T47] mld_sendpack+0x64a/0xee0
[ 28.503781][ T47] ? __pfx_mld_sendpack+0x10/0x10
[ 28.504278][ T47] ? mld_send_cr+0x3a1/0x780
[ 28.504674][ T47] mld_ifc_work+0x36/0x200
[ 28.505080][ T47] process_one_work+0x78c/0x1310
[ 28.505525][ T47] ? hlock_class+0x4e/0x130
[ 28.505932][ T47] ? __pfx_process_one_work+0x10/0x10
[ 28.506412][ T47] ? assign_work+0x16c/0x240
[ 28.506870][ T47] worker_thread+0x73d/0x1010
[ 28.507287][ T47] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.507875][ T47] ? __pfx_worker_thread+0x10/0x10
[ 28.508331][ T47] ? __pfx_worker_thread+0x10/0x10
[ 28.508769][ T47] kthread+0x28f/0x360
[ 28.509120][ T47] ? __pfx_kthread+0x10/0x10
[ 28.509534][ T47] ret_from_fork+0x31/0x70
[ 28.509897][ T47] ? __pfx_kthread+0x10/0x10
[ 28.510306][ T47] ret_from_fork_asm+0x1b/0x30
[ 28.510776][ T47]
[ 28.511105][ T47] ---[ end trace ]---
[ 31.146203][ T270] br0: port 1(eth0) entered disabled state
[ 31.243866][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 33.708496][ T271] br0: port 1(eth0) entered blocking state
[ 33.709055][ T271] br0: port 1(eth0) entered forwarding state
[ 33.740557][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 34.051870][ T274] br0: port 2(eth1) entered disabled state
[ 34.054575][ T11] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 35.904651][ T277] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 35.914837][ T277] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 35.926307][ T277] bond0 (unregistering): Released all slaves
[ 35.949315][ T46] br0: port 1(eth0) entered disabled state
[ 38.082066][ T284] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 38.088358][ T8] br0: port 1(eth0) entered blocking state
[ 38.088690][ T8] br0: port 1(eth0) entered forwarding state
[ 38.399659][ T285] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 38.400873][ T47] br0: port 2(eth1) entered blocking state
[ 38.401188][ T47] br0: port 2(eth1) entered forwarding state
[ 38.722914][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 38.723701][ T11] bond0: active interface up!
[ 38.827770][ T11] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 40.946422][ T290] br0: port 1(eth0) entered disabled state
[ 41.011582][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 43.457038][ T48] br0: port 1(eth0) entered blocking state
[ 43.457428][ T48] br0: port 1(eth0) entered forwarding state
[ 43.507329][ T11] bond0: (slave eth0): link status up, enabling it in 200 ms
[ 43.508100][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 43.715866][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 43.774953][ T293] br0: port 2(eth1) entered disabled state
[ 43.819678][ T11] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 45.689008][ T296] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 45.700912][ T296] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 45.712411][ T296] bond0 (unregistering): Released all slaves
[ 45.734167][ T48] br0: port 1(eth0) entered disabled state
[ 47.833999][ T303] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 47.840398][ T8] br0: port 1(eth0) entered blocking state
[ 47.840734][ T8] br0: port 1(eth0) entered forwarding state
[ 48.228718][ T304] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 48.230723][ T47] br0: port 2(eth1) entered blocking state
[ 48.231047][ T47] br0: port 2(eth1) entered forwarding state
[ 48.536839][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 48.537600][ T11] bond0: active interface up!
[ 48.955546][ T11] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 50.743526][ T309] br0: port 1(eth0) entered disabled state
[ 50.827574][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 53.272782][ T48] br0: port 1(eth0) entered blocking state
[ 53.273145][ T48] br0: port 1(eth0) entered forwarding state
[ 53.323331][ T11] bond0: (slave eth0): link status up, enabling it in 500 ms
[ 53.323962][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 53.579228][ T312] br0: port 2(eth1) entered disabled state
[ 53.635608][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 53.636350][ T11] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 55.415051][ T315] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 55.428873][ T315] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 55.440222][ T315] bond0 (unregistering): Released all slaves
[ 55.460780][ T46] br0: port 1(eth0) entered disabled state
[ 57.571072][ T322] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 57.572831][ T236] br0: port 1(eth0) entered blocking state
[ 57.573312][ T236] br0: port 1(eth0) entered forwarding state
[ 57.908941][ T323] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 57.910160][ T48] br0: port 2(eth1) entered blocking state
[ 57.910493][ T48] br0: port 2(eth1) entered forwarding state
[ 58.258901][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 58.259779][ T11] bond0: active interface up!
[ 59.200895][ T269] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 60.451689][ T328] br0: port 1(eth0) entered disabled state
[ 60.453588][ T269] bond0: (slave eth0): link status definitely down, disabling slave
[ 63.016019][ T46] br0: port 1(eth0) entered blocking state
[ 63.016580][ T46] br0: port 1(eth0) entered forwarding state
[ 63.051295][ T269] bond0: (slave eth0): link status up, enabling it in 1000 ms
[ 63.051869][ T269] bond0: (slave eth0): invalid new link 3 on slave
[ 63.347417][ T331] br0: port 2(eth1) entered disabled state
[ 63.363547][ T269] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 63.364435][ T269] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 65.154237][ T334] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 65.167978][ T334] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 65.179197][ T334] bond0 (unregistering): Released all slaves
[ 65.198213][ T46] br0: port 1(eth0) entered disabled state
[ 67.219515][ T341] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 67.220270][ T236] br0: port 1(eth0) entered blocking state
[ 67.220580][ T236] br0: port 1(eth0) entered forwarding state
[ 67.564190][ T342] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 67.565470][ T8] br0: port 2(eth1) entered blocking state
[ 67.565812][ T8] br0: port 2(eth1) entered forwarding state
[ 67.879913][ T269] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 67.880777][ T269] bond0: active interface up!
[ 69.860548][ T269] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 70.013001][ T347] br0: port 1(eth0) entered disabled state
[ 70.068650][ T269] bond0: (slave eth0): link status definitely down, disabling slave
[ 72.482273][ T8] br0: port 1(eth0) entered blocking state
[ 72.482683][ T8] br0: port 1(eth0) entered forwarding state
[ 72.563375][ T269] bond0: (slave eth0): link status up, enabling it in 2000 ms
[ 72.564107][ T269] bond0: (slave eth0): invalid new link 3 on slave
[ 72.787413][ T350] br0: port 2(eth1) entered disabled state
[ 72.875637][ T269] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 72.876391][ T269] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 74.531990][ T353] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 74.543793][ T353] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 74.555144][ T353] bond0 (unregistering): Released all slaves
[ 74.574665][ T236] br0: port 1(eth0) entered disabled state
[ 76.623004][ T360] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 76.626542][ T271] br0: port 1(eth0) entered blocking state
[ 76.626872][ T271] br0: port 1(eth0) entered forwarding state
[ 76.937384][ T361] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 76.939275][ T236] br0: port 2(eth1) entered blocking state
[ 76.939597][ T236] br0: port 2(eth1) entered forwarding state
[ 77.240883][ T269] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 77.241760][ T269] bond0: active interface up!
[ 79.376514][ T366] br0: port 1(eth0) entered disabled state
[ 79.427532][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 79.428316][ T11] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 81.805721][ T46] br0: port 1(eth0) entered blocking state
[ 81.806091][ T46] br0: port 1(eth0) entered forwarding state
[ 81.820288][ T11] bond0: (slave eth0): link status up, enabling it in 5000 ms
[ 81.820830][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 82.137992][ T370] br0: port 2(eth1) entered disabled state
[ 82.235517][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 82.236324][ T11] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 83.969199][ T373] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 83.982068][ T373] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 83.997612][ T373] bond0 (unregistering): Released all slaves
[ 84.016523][ T8] br0: port 1(eth0) entered disabled state
[ 86.263214][ T380] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 86.263965][ T271] br0: port 1(eth0) entered blocking state
[ 86.264288][ T271] br0: port 1(eth0) entered forwarding state
[ 86.688019][ T381] bond0: (slave eth1): Enslaving as an active interface with an up link
[ 86.690576][ T8] br0: port 2(eth1) entered blocking state
[ 86.691056][ T8] br0: port 2(eth1) entered forwarding state
[ 86.996835][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 86.997639][ T11] bond0: active interface up!
[ 89.162956][ T386] br0: port 1(eth0) entered disabled state
[ 89.179615][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 89.180683][ T11] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 91.587748][ T236] br0: port 1(eth0) entered blocking state
[ 91.588107][ T236] br0: port 1(eth0) entered forwarding state
[ 91.675363][ T11] bond0: (slave eth0): link status up, enabling it in 10000 ms
[ 91.676114][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 91.885064][ T389] br0: port 2(eth1) entered disabled state
[ 91.987601][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 91.988317][ T11] bond0: (slave eth1): link status definitely down, disabling slave
# TEST: mode (2) bond recovery [ OK ]
[ 93.663539][ T392] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 93.673143][ T392] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 93.689899][ T392] bond0 (unregistering): Released all slaves
[ 93.703292][ T236] br0: port 1(eth0) entered disabled state
[ 94.531188][ T11] eth1: left allmulticast mode
[ 94.531523][ T11] eth1: left promiscuous mode
[ 94.531967][ T11] br0: port 2(eth1) entered disabled state
[ 94.533808][ T11] eth0: left allmulticast mode
[ 94.534101][ T11] eth0: left promiscuous mode
[ 94.534512][ T11] br0: port 1(eth0) entered disabled state
ok 1 selftests: drivers/net/bonding: mode-2-recovery-updelay.sh
make[1]: Leaving directory '/home/virtme/testing-10/tools/testing/selftests/drivers/net/bonding'
make: Leaving directory '/home/virtme/testing-10/tools/testing/selftests'
xx__-> echo $?
0
xx__->