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
[  846.947061][ T6243] eth0: renamed from lag1
[  847.128671][ T6248] eth0: renamed from lag1-end
[  847.614361][ T6258] eth1: renamed from lag1
[  847.820293][ T6260] eth1: renamed from lag1-end
[  848.547913][ T6262] br0: port 1(eth0) entered blocking state
[  848.548305][ T6262] br0: port 1(eth0) entered disabled state
[  848.548650][ T6262] eth0: entered allmulticast mode
[  848.550054][ T6262] eth0: entered promiscuous mode
[  848.902421][ T6263] br0: port 2(eth1) entered blocking state
[  848.902859][ T6263] br0: port 2(eth1) entered disabled state
[  848.903519][ T6263] eth1: entered allmulticast mode
[  848.905345][ T6263] eth1: entered promiscuous mode
[  850.668126][ T6269] bond0: (slave eth0): making interface the new active one
[  850.668867][ T6269] bond0: (slave eth0): Enslaving as an active interface with an up link
[  850.670112][   T68] br0: port 1(eth0) entered blocking state
[  850.670532][   T68] br0: port 1(eth0) entered forwarding state
[  851.031933][ T6270] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  851.034010][  T262] br0: port 2(eth1) entered blocking state
[  851.034460][  T262] br0: port 2(eth1) entered forwarding state
[  853.536867][ T6275] br0: port 1(eth0) entered disabled state
[  853.609309][ T4229] bond0: (slave eth0): link status definitely down, disabling slave
[  853.610119][ T4229] bond0: (slave eth1): making interface the new active one
[  856.050784][   T68] br0: port 1(eth0) entered blocking state
[  856.051164][   T68] br0: port 1(eth0) entered forwarding state
[  856.111515][ T4229] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  856.372669][ T6278] br0: port 2(eth1) entered disabled state
[  856.423745][ T4229] bond0: (slave eth1): link status definitely down, disabling slave
[  856.424367][ T4229] bond0: (slave eth0): making interface the new active one
# TEST: mode (1) bond recovery                                        [ OK ]
[  858.187949][ T6281] bond0 (unregistering): (slave eth0): Releasing backup interface
[  858.207291][ T6281] bond0 (unregistering): (slave eth1): Releasing backup interface
[  858.213982][ T6281] bond0 (unregistering): Released all slaves
[  858.227944][   T68] br0: port 1(eth0) entered disabled state
[  860.300904][ T6288] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  860.302494][  T262] br0: port 1(eth0) entered blocking state
[  860.302908][  T262] br0: port 1(eth0) entered forwarding state
[  860.623860][ T6289] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  860.625080][ T6189] br0: port 2(eth1) entered blocking state
[  860.625410][ T6189] br0: port 2(eth1) entered forwarding state
[  861.003723][ T4229] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  861.004488][ T4229] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[  861.004953][ T4229] bond0: (slave eth0): making interface the new active one
[  861.006020][ T4229] bond0: active interface up!
[  863.117433][ T6295] br0: port 1(eth0) entered disabled state
[  863.191613][ T6291] bond0: (slave eth0): link status definitely down, disabling slave
[  863.192184][ T6291] bond0: (slave eth1): making interface the new active one
[  865.572641][ T2864] br0: port 1(eth0) entered blocking state
[  865.573006][ T2864] br0: port 1(eth0) entered forwarding state
[  865.583207][ T6291] bond0: (slave eth0): link status up, enabling it in 200 ms
[  865.583937][ T6291] bond0: (slave eth0): invalid new link 3 on slave
[  865.791378][ T6291] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  865.886133][ T6298] br0: port 2(eth1) entered disabled state
[  865.896498][ T6291] bond0: (slave eth1): link status definitely down, disabling slave
[  865.896971][ T6291] bond0: (slave eth0): making interface the new active one
# TEST: mode (1) bond recovery                                        [ OK ]
[  867.693164][ T6301] bond0 (unregistering): (slave eth0): Releasing backup interface
[  867.703567][ T6301] bond0 (unregistering): (slave eth1): Releasing backup interface
[  867.715010][ T6301] bond0 (unregistering): Released all slaves
[  867.734592][   T68] br0: port 1(eth0) entered disabled state
[  869.745106][ T6308] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  869.746739][   T68] br0: port 1(eth0) entered blocking state
[  869.747087][   T68] br0: port 1(eth0) entered forwarding state
[  870.070470][ T6309] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  870.072108][  T262] br0: port 2(eth1) entered blocking state
[  870.072430][  T262] br0: port 2(eth1) entered forwarding state
[  870.411715][ T6291] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  870.412259][ T6291] bond0: (slave eth0): making interface the new active one
[  870.413259][ T6291] bond0: active interface up!
[  870.831440][ T6291] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[  872.521798][ T6314] br0: port 1(eth0) entered disabled state
[  872.599565][ T6291] bond0: (slave eth0): link status definitely down, disabling slave
[  872.600127][ T6291] bond0: (slave eth1): making interface the new active one
[  874.976643][ T2864] br0: port 1(eth0) entered blocking state
[  874.977003][ T2864] br0: port 1(eth0) entered forwarding state
[  874.992123][ T6291] bond0: (slave eth0): link status up, enabling it in 500 ms
[  874.992629][ T6291] bond0: (slave eth0): invalid new link 3 on slave
[  875.417309][ T6317] br0: port 2(eth1) entered disabled state
[  875.511429][ T6291] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  875.512397][ T6291] bond0: (slave eth1): link status definitely down, disabling slave
[  875.512867][ T6291] bond0: (slave eth0): making interface the new active one
# TEST: mode (1) bond recovery                                        [ OK ]
[  877.211214][ T6320] bond0 (unregistering): (slave eth0): Releasing backup interface
[  877.224448][ T6320] bond0 (unregistering): (slave eth1): Releasing backup interface
[  877.235960][ T6320] bond0 (unregistering): Released all slaves
[  877.253494][   T68] br0: port 1(eth0) entered disabled state
[  879.243000][ T6327] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  879.244477][   T68] br0: port 1(eth0) entered blocking state
[  879.244808][   T68] br0: port 1(eth0) entered forwarding state
[  879.590416][ T6328] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  879.591843][  T262] br0: port 2(eth1) entered blocking state
[  879.592191][  T262] br0: port 2(eth1) entered forwarding state
[  879.909702][ T6291] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  879.910264][ T6291] bond0: (slave eth0): making interface the new active one
[  879.925684][ T6291] bond0: active interface up!
[  880.863485][  T250] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[  881.955984][ T6333] br0: port 1(eth0) entered disabled state
[  882.007728][  T250] bond0: (slave eth0): link status definitely down, disabling slave
[  882.008222][  T250] bond0: (slave eth1): making interface the new active one
[  884.410653][  T262] br0: port 1(eth0) entered blocking state
[  884.411031][  T262] br0: port 1(eth0) entered forwarding state
[  884.503131][  T250] bond0: (slave eth0): link status up, enabling it in 1000 ms
[  884.503668][  T250] bond0: (slave eth0): invalid new link 3 on slave
[  884.715079][ T6336] br0: port 2(eth1) entered disabled state
[  884.815595][  T250] bond0: (slave eth1): link status definitely down, disabling slave
[  884.816200][  T250] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  886.478179][ T6339] bond0 (unregistering): (slave eth0): Releasing backup interface
[  886.493294][ T6339] bond0 (unregistering): (slave eth1): Releasing backup interface
[  886.500963][ T6339] bond0 (unregistering): Released all slaves
[  886.512683][ T5942] br0: port 1(eth0) entered disabled state
[  888.505405][ T6346] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  888.506396][  T262] br0: port 1(eth0) entered blocking state
[  888.506707][  T262] br0: port 1(eth0) entered forwarding state
[  888.841234][ T6347] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  888.844811][ T3482] br0: port 2(eth1) entered blocking state
[  888.845151][ T3482] br0: port 2(eth1) entered forwarding state
[  889.154700][  T250] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  889.155245][  T250] bond0: (slave eth0): making interface the new active one
[  889.156312][  T250] bond0: active interface up!
[  891.135368][ T6291] bond0: (slave eth1): link status definitely up, 10000 Mbps full duplex
[  891.322537][ T6352] br0: port 1(eth0) entered disabled state
[  891.343587][ T6291] bond0: (slave eth0): link status definitely down, disabling slave
[  891.344098][ T6291] bond0: (slave eth1): making interface the new active one
[  893.778360][ T3482] br0: port 1(eth0) entered blocking state
[  893.778871][ T3482] br0: port 1(eth0) entered forwarding state
[  893.839166][ T6291] bond0: (slave eth0): link status up, enabling it in 2000 ms
[  893.839883][ T6291] bond0: (slave eth0): invalid new link 3 on slave
[  894.124860][ T6355] br0: port 2(eth1) entered disabled state
[  894.151776][ T6291] bond0: (slave eth1): link status definitely down, disabling slave
[  894.152479][ T6291] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  895.902784][ T6358] bond0 (unregistering): (slave eth0): Releasing backup interface
[  895.913961][ T6358] bond0 (unregistering): (slave eth1): Releasing backup interface
[  895.925903][ T6358] bond0 (unregistering): Released all slaves
[  895.943083][   T68] br0: port 1(eth0) entered disabled state
[  897.917477][ T6365] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  897.920360][   T68] br0: port 1(eth0) entered blocking state
[  897.920686][   T68] br0: port 1(eth0) entered forwarding state
[  898.272709][ T6366] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  898.274823][ T3482] br0: port 2(eth1) entered blocking state
[  898.275164][ T3482] br0: port 2(eth1) entered forwarding state
[  898.609690][ T6291] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  898.610258][ T6291] bond0: (slave eth0): making interface the new active one
[  898.611286][ T6291] bond0: active interface up!
[  900.699710][ T6371] br0: port 1(eth0) entered disabled state
[  900.799539][  T250] bond0: (slave eth0): link status definitely down, disabling slave
[  900.800044][  T250] bond0: (slave eth1): making interface the new active one 2400 ms earlier
[  903.197039][  T262] br0: port 1(eth0) entered blocking state
[  903.197420][  T262] br0: port 1(eth0) entered forwarding state
[  903.295145][  T250] bond0: (slave eth0): link status up, enabling it in 5000 ms
[  903.295645][  T250] bond0: (slave eth0): invalid new link 3 on slave
[  903.525638][ T6374] br0: port 2(eth1) entered disabled state
[  903.607584][  T250] bond0: (slave eth1): link status definitely down, disabling slave
[  903.608110][  T250] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  905.388834][ T6377] bond0 (unregistering): (slave eth0): Releasing backup interface
[  905.399269][ T6377] bond0 (unregistering): (slave eth1): Releasing backup interface
[  905.410944][ T6377] bond0 (unregistering): Released all slaves
[  905.427854][ T5942] br0: port 1(eth0) entered disabled state
[  907.428633][ T6384] bond0: (slave eth0): Enslaving as a backup interface with an up link
[  907.430384][ T5942] br0: port 1(eth0) entered blocking state
[  907.430860][ T5942] br0: port 1(eth0) entered forwarding state
[  907.760800][ T6385] bond0: (slave eth1): Enslaving as a backup interface with an up link
[  907.770023][ T5942] br0: port 2(eth1) entered blocking state
[  907.770402][ T5942] br0: port 2(eth1) entered forwarding state
[  908.081670][  T250] bond0: (slave eth0): link status definitely up, 10000 Mbps full duplex
[  908.082207][  T250] bond0: (slave eth0): making interface the new active one
[  908.087985][  T250] bond0: active interface up!
[  910.155315][ T6390] br0: port 1(eth0) entered disabled state
[  910.167511][  T250] bond0: (slave eth0): link status definitely down, disabling slave
[  910.167968][  T250] bond0: (slave eth1): making interface the new active one 2200 ms earlier
[  912.590921][ T3482] br0: port 1(eth0) entered blocking state
[  912.591457][ T3482] br0: port 1(eth0) entered forwarding state
[  912.663151][  T250] bond0: (slave eth0): link status up, enabling it in 10000 ms
[  912.663695][  T250] bond0: (slave eth0): invalid new link 3 on slave
[  912.895762][ T6393] br0: port 2(eth1) entered disabled state
[  912.975555][  T250] bond0: (slave eth1): link status definitely down, disabling slave
[  912.976007][  T250] bond0: (slave eth0): making interface the new active one 400 ms earlier
# TEST: mode (1) bond recovery                                        [ OK ]
[  914.691710][ T6396] bond0 (unregistering): (slave eth0): Releasing backup interface
[  914.702883][ T6396] bond0 (unregistering): (slave eth1): Releasing backup interface
[  914.714876][ T6396] bond0 (unregistering): Released all slaves
[  914.732672][  T349] br0: port 1(eth0) entered disabled state
[  915.570140][  T250] eth1: left allmulticast mode
[  915.570585][  T250] eth1: left promiscuous mode
[  915.571212][  T250] br0: port 2(eth1) entered disabled state
[  915.573605][  T250] eth0: left allmulticast mode
[  915.574041][  T250] eth0: left promiscuous mode
[  915.574614][  T250] 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__->