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-9/tools/testing/selftests'
make[1]: Entering directory '/home/virtme/testing-9/tools/testing/selftests/drivers/net/bonding'
make[1]: Nothing to be done for 'all'.
make[1]: Leaving directory '/home/virtme/testing-9/tools/testing/selftests/drivers/net/bonding'
make[1]: Entering directory '/home/virtme/testing-9/tools/testing/selftests/drivers/net/bonding'
TAP version 13
1..1
# timeout set to 1200
# selftests: drivers/net/bonding: mode-1-recovery-updelay.sh
[   38.488980] eth0: renamed from lag1
[   38.521818] eth0: renamed from lag1-end
[   38.572841] eth1: renamed from lag1
[   38.615890] eth1: renamed from lag1-end
[   38.675413] br0: port 1(eth0) entered blocking state
[   38.675753] br0: port 1(eth0) entered disabled state
[   38.676042] eth0: entered allmulticast mode
[   38.676346] eth0: entered promiscuous mode
[   38.703818] br0: port 2(eth1) entered blocking state
[   38.704224] br0: port 2(eth1) entered disabled state
[   38.704602] eth1: entered allmulticast mode
[   38.705018] eth1: entered promiscuous mode
[   38.844999] bond0: (slave eth0): making interface the new active one
[   38.845533] bond0: (slave eth0): Enslaving as an active interface with an up link
[   38.846622] br0: port 1(eth0) entered blocking state
[   38.847034] br0: port 1(eth0) entered forwarding state
[   38.877621] bond0: (slave eth1): Enslaving as a backup interface with an up link
[   38.878299] br0: port 2(eth1) entered blocking state
[   38.878568] br0: port 2(eth1) entered forwarding state
[   40.024544] br0: port 1(eth0) entered disabled state
[   40.047788] bond0: (slave eth0): link status definitely down, disabling slave
[   40.048228] bond0: (slave eth1): making interface the new active one
[   42.059949] br0: port 1(eth0) entered blocking state
[   42.060244] br0: port 1(eth0) entered forwarding state
[   42.085598] br0: port 2(eth1) entered disabled state
[   42.127677] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[   42.128290] bond0: (slave eth1): link status definitely down, disabling slave
[   42.128841] bond0: (slave eth0): making interface the new active one
# TEST: mode (1) bond recovery                                        [ OK ]
[   53.160368] bond0 (unregistering): (slave eth0): Releasing backup interface
[   53.169841] bond0 (unregistering): (slave eth1): Releasing backup interface
[   53.175714] bond0 (unregistering): Released all slaves
[   53.176522] br0: port 1(eth0) entered disabled state
[   53.322070] bond0: (slave eth0): Enslaving as a backup interface with an up link
[   53.322770] br0: port 1(eth0) entered blocking state
[   53.323034] br0: port 1(eth0) entered forwarding state
[   53.346380] bond0: (slave eth1): Enslaving as a backup interface with an up link
[   53.346997] br0: port 2(eth1) entered blocking state
[   53.347286] br0: port 2(eth1) entered forwarding state
[   53.367689] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[   53.368173] bond0: (slave eth0): making interface the new active one
[   53.368743] bond0: active interface up!
[   53.575736] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[   54.489730] br0: port 1(eth0) entered disabled state
[   54.511656] bond0: (slave eth0): link status definitely down, disabling slave
[   54.512059] bond0: (slave eth1): making interface the new active one
[   56.524138] br0: port 1(eth0) entered blocking state
[   56.524431] br0: port 1(eth0) entered forwarding state
[   56.552015] br0: port 2(eth1) entered disabled state
[   56.591638] bond0: (slave eth0): link status up, enabling it in 200 ms
[   56.592061] bond0: (slave eth0): invalid new link 3 on slave
[   56.592401] bond0: (slave eth1): link status definitely down, disabling slave
[   56.592789] bond0: (slave eth0): making interface the new active one 100 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[   67.623728] bond0 (unregistering): (slave eth0): Releasing backup interface
[   67.631816] bond0 (unregistering): (slave eth1): Releasing backup interface
[   67.637692] bond0 (unregistering): Released all slaves
[   67.638564] br0: port 1(eth0) entered disabled state
[   67.811645] bond0: (slave eth0): Enslaving as a backup interface with an up link
[   67.812459] br0: port 1(eth0) entered blocking state
[   67.812862] br0: port 1(eth0) entered forwarding state
[   67.841203] bond0: (slave eth1): Enslaving as a backup interface with an up link
[   67.841916] br0: port 2(eth1) entered blocking state
[   67.842185] br0: port 2(eth1) entered forwarding state
[   67.873291] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[   67.873943] bond0: (slave eth0): making interface the new active one
[   67.874482] bond0: active interface up!
[   68.391687] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[   69.019149] br0: port 1(eth0) entered disabled state
[   69.119694] bond0: (slave eth0): link status definitely down, disabling slave
[   69.120102] bond0: (slave eth1): making interface the new active one
[   71.054241] br0: port 1(eth0) entered blocking state
[   71.054684] br0: port 1(eth0) entered forwarding state
[   71.077941] br0: port 2(eth1) entered disabled state
[   71.095620] bond0: (slave eth0): link status up, enabling it in 500 ms
[   71.096383] bond0: (slave eth0): invalid new link 3 on slave
[   71.096970] bond0: (slave eth1): link status definitely down, disabling slave
[   71.097571] bond0: (slave eth0): making interface the new active one 100 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[   72.150527] bond0 (unregistering): (slave eth0): Releasing backup interface
[   72.161805] bond0 (unregistering): (slave eth1): Releasing backup interface
[   72.167722] bond0 (unregistering): Released all slaves
[   72.168576] br0: port 1(eth0) entered disabled state
[   72.315448] bond0: (slave eth0): Enslaving as a backup interface with an up link
[   72.316099] br0: port 1(eth0) entered blocking state
[   72.316373] br0: port 1(eth0) entered forwarding state
[   72.341151] bond0: (slave eth1): Enslaving as a backup interface with an up link
[   72.342221] br0: port 2(eth1) entered blocking state
[   72.342652] br0: port 2(eth1) entered forwarding state
[   72.366754] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[   72.367205] bond0: (slave eth0): making interface the new active one
[   72.367680] bond0: active interface up!
[   73.407754] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[   73.498345] br0: port 1(eth0) entered disabled state
[   73.511663] bond0: (slave eth0): link status definitely down, disabling slave
[   73.512068] bond0: (slave eth1): making interface the new active one
[   75.530329] br0: port 1(eth0) entered blocking state
[   75.530642] br0: port 1(eth0) entered forwarding state
[   75.557147] br0: port 2(eth1) entered disabled state
[   75.591624] bond0: (slave eth0): link status up, enabling it in 1000 ms
[   75.592047] bond0: (slave eth0): invalid new link 3 on slave
[   75.592379] bond0: (slave eth1): link status definitely down, disabling slave
[   75.592787] bond0: (slave eth0): making interface the new active one 100 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[   86.635338] bond0 (unregistering): (slave eth0): Releasing backup interface
[   86.643913] bond0 (unregistering): (slave eth1): Releasing backup interface
[   86.649766] bond0 (unregistering): Released all slaves
[   86.651107] br0: port 1(eth0) entered disabled state
[   86.803686] bond0: (slave eth0): Enslaving as a backup interface with an up link
[   86.804389] br0: port 1(eth0) entered blocking state
[   86.804671] br0: port 1(eth0) entered forwarding state
[   86.825605] bond0: (slave eth1): Enslaving as a backup interface with an up link
[   86.826062] br0: port 2(eth1) entered blocking state
[   86.826321] br0: port 2(eth1) entered forwarding state
[   86.847580] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[   86.848060] bond0: (slave eth0): making interface the new active one
[   86.848531] bond0: active interface up!
[   87.962455] br0: port 1(eth0) entered disabled state
[   87.991656] bond0: (slave eth0): link status definitely down, disabling slave
[   87.992068] bond0: (slave eth1): making interface the new active one 1200 ms earlier
[   89.997897] br0: port 1(eth0) entered blocking state
[   89.998323] br0: port 1(eth0) entered forwarding state
[   90.020000] br0: port 2(eth1) entered disabled state
[   90.071653] bond0: (slave eth0): link status up, enabling it in 2000 ms
[   90.072232] bond0: (slave eth0): invalid new link 3 on slave
[   90.072645] bond0: (slave eth1): link status definitely down, disabling slave
[   90.073012] bond0: (slave eth0): making interface the new active one 100 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  101.099957] bond0 (unregistering): (slave eth0): Releasing backup interface
[  101.109892] bond0 (unregistering): (slave eth1): Releasing backup interface
[  101.115772] bond0 (unregistering): Released all slaves
[  101.117097] br0: port 1(eth0) entered disabled state
[  101.278949] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  101.279626] br0: port 1(eth0) entered blocking state
[  101.279891] br0: port 1(eth0) entered forwarding state
[  101.305903] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  101.306573] br0: port 2(eth1) entered blocking state
[  101.306862] br0: port 2(eth1) entered forwarding state
[  101.332803] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  101.333247] bond0: (slave eth0): making interface the new active one
[  101.333696] bond0: active interface up!
[  102.426842] br0: port 1(eth0) entered disabled state
[  102.479673] bond0: (slave eth0): link status definitely down, disabling slave
[  102.480261] bond0: (slave eth1): making interface the new active one 1300 ms earlier
[  104.460047] br0: port 1(eth0) entered blocking state
[  104.460335] br0: port 1(eth0) entered forwarding state
[  104.486479] br0: port 2(eth1) entered disabled state
[  104.559646] bond0: (slave eth0): link status up, enabling it in 5000 ms
[  104.560057] bond0: (slave eth0): invalid new link 3 on slave
[  104.560385] bond0: (slave eth1): link status definitely down, disabling slave
[  104.560771] bond0: (slave eth0): making interface the new active one 100 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  115.560360] bond0 (unregistering): (slave eth0): Releasing backup interface
[  115.569810] bond0 (unregistering): (slave eth1): Releasing backup interface
[  115.575714] bond0 (unregistering): Released all slaves
[  115.576571] br0: port 1(eth0) entered disabled state
[  115.731402] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  115.732355] br0: port 1(eth0) entered blocking state
[  115.732775] br0: port 1(eth0) entered forwarding state
[  115.756484] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  115.757212] br0: port 2(eth1) entered blocking state
[  115.757489] br0: port 2(eth1) entered forwarding state
[  115.782348] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  115.783170] bond0: (slave eth0): making interface the new active one
[  115.783953] bond0: active interface up!
[  116.892277] br0: port 1(eth0) entered disabled state
[  116.927669] bond0: (slave eth0): link status definitely down, disabling slave
[  116.928109] bond0: (slave eth1): making interface the new active one 1200 ms earlier
[  118.929502] br0: port 1(eth0) entered blocking state
[  118.929806] br0: port 1(eth0) entered forwarding state
[  118.954141] br0: port 2(eth1) entered disabled state
[  119.007640] bond0: (slave eth0): link status up, enabling it in 10000 ms
[  119.008111] bond0: (slave eth0): invalid new link 3 on slave
[  119.008480] bond0: (slave eth1): link status definitely down, disabling slave
[  119.008891] bond0: (slave eth0): making interface the new active one 100 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  130.024104] bond0 (unregistering): (slave eth0): Releasing backup interface
[  130.032857] bond0 (unregistering): (slave eth1): Releasing backup interface
[  130.038773] bond0 (unregistering): Released all slaves
[  130.040057] br0: port 1(eth0) entered disabled state
# modprobe: FATAL: Module bonding is builtin.
[  130.140636] eth1: left allmulticast mode
ok 1 selftests: [  130.140954] eth1: left promiscuous mode
drivers/net/bonding: mode-1-recovery-updelay.sh
[  130.141777] br0: port 2(eth1) entered disabled state
make[1]: Leaving directory '/home/virtme/testing-9/tools/testing/selftests/drivers/net/bonding'
[  130.143031] eth0: left allmulticast mode
[  130.143385] eth0: left promiscuous mode
[  130.143661] br0: port 1(eth0) entered disabled state
make: Leaving directory '/home/virtme/testing-9/tools/testing/selftests'
xx__-> echo $?
0
xx__->