Appending logs for you to look over.
--8<--------------------------cut here-------------------------->8--
# -*- compilation -*-
2. bfd.at:79: testing bfd - Verify tunnel down detection ...
../../tests/bfd.at:85: ovsdb-tool create conf.db
$abs_top_srcdir/vswitchd/vswitch.ovsschema
../../tests/bfd.at:85: ovsdb-server --detach --no-chdir --pidfile --log-file
--remote=punix:$OVS_RUNDIR/db.sock
stderr:
2013-11-18T22:50:20Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0002/ovsdb-server.log
../../tests/bfd.at:85: sed < stderr '
/vlog|INFO|opened log file/d
/ovsdb_server|INFO|ovsdb-server (Open vSwitch)/d'
../../tests/bfd.at:85: ovs-vsctl --no-wait init
../../tests/bfd.at:85: ovs-vswitchd --detach --no-chdir --pidfile
--enable-dummy --disable-system --log-file -vvconn -vofproto_dpif
stderr:
2013-11-18T22:50:20Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0002/ovs-vswitchd.log
2013-11-18T22:50:20Z|00002|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0002/db.sock:
connecting...
2013-11-18T22:50:20Z|00003|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0002/db.sock:
connected
../../tests/bfd.at:85: sed < stderr '
/vlog|INFO|opened log file/d
/vswitchd|INFO|ovs-vswitchd (Open vSwitch)/d
/reconnect|INFO|/d
/ofproto|INFO|using datapath ID/d
/ofproto|INFO|datapath ID changed to fedcba9876543210/d'
../../tests/bfd.at:85: ovs-vsctl -- add-br br0 -- set bridge br0
datapath-type=dummy other-config:datapath-id=fedcba9876543210
other-config:hwaddr=aa:55:aa:55:00:00
protocols=[OpenFlow10,OpenFlow11,OpenFlow12,OpenFlow13] fail-mode=secure --
add-br br-bfd0 -- \
set bridge br-bfd0 datapath-type=dummy \
other-config:hwaddr=aa:55:aa:56:00:00 -- \
add-br br-bfd1 -- \
set bridge br-bfd1 datapath-type=dummy \
other-config:hwaddr=aa:55:aa:57:00:00 -- \
add-br br-sw -- \
set bridge br-sw datapath-type=dummy \
other-config:hwaddr=aa:55:aa:58:00:00 -- \
add-port br-sw p1-sw -- set Interface p1-sw type=patch \
options:peer=p1 -- \
add-port br-sw p0-sw -- set Interface p0-sw type=patch \
options:peer=p0 -- \
add-port br-bfd1 p1 -- set Interface p1 type=patch \
options:peer=p1-sw bfd:enable=true -- \
add-port br-bfd0 p0 -- set Interface p0 type=patch \
options:peer=p0-sw bfd:enable=true --
../../tests/bfd.at:108: ovs-ofctl add-flow br-sw 'priority=0,actions=NORMAL'
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
warped
../../tests/bfd.at:111: ovs-appctl bfd/show p1 | sed -e '/Time:/d' | sed -e
'/Discriminator/d' | sed -e '/Interval:/d'
--- - 2013-11-18 14:50:20.803092850 -0800
+++ /home/blp/ovs/_build/tests/testsuite.dir/at-groups/2/stdout 2013-11-18
14:50:20.000000000 -0800
@@ -1,12 +1,12 @@
- Forwarding: true
+ Forwarding: false
Detect Multiplier: 3
Concatenated Path Down: false
Local Flags: none
- Local Session State: up
+ Local Session State: down
Local Diagnostic: No Diagnostic
Remote Flags: none
- Remote Session State: up
+ Remote Session State: down
Remote Diagnostic: No Diagnostic
ovsdb-server.log:
2013-11-18T22:50:20.531Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0002/ovsdb-server.log
2013-11-18T22:50:20.540Z|00002|ovsdb_server|INFO|ovsdb-server (Open vSwitch)
2.0.90
ovs-vswitchd.log:
2013-11-18T22:50:20.554Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0002/ovs-vswitchd.log
2013-11-18T22:50:20.555Z|00002|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0002/db.sock:
connecting...
2013-11-18T22:50:20.556Z|00003|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0002/db.sock:
connected
2013-11-18T22:50:20.557Z|00004|bridge|INFO|ovs-vswitchd (Open vSwitch) 2.0.90
2013-11-18T22:50:20.576Z|00005|bridge|INFO|bridge br-bfd1: added interface
br-bfd1 on port 65534
2013-11-18T22:50:20.577Z|00006|bridge|INFO|bridge br-bfd1: added interface p1
on port 1
2013-11-18T22:50:20.577Z|00007|bridge|INFO|bridge br0: added interface br0 on
port 65534
2013-11-18T22:50:20.577Z|00008|bridge|INFO|bridge br-sw: added interface p0-sw
on port 1
2013-11-18T22:50:20.577Z|00009|bridge|INFO|bridge br-sw: added interface br-sw
on port 65534
2013-11-18T22:50:20.577Z|00010|bridge|INFO|bridge br-sw: added interface p1-sw
on port 2
2013-11-18T22:50:20.578Z|00011|bridge|INFO|bridge br-bfd0: added interface
br-bfd0 on port 65534
2013-11-18T22:50:20.578Z|00012|bridge|INFO|bridge br-bfd0: added interface p0
on port 1
2013-11-18T22:50:20.578Z|00013|bridge|INFO|bridge br-bfd1: using datapath ID
0000aa55aa570000
2013-11-18T22:50:20.578Z|00014|bfd|INFO|p1: BFD state change: admin_down->down "No
Diagnostic"->"No Diagnostic".
Forwarding: false
Detect Multiplier: 3
Concatenated Path Down: false
TX Interval: Approx 1000ms
RX Interval: Approx 0ms
Detect Time: now +863722930ms
Next TX Time: now +863722930ms
Last TX Time: now +863722930ms
Local Flags: none
Local Session State: admin_down
Local Diagnostic: No Diagnostic
Local Discriminator: 0xff1abeba
Local Minimum TX Interval: 1000ms
Local Minimum RX Interval: 0ms
Remote Flags: none
Remote Session State: admin_down
Remote Diagnostic: No Diagnostic
Remote Discriminator: 0x0
Remote Minimum TX Interval: 0ms
Remote Minimum RX Interval: 0ms
2013-11-18T22:50:20.578Z|00015|connmgr|INFO|br-bfd1: added service controller
"punix:/home/blp/ovs/_build/tests/testsuite.dir/0002/br-bfd1.mgmt"
2013-11-18T22:50:20.578Z|00001|ofproto_dpif_monitor(monitor)|INFO|monitor
thread created
2013-11-18T22:50:20.579Z|00016|bridge|INFO|bridge br0: using datapath ID
fedcba9876543210
2013-11-18T22:50:20.579Z|00017|connmgr|INFO|br0: added service controller
"punix:/home/blp/ovs/_build/tests/testsuite.dir/0002/br0.mgmt"
2013-11-18T22:50:20.579Z|00018|bridge|INFO|bridge br-sw: using datapath ID
0000aa55aa580000
2013-11-18T22:50:20.579Z|00019|connmgr|INFO|br-sw: added service controller
"punix:/home/blp/ovs/_build/tests/testsuite.dir/0002/br-sw.mgmt"
2013-11-18T22:50:20.579Z|00020|bridge|INFO|bridge br-bfd0: using datapath ID
0000aa55aa560000
2013-11-18T22:50:20.579Z|00021|bfd|INFO|p0: BFD state change: admin_down->down "No
Diagnostic"->"No Diagnostic".
Forwarding: false
Detect Multiplier: 3
Concatenated Path Down: false
TX Interval: Approx 1000ms
RX Interval: Approx 0ms
Detect Time: now +863722931ms
Next TX Time: now +863722931ms
Last TX Time: now +863722931ms
Local Flags: none
Local Session State: admin_down
Local Diagnostic: No Diagnostic
Local Discriminator: 0xb178f15c
Local Minimum TX Interval: 1000ms
Local Minimum RX Interval: 0ms
Remote Flags: none
Remote Session State: admin_down
Remote Diagnostic: No Diagnostic
Remote Discriminator: 0x0
Remote Minimum TX Interval: 0ms
Remote Minimum RX Interval: 0ms
2013-11-18T22:50:20.579Z|00022|connmgr|INFO|br-bfd0: added service controller
"punix:/home/blp/ovs/_build/tests/testsuite.dir/0002/br-bfd0.mgmt"
2013-11-18T22:50:20.580Z|00023|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:20.581Z|00024|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:20.581Z|00025|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:20.581Z|00026|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:20.581Z|00027|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:20.591Z|00028|vconn|DBG|unix: sent (Success): OFPT_HELLO
(xid=0x1):
version bitmap: 0x01
2013-11-18T22:50:20.591Z|00029|vconn|DBG|unix: received: OFPT_HELLO (xid=0x1):
version bitmap: 0x01
2013-11-18T22:50:20.591Z|00030|vconn|DBG|unix: negotiated OpenFlow version 0x01
(we support version 0x01, peer supports version 0x01)
2013-11-18T22:50:20.591Z|00031|vconn|DBG|unix: received: OFPT_FLOW_MOD
(xid=0x2): ADD priority=0 actions=NORMAL
2013-11-18T22:50:20.591Z|00032|vconn|DBG|unix: received: OFPT_BARRIER_REQUEST
(xid=0x3):
2013-11-18T22:50:20.591Z|00033|vconn|DBG|unix: sent (Success):
OFPT_BARRIER_REPLY (xid=0x3):
2. bfd.at:79: 2. bfd - Verify tunnel down detection (bfd.at:79): FAILED
(bfd.at:111)
--8<--------------------------cut here-------------------------->8--
# -*- compilation -*-
710. ofproto-dpif.at:2702: testing ofproto-dpif megaflow - normal, balance-tcp
bonding ...
../../tests/ofproto-dpif.at:2706: ovsdb-tool create conf.db
$abs_top_srcdir/vswitchd/vswitch.ovsschema
../../tests/ofproto-dpif.at:2706: ovsdb-server --detach --no-chdir --pidfile
--log-file --remote=punix:$OVS_RUNDIR/db.sock
stderr:
2013-11-18T22:50:21Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0710/ovsdb-server.log
../../tests/ofproto-dpif.at:2706: sed < stderr '
/vlog|INFO|opened log file/d
/ovsdb_server|INFO|ovsdb-server (Open vSwitch)/d'
../../tests/ofproto-dpif.at:2706: ovs-vsctl --no-wait init
../../tests/ofproto-dpif.at:2706: ovs-vswitchd --detach --no-chdir --pidfile
--enable-dummy --disable-system --log-file -vvconn -vofproto_dpif
stderr:
2013-11-18T22:50:21Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0710/ovs-vswitchd.log
2013-11-18T22:50:21Z|00002|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0710/db.sock:
connecting...
2013-11-18T22:50:21Z|00003|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0710/db.sock:
connected
../../tests/ofproto-dpif.at:2706: sed < stderr '
/vlog|INFO|opened log file/d
/vswitchd|INFO|ovs-vswitchd (Open vSwitch)/d
/reconnect|INFO|/d
/ofproto|INFO|using datapath ID/d
/ofproto|INFO|datapath ID changed to fedcba9876543210/d'
../../tests/ofproto-dpif.at:2706: ovs-vsctl -- add-br br0 -- set bridge br0
datapath-type=dummy other-config:datapath-id=fedcba9876543210
other-config:hwaddr=aa:55:aa:55:00:00
protocols=[OpenFlow10,OpenFlow11,OpenFlow12,OpenFlow13] fail-mode=secure --
add-bond br0 bond0 p0 p1 bond_mode=balance-tcp lacp=active \
other-config:lacp-time=fast \
other-config:bond-rebalance-interval=0 -- \
set interface p0 type=patch options:peer=p2 ofport_request=1 -- \
set interface p1 type=patch options:peer=p3 ofport_request=2 -- \
add-br br1 -- \
set bridge br1 other-config:hwaddr=aa:66:aa:66:00:00 -- \
set bridge br1 datapath-type=dummy other-config:datapath-id=1234 \
fail-mode=secure -- \
add-bond br1 bond1 p2 p3 bond_mode=balance-tcp lacp=active \
other-config:lacp-time=fast \
other-config:bond-rebalance-interval=0 -- \
set interface p2 type=patch options:peer=p0 ofport_request=3 -- \
set interface p3 type=patch options:peer=p1 ofport_request=4 --
../../tests/ofproto-dpif.at:2722: ovs-appctl netdev-dummy/set-admin-state up
../../tests/ofproto-dpif.at:2725: ovs-ofctl add-flow br0 action=normal
../../tests/ofproto-dpif.at:2726: ovs-ofctl add-flow br1 action=normal
warped
../../tests/ofproto-dpif.at:2729: ovs-appctl netdev-dummy/receive p7
'in_port(1),eth(src=50:54:00:00:00:09,dst=50:54:00:00:00:0a),eth_type(0x0800),ipv4(src=10.0.0.2,dst=10.0.0.1,proto=1,tos=0,ttl=64,frag=no),icmp(type=8,code=0)'
../../tests/ofproto-dpif.at:2730: ovs-appctl netdev-dummy/receive p7
'in_port(1),eth(src=50:54:00:00:00:0b,dst=50:54:00:00:00:0c),eth_type(0x0800),ipv4(src=10.0.0.4,dst=10.0.0.3,proto=1,tos=0,ttl=64,frag=no),icmp(type=8,code=0)'
../../tests/ofproto-dpif.at:2732: ovs-appctl dpif/dump-flows br0 | sed '
s/used:[0-9]*\.[0-9]*/used:0.0/
s/actions:.*/actions: <del>/
s/packets:[0-9]*/packets:0/
s/bytes:[0-9]*/bytes:0/
' | sort
--- - 2013-11-18 14:50:21.139029462 -0800
+++ /home/blp/ovs/_build/tests/testsuite.dir/at-groups/710/stdout
2013-11-18 14:50:21.000000000 -0800
@@ -1,3 +1,3 @@
-skb_priority(0),in_port(7),eth(src=50:54:00:00:00:09,dst=50:54:00:00:00:0a),eth_type(0x0800),ipv4(src=10.0.0.2/255.255.255.255,dst=10.0.0.1/255.255.255.255,proto=1/0xff,tos=0/0,ttl=64/0,frag=no/0xff),icmp(type=8,code=0),
packets:0, bytes:0, used:0.0s, actions: <del>
-skb_priority(0),in_port(7),eth(src=50:54:00:00:00:0b,dst=50:54:00:00:00:0c),eth_type(0x0800),ipv4(src=10.0.0.4/255.255.255.255,dst=10.0.0.3/255.255.255.255,proto=1/0xff,tos=0/0,ttl=64/0,frag=no/0xff),icmp(type=8,code=0),
packets:0, bytes:0, used:0.0s, actions: <del>
+skb_priority(0),in_port(7),eth(src=50:54:00:00:00:09,dst=50:54:00:00:00:0a),eth_type(0x0800),ipv4(src=10.0.0.2/0.0.0.0,dst=10.0.0.1/0.0.0.0,proto=1/0,tos=0/0,ttl=64/0,frag=no/0xff),
packets:0, bytes:0, used:0.0s, actions: <del>
+skb_priority(0),in_port(7),eth(src=50:54:00:00:00:0b,dst=50:54:00:00:00:0c),eth_type(0x0800),ipv4(src=10.0.0.4/0.0.0.0,dst=10.0.0.3/0.0.0.0,proto=1/0,tos=0/0,ttl=64/0,frag=no/0xff),
packets:0, bytes:0, used:0.0s, actions: <del>
ovsdb-server.log:
2013-11-18T22:50:21.040Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0710/ovsdb-server.log
2013-11-18T22:50:21.050Z|00002|ovsdb_server|INFO|ovsdb-server (Open vSwitch)
2.0.90
ovs-vswitchd.log:
2013-11-18T22:50:21.064Z|00001|vlog|INFO|opened log file
/home/blp/ovs/_build/tests/testsuite.dir/0710/ovs-vswitchd.log
2013-11-18T22:50:21.065Z|00002|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0710/db.sock:
connecting...
2013-11-18T22:50:21.066Z|00003|reconnect|INFO|unix:/home/blp/ovs/_build/tests/testsuite.dir/0710/db.sock:
connected
2013-11-18T22:50:21.067Z|00004|bridge|INFO|ovs-vswitchd (Open vSwitch) 2.0.90
2013-11-18T22:50:21.084Z|00005|bridge|INFO|bridge br0: added interface br0 on
port 65534
2013-11-18T22:50:21.085Z|00006|bridge|INFO|bridge br0: added interface p1 on
port 2
2013-11-18T22:50:21.085Z|00007|bridge|INFO|bridge br0: added interface p0 on
port 1
2013-11-18T22:50:21.085Z|00008|bridge|INFO|bridge br1: added interface p3 on
port 4
2013-11-18T22:50:21.085Z|00009|bridge|INFO|bridge br1: added interface p2 on
port 3
2013-11-18T22:50:21.085Z|00010|bridge|INFO|bridge br1: added interface br1 on
port 65534
2013-11-18T22:50:21.085Z|00011|bridge|INFO|bridge br0: using datapath ID
fedcba9876543210
2013-11-18T22:50:21.085Z|00012|bond|INFO|interface p1: enabled
2013-11-18T22:50:21.085Z|00013|bond|INFO|interface p0: enabled
2013-11-18T22:50:21.086Z|00014|connmgr|INFO|br0: added service controller
"punix:/home/blp/ovs/_build/tests/testsuite.dir/0710/br0.mgmt"
2013-11-18T22:50:21.086Z|00015|bridge|INFO|bridge br1: using datapath ID
0000aa66aa660000
2013-11-18T22:50:21.086Z|00016|bond|INFO|interface p3: enabled
2013-11-18T22:50:21.086Z|00017|bond|INFO|interface p2: enabled
2013-11-18T22:50:21.086Z|00018|connmgr|INFO|br1: added service controller
"punix:/home/blp/ovs/_build/tests/testsuite.dir/0710/br1.mgmt"
2013-11-18T22:50:21.087Z|00019|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:21.087Z|00020|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:21.087Z|00021|bond|INFO|interface p1: link state down
2013-11-18T22:50:21.087Z|00022|bond|INFO|interface p1: disabled
2013-11-18T22:50:21.087Z|00023|bond|INFO|interface p0: link state down
2013-11-18T22:50:21.087Z|00024|bond|INFO|interface p0: disabled
2013-11-18T22:50:21.087Z|00025|bond|INFO|interface p3: link state down
2013-11-18T22:50:21.087Z|00026|bond|INFO|interface p3: disabled
2013-11-18T22:50:21.087Z|00027|bond|INFO|interface p2: link state down
2013-11-18T22:50:21.087Z|00028|bond|INFO|interface p2: disabled
2013-11-18T22:50:21.087Z|00029|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:21.087Z|00030|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:21.088Z|00031|bond|INFO|interface p1: link state up
2013-11-18T22:50:21.088Z|00032|bond|INFO|interface p1: enabled
2013-11-18T22:50:21.088Z|00033|bond|INFO|interface p0: link state up
2013-11-18T22:50:21.088Z|00034|bond|INFO|interface p0: enabled
2013-11-18T22:50:21.088Z|00035|bond|INFO|bond bond0: active interface is now p1
2013-11-18T22:50:21.088Z|00036|bond|INFO|interface p3: link state up
2013-11-18T22:50:21.088Z|00037|bond|INFO|interface p3: enabled
2013-11-18T22:50:21.088Z|00038|bond|INFO|interface p2: link state up
2013-11-18T22:50:21.088Z|00039|bond|INFO|interface p2: enabled
2013-11-18T22:50:21.088Z|00040|bond|INFO|bond bond1: active interface is now p3
2013-11-18T22:50:21.088Z|00041|ofproto_dpif|DBG|need revalidate in
ofproto_wait_cb()
2013-11-18T22:50:21.103Z|00042|bridge|INFO|bridge br0: added interface p7 on
port 7
2013-11-18T22:50:21.108Z|00043|vconn|DBG|unix: sent (Success): OFPT_HELLO
(OF1.3) (xid=0x1):
version bitmap: 0x01, 0x02, 0x03, 0x04
2013-11-18T22:50:21.108Z|00044|vconn|DBG|unix: received: OFPT_HELLO (xid=0x1):
version bitmap: 0x01
2013-11-18T22:50:21.108Z|00045|vconn|DBG|unix: negotiated OpenFlow version 0x01
(we support version 0x04 and earlier, peer supports version 0x01)
2013-11-18T22:50:21.108Z|00046|vconn|DBG|unix: received: OFPT_FLOW_MOD
(xid=0x2): ADD actions=NORMAL
2013-11-18T22:50:21.108Z|00047|vconn|DBG|unix: received: OFPT_BARRIER_REQUEST
(xid=0x3):
2013-11-18T22:50:21.109Z|00048|vconn|DBG|unix: sent (Success):
OFPT_BARRIER_REPLY (xid=0x3):
2013-11-18T22:50:21.113Z|00049|vconn|DBG|unix: sent (Success): OFPT_HELLO
(xid=0x2):
version bitmap: 0x01
2013-11-18T22:50:21.113Z|00050|vconn|DBG|unix: received: OFPT_HELLO (xid=0x1):
version bitmap: 0x01
2013-11-18T22:50:21.113Z|00051|vconn|DBG|unix: negotiated OpenFlow version 0x01
(we support version 0x01, peer supports version 0x01)
2013-11-18T22:50:21.113Z|00052|vconn|DBG|unix: received: OFPT_FLOW_MOD
(xid=0x2): ADD actions=NORMAL
2013-11-18T22:50:21.113Z|00053|vconn|DBG|unix: received: OFPT_BARRIER_REQUEST
(xid=0x3):
2013-11-18T22:50:21.113Z|00054|vconn|DBG|unix: sent (Success):
OFPT_BARRIER_REPLY (xid=0x3):
710. ofproto-dpif.at:2702: 710. ofproto-dpif megaflow - normal, balance-tcp
bonding (ofproto-dpif.at:2702): FAILED (ofproto-dpif.at:2732)