make -C tools/testing/selftests TARGETS=drivers/net/bonding TEST_PROGS=modde-1-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-1-recovery-updelay.sh
[ 24.598763][ T237] eth0: renamed from lag1
[ 24.749176][ T242] eth0: renamed from lag1-end
[ 25.117601][ T251] eth1: renamed from lag1
[ 25.290526][ T254] eth1: renamed from lag1-end
[ 25.958024][ T256] br0: port 1(eth0) entered blocking state
[ 25.958428][ T256] br0: port 1(eth0) entered disabled state
[ 25.958767][ T256] eth0: entered allmulticast mode
[ 25.960103][ T256] eth0: entered promiscuous mode
[ 26.320424][ T257] br0: port 2(eth1) entered blocking state
[ 26.320768][ T257] br0: port 2(eth1) entered disabled state
[ 26.321101][ T257] eth1: entered allmulticast mode
[ 26.322781][ T257] eth1: entered promiscuous mode
[ 28.009691][ T263] bond0: (slave eth0): making interface the new active one
[ 28.011027][ T263] bond0: (slave eth0): Enslaving as an active interface with an up link
[ 28.017706][ T48] br0: port 1(eth0) entered blocking state
[ 28.018108][ T48] br0: port 1(eth0) entered forwarding state
[ 28.404282][ T264] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 28.408555][ T47] br0: port 2(eth1) entered blocking state
[ 28.408892][ T47] br0: port 2(eth1) entered forwarding state
[ 28.442369][ T48] ------------[ cut here ]------------
[ 28.442921][ T48] UBSAN: invalid-load in ./include/linux/skbuff.h:4267:9
[ 28.443476][ T48] load of value 107 is not a valid value for type '_Bool'
[ 28.444076][ T48] CPU: 2 PID: 48 Comm: kworker/2:1 Not tainted 6.8.0-rc4-virtme #1
[ 28.444695][ T48] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 28.445712][ T48] Workqueue: mld mld_ifc_work
[ 28.446107][ T48] Call Trace:
[ 28.446408][ T48]
[ 28.446652][ T48] dump_stack_lvl+0x92/0xb0
[ 28.447008][ T48] __ubsan_handle_load_invalid_value+0xa7/0xe0
[ 28.447513][ T48] ? __copy_skb_header+0x306/0x3e0
[ 28.447955][ T48] __br_forward.cold+0xf/0x14
[ 28.448367][ T48] deliver_clone+0x52/0x90
[ 28.448756][ T48] maybe_deliver+0x14f/0x240
[ 28.449147][ T48] br_flood+0xf7/0x310
[ 28.449539][ T48] br_dev_xmit+0x6b0/0x10f0
[ 28.449944][ T48] ? __pfx_br_dev_xmit+0x10/0x10
[ 28.450371][ T48] ? __dev_queue_xmit+0x1e9/0x1790
[ 28.450823][ T48] ? lock_acquire.part.0+0xe5/0x330
[ 28.451276][ T48] ? __pfx_skb_network_protocol+0x10/0x10
[ 28.451762][ T48] ? __pfx_qdisc_pkt_len_init+0x10/0x10
[ 28.452213][ T48] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 28.452750][ T48] dev_hard_start_xmit+0x13d/0x570
[ 28.453199][ T48] __dev_queue_xmit+0xea3/0x1790
[ 28.453608][ T48] ? mark_held_locks+0xa5/0xf0
[ 28.454027][ T48] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.454573][ T48] ? __pfx___dev_queue_xmit+0x10/0x10
[ 28.455050][ T48] ip6_finish_output2+0x53f/0xf00
[ 28.455505][ T48] ip6_finish_output.constprop.0+0x284/0xa40
[ 28.456015][ T48] mld_sendpack+0x64a/0xee0
[ 28.456375][ T48] ? __pfx_mld_sendpack+0x10/0x10
[ 28.456793][ T48] ? mld_send_cr+0x3a1/0x780
[ 28.457164][ T48] mld_ifc_work+0x36/0x200
[ 28.457517][ T48] process_one_work+0x78c/0x1310
[ 28.457926][ T48] ? hlock_class+0x4e/0x130
[ 28.458280][ T48] ? __pfx_process_one_work+0x10/0x10
[ 28.458719][ T48] ? assign_work+0x16c/0x240
[ 28.459092][ T48] worker_thread+0x73d/0x1010
[ 28.459457][ T48] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.459979][ T48] ? __pfx_worker_thread+0x10/0x10
[ 28.460411][ T48] ? __pfx_worker_thread+0x10/0x10
[ 28.460822][ T48] kthread+0x28f/0x360
[ 28.461167][ T48] ? __pfx_kthread+0x10/0x10
[ 28.461580][ T48] ret_from_fork+0x31/0x70
[ 28.461963][ T48] ? __pfx_kthread+0x10/0x10
[ 28.462345][ T48] ret_from_fork_asm+0x1b/0x30
[ 28.462802][ T48]
[ 28.463113][ T48] ---[ end trace ]---
[ 28.463515][ T48] ------------[ cut here ]------------
[ 28.464021][ T48] UBSAN: invalid-load in ./include/linux/skbuff.h:4267:9
[ 28.464656][ T48] load of value 107 is not a valid value for type '_Bool'
[ 28.465259][ T48] CPU: 2 PID: 48 Comm: kworker/2:1 Not tainted 6.8.0-rc4-virtme #1
[ 28.465902][ T48] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 28.466938][ T48] Workqueue: mld mld_ifc_work
[ 28.467354][ T48] Call Trace:
[ 28.467660][ T48]
[ 28.467927][ T48] dump_stack_lvl+0x92/0xb0
[ 28.468304][ T48] __ubsan_handle_load_invalid_value+0xa7/0xe0
[ 28.468835][ T48] skb_scrub_packet.cold+0x10/0x15
[ 28.469291][ T48] __dev_forward_skb2+0x332/0x650
[ 28.469723][ T48] veth_xmit+0x23f/0x7b0
[ 28.470069][ T48] ? __pfx_passthru_features_check+0x10/0x10
[ 28.470611][ T48] dev_hard_start_xmit+0x13d/0x570
[ 28.471076][ T48] __dev_queue_xmit+0xea3/0x1790
[ 28.471491][ T48] ? _printk+0xb7/0xf0
[ 28.471856][ T48] ? __pfx___dev_queue_xmit+0x10/0x10
[ 28.472365][ T48] br_dev_queue_push_xmit+0x114/0x240
[ 28.472829][ T48] ? __pfx_br_dev_queue_push_xmit+0x10/0x10
[ 28.473332][ T48] ? __copy_skb_header+0x306/0x3e0
[ 28.473785][ T48] ? __br_forward.cold+0xf/0x14
[ 28.474212][ T48] deliver_clone+0x52/0x90
[ 28.474598][ T48] maybe_deliver+0x14f/0x240
[ 28.475009][ T48] br_flood+0xf7/0x310
[ 28.475402][ T48] br_dev_xmit+0x6b0/0x10f0
[ 28.475782][ T48] ? __pfx_br_dev_xmit+0x10/0x10
[ 28.476213][ T48] ? __dev_queue_xmit+0x1e9/0x1790
[ 28.476683][ T48] ? lock_acquire.part.0+0xe5/0x330
[ 28.477101][ T48] ? __pfx_skb_network_protocol+0x10/0x10
[ 28.477553][ T48] ? __pfx_qdisc_pkt_len_init+0x10/0x10
[ 28.477986][ T48] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 28.478445][ T48] dev_hard_start_xmit+0x13d/0x570
[ 28.478870][ T48] __dev_queue_xmit+0xea3/0x1790
[ 28.479267][ T48] ? mark_held_locks+0xa5/0xf0
[ 28.479653][ T48] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.480154][ T48] ? __pfx___dev_queue_xmit+0x10/0x10
[ 28.480612][ T48] ip6_finish_output2+0x53f/0xf00
[ 28.481053][ T48] ip6_finish_output.constprop.0+0x284/0xa40
[ 28.481574][ T48] mld_sendpack+0x64a/0xee0
[ 28.482014][ T48] ? __pfx_mld_sendpack+0x10/0x10
[ 28.482492][ T48] ? mld_send_cr+0x3a1/0x780
[ 28.482898][ T48] mld_ifc_work+0x36/0x200
[ 28.483184][ T48] process_one_work+0x78c/0x1310
[ 28.483457][ T48] ? hlock_class+0x4e/0x130
[ 28.483746][ T48] ? __pfx_process_one_work+0x10/0x10
[ 28.484060][ T48] ? assign_work+0x16c/0x240
[ 28.484352][ T48] worker_thread+0x73d/0x1010
[ 28.484643][ T48] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 28.485032][ T48] ? __pfx_worker_thread+0x10/0x10
[ 28.485303][ T48] ? __pfx_worker_thread+0x10/0x10
[ 28.485600][ T48] kthread+0x28f/0x360
[ 28.485858][ T48] ? __pfx_kthread+0x10/0x10
[ 28.486146][ T48] ret_from_fork+0x31/0x70
[ 28.486380][ T48] ? __pfx_kthread+0x10/0x10
[ 28.486682][ T48] ret_from_fork_asm+0x1b/0x30
[ 28.486955][ T48]
[ 28.487147][ T48] ---[ end trace ]---
[ 30.985490][ T269] br0: port 1(eth0) entered disabled state
[ 31.019291][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 31.020000][ T11] bond0: (slave eth1): making interface the new active one
[ 33.507354][ T48] br0: port 1(eth0) entered blocking state
[ 33.507827][ T48] br0: port 1(eth0) entered forwarding state
[ 33.521595][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 33.839490][ T272] br0: port 2(eth1) entered disabled state
[ 33.937834][ T11] bond0: (slave eth1): link status definitely down, disabling slave
[ 33.938580][ T11] bond0: (slave eth0): making interface the new active one
# TEST: mode (1) bond recovery [ OK ]
[ 35.657732][ T276] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 35.671835][ T276] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 35.683272][ T276] bond0 (unregistering): Released all slaves
[ 35.704771][ T273] br0: port 1(eth0) entered disabled state
[ 37.715411][ T283] bond0: (slave eth0): Enslaving as a backup interface with an up link
[ 37.716141][ T273] br0: port 1(eth0) entered blocking state
[ 37.716474][ T273] br0: port 1(eth0) entered forwarding state
[ 38.061164][ T284] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 38.063517][ T47] br0: port 2(eth1) entered blocking state
[ 38.063848][ T47] br0: port 2(eth1) entered forwarding state
[ 38.401981][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 38.402784][ T11] bond0: (slave eth0): making interface the new active one
[ 38.404714][ T11] bond0: active interface up!
[ 38.506470][ T11] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 40.540801][ T290] br0: port 1(eth0) entered disabled state
[ 40.585661][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 40.586119][ T11] bond0: (slave eth1): making interface the new active one
[ 43.025972][ T273] br0: port 1(eth0) entered blocking state
[ 43.026506][ T273] br0: port 1(eth0) entered forwarding state
[ 43.081262][ T11] bond0: (slave eth0): link status up, enabling it in 200 ms
[ 43.081803][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 43.289522][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 43.372916][ T293] br0: port 2(eth1) entered disabled state
[ 43.393736][ T11] bond0: (slave eth1): link status definitely down, disabling slave
[ 43.394257][ T11] bond0: (slave eth0): making interface the new active one
# TEST: mode (1) bond recovery [ OK ]
[ 45.201709][ T296] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 45.217877][ T296] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 45.227551][ T296] bond0 (unregistering): Released all slaves
[ 45.251153][ T47] br0: port 1(eth0) entered disabled state
[ 47.432789][ T303] bond0: (slave eth0): Enslaving as a backup interface with an up link
[ 47.436114][ T50] br0: port 1(eth0) entered blocking state
[ 47.436468][ T50] br0: port 1(eth0) entered forwarding state
[ 47.805275][ T305] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 47.806424][ T273] br0: port 2(eth1) entered blocking state
[ 47.806743][ T273] br0: port 2(eth1) entered forwarding state
[ 48.223656][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 48.224155][ T11] bond0: (slave eth0): making interface the new active one
[ 48.225104][ T11] bond0: active interface up!
[ 48.641581][ T286] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 50.307859][ T310] br0: port 1(eth0) entered disabled state
[ 50.409747][ T286] bond0: (slave eth0): link status definitely down, disabling slave
[ 50.410444][ T286] bond0: (slave eth1): making interface the new active one
[ 52.799272][ T50] br0: port 1(eth0) entered blocking state
[ 52.799644][ T50] br0: port 1(eth0) entered forwarding state
[ 52.801365][ T286] bond0: (slave eth0): link status up, enabling it in 500 ms
[ 52.802059][ T286] bond0: (slave eth0): invalid new link 3 on slave
[ 53.128159][ T313] br0: port 2(eth1) entered disabled state
[ 53.217945][ T286] bond0: (slave eth1): link status definitely down, disabling slave
[ 53.218716][ T286] bond0: (slave eth0): making interface the new active one 500 ms earlier
# TEST: mode (1) bond recovery [ OK ]
[ 54.977089][ T316] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 54.986970][ T316] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 54.999063][ T316] bond0 (unregistering): Released all slaves
[ 55.023464][ T273] br0: port 1(eth0) entered disabled state
[ 57.085093][ T323] bond0: (slave eth0): Enslaving as a backup interface with an up link
[ 57.086301][ T273] br0: port 1(eth0) entered blocking state
[ 57.086610][ T273] br0: port 1(eth0) entered forwarding state
[ 57.393258][ T324] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 57.403064][ T236] br0: port 2(eth1) entered blocking state
[ 57.403489][ T236] br0: port 2(eth1) entered forwarding state
[ 57.704810][ T286] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 57.705399][ T286] bond0: (slave eth0): making interface the new active one
[ 57.712563][ T286] bond0: active interface up!
[ 58.649653][ T286] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 59.907189][ T329] br0: port 1(eth0) entered disabled state
[ 60.002864][ T286] bond0: (slave eth0): link status definitely down, disabling slave
[ 60.003557][ T286] bond0: (slave eth1): making interface the new active one
[ 62.410974][ T50] br0: port 1(eth0) entered blocking state
[ 62.411515][ T50] br0: port 1(eth0) entered forwarding state
[ 62.506259][ T286] bond0: (slave eth0): link status up, enabling it in 1000 ms
[ 62.506801][ T286] bond0: (slave eth0): invalid new link 3 on slave
[ 62.804137][ T332] br0: port 2(eth1) entered disabled state
[ 62.817816][ T286] bond0: (slave eth1): link status definitely down, disabling slave
[ 62.818441][ T286] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery [ OK ]
[ 64.588871][ T335] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 64.598990][ T335] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 64.611416][ T335] bond0 (unregistering): Released all slaves
[ 64.640001][ T47] br0: port 1(eth0) entered disabled state
[ 66.676564][ T342] bond0: (slave eth0): Enslaving as a backup interface with an up link
[ 66.677279][ T273] br0: port 1(eth0) entered blocking state
[ 66.677589][ T273] br0: port 1(eth0) entered forwarding state
[ 67.051854][ T343] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 67.054278][ T273] br0: port 2(eth1) entered blocking state
[ 67.054761][ T273] br0: port 2(eth1) entered forwarding state
[ 67.391712][ T11] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 67.392293][ T11] bond0: (slave eth0): making interface the new active one
[ 67.393214][ T11] bond0: active interface up!
[ 69.369518][ T11] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[ 69.560658][ T348] br0: port 1(eth0) entered disabled state
[ 69.580867][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 69.581557][ T11] bond0: (slave eth1): making interface the new active one
[ 72.098709][ T50] br0: port 1(eth0) entered blocking state
[ 72.099091][ T50] br0: port 1(eth0) entered forwarding state
[ 72.186497][ T11] bond0: (slave eth0): link status up, enabling it in 2000 ms
[ 72.186996][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 72.417013][ T351] br0: port 2(eth1) entered disabled state
[ 72.497889][ T11] bond0: (slave eth1): link status definitely down, disabling slave
[ 72.498591][ T11] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery [ OK ]
[ 74.187699][ T354] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 74.203087][ T354] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 74.214128][ T354] bond0 (unregistering): Released all slaves
[ 74.235723][ T273] br0: port 1(eth0) entered disabled state
[ 76.358746][ T361] bond0: (slave eth0): Enslaving as a backup interface with an up link
[ 76.359454][ T47] br0: port 1(eth0) entered blocking state
[ 76.359780][ T47] br0: port 1(eth0) entered forwarding state
[ 76.699829][ T362] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 76.702109][ T273] br0: port 2(eth1) entered blocking state
[ 76.702598][ T273] br0: port 2(eth1) entered forwarding state
[ 77.047759][ T286] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 77.048306][ T286] bond0: (slave eth0): making interface the new active one
[ 77.049169][ T286] bond0: active interface up!
[ 79.167450][ T367] br0: port 1(eth0) entered disabled state
[ 79.234853][ T11] bond0: (slave eth0): link status definitely down, disabling slave
[ 79.235544][ T11] bond0: (slave eth1): making interface the new active one 2400 ms earlier
[ 81.637690][ T47] br0: port 1(eth0) entered blocking state
[ 81.638036][ T47] br0: port 1(eth0) entered forwarding state
[ 81.737259][ T11] bond0: (slave eth0): link status up, enabling it in 5000 ms
[ 81.738031][ T11] bond0: (slave eth0): invalid new link 3 on slave
[ 81.966894][ T370] br0: port 2(eth1) entered disabled state
[ 82.049756][ T11] bond0: (slave eth1): link status definitely down, disabling slave
[ 82.050294][ T11] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery [ OK ]
[ 83.825013][ T373] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 83.833686][ T373] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 83.842481][ T373] bond0 (unregistering): Released all slaves
[ 83.868673][ T273] br0: port 1(eth0) entered disabled state
[ 86.019073][ T380] bond0: (slave eth0): Enslaving as a backup interface with an up link
[ 86.020388][ T273] br0: port 1(eth0) entered blocking state
[ 86.020704][ T273] br0: port 1(eth0) entered forwarding state
[ 86.460503][ T381] bond0: (slave eth1): Enslaving as a backup interface with an up link
[ 86.462185][ T50] br0: port 2(eth1) entered blocking state
[ 86.462696][ T50] br0: port 2(eth1) entered forwarding state
[ 86.824830][ T286] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[ 86.825640][ T286] bond0: (slave eth0): making interface the new active one
[ 86.826923][ T286] bond0: active interface up!
[ 89.067698][ T386] br0: port 1(eth0) entered disabled state
[ 89.114861][ T286] bond0: (slave eth0): link status definitely down, disabling slave
[ 89.115932][ T286] bond0: (slave eth1): making interface the new active one 2400 ms earlier
[ 91.601837][ T47] br0: port 1(eth0) entered blocking state
[ 91.602374][ T47] br0: port 1(eth0) entered forwarding state
[ 91.618259][ T286] bond0: (slave eth0): link status up, enabling it in 10000 ms
[ 91.618745][ T286] bond0: (slave eth0): invalid new link 3 on slave
[ 91.995175][ T389] br0: port 2(eth1) entered disabled state
[ 92.033791][ T286] bond0: (slave eth1): link status definitely down, disabling slave
[ 92.034524][ T286] bond0: (slave eth0): making interface the new active one 500 ms earlier
# TEST: mode (1) bond recovery [ OK ]
[ 93.878162][ T392] bond0 (unregistering): (slave eth0): Releasing backup interface
[ 93.889190][ T392] bond0 (unregistering): (slave eth1): Releasing backup interface
[ 93.900652][ T392] bond0 (unregistering): Released all slaves
[ 93.930979][ T47] br0: port 1(eth0) entered disabled state
[ 94.797345][ T286] eth1: left allmulticast mode
[ 94.797652][ T286] eth1: left promiscuous mode
[ 94.798058][ T286] br0: port 2(eth1) entered disabled state
[ 94.800720][ T286] eth0: left allmulticast mode
[ 94.801147][ T286] eth0: left promiscuous mode
[ 94.801733][ T286] br0: port 1(eth0) entered disabled state
ok 1 selftests: drivers/net/bonding: mode-1-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__->