Ubuntu

1.10 FTBFS on powerpc (lacp test failure)

Reported by James Page on 2013-06-10
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
openvswitch (Ubuntu)
Medium
Unassigned

Bug Description

Link:

https://launchpadlibrarian.net/142079482/buildlog_ubuntu-saucy-powerpc.openvswitch_1.10.0-0ubuntu2_FAILEDTOBUILD.txt.gz

Log:

## ------------- ##
## Test results. ##
## ------------- ##

ERROR: All 1238 tests were run,
1 failed unexpectedly.

## ------------------------ ##
## Summary of the failures. ##
## ------------------------ ##
Failed tests:
openvswitch 1.10.0 test suite test groups:

 NUM: FILE-NAME:LINE TEST-GROUP-NAME
      KEYWORDS

   3: lacp.at:121 lacp - negotiation

## ---------------------- ##
## Detailed failed tests. ##
## ---------------------- ##

# -*- compilation -*-
3. lacp.at:121: testing lacp - negotiation ...
../../tests/lacp.at:125: ovsdb-tool create conf.db $abs_top_srcdir/vswitchd/vswitch.ovsschema
../../tests/lacp.at:125: ovsdb-server --detach --no-chdir --pidfile --log-file --remote=punix:$OVS_RUNDIR/db.sock
stderr:
2013-06-10T09:43:38Z|00001|vlog|INFO|opened log file /build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/ovsdb-server.log
../../tests/lacp.at:125: sed < stderr '
/vlog|INFO|opened log file/d
/ovsdb_server|INFO|ovsdb-server (Open vSwitch)/d'
../../tests/lacp.at:125: ovs-vsctl --no-wait init
../../tests/lacp.at:125: ovs-vswitchd --detach --no-chdir --pidfile --enable-dummy --disable-system --log-file -vvconn -vofproto_dpif
stderr:
2013-06-10T09:43:38Z|00001|vlog|INFO|opened log file /build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/ovs-vswitchd.log
2013-06-10T09:43:38Z|00002|reconnect|INFO|unix:/build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/db.sock: connecting...
2013-06-10T09:43:38Z|00003|reconnect|INFO|unix:/build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/db.sock: connected
../../tests/lacp.at:125: 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/lacp.at:125: 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,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/lacp.at:141: ovs-appctl netdev-dummy/set-admin-state up
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
 partner state: activity timeout aggregation synchronized collecting distributing expired
 partner state: activity timeout aggregation synchronized collecting distributing expired
warped
 status: active negotiated
 status: active negotiated
../../tests/lacp.at:168: ovs-appctl lacp/show bond0
ovs-appctl lacp/show bond1
ovs-appctl bond/show bond0
ovs-appctl bond/show bond1
stdout:
---- bond0 ----
 status: active negotiated
 sys_id: aa:55:aa:55:00:00
 sys_priority: 65534
 aggregation key: 1
 lacp_time: fast

slave: p0: current attached
 port_id: 1
 port_priority: 65535
 may_enable: true

 actor sys_id: aa:55:aa:55:00:00
 actor sys_priority: 65534
 actor port_id: 1
 actor port_priority: 65535
 actor key: 1
 actor state: activity timeout aggregation synchronized collecting distributing

 partner sys_id: aa:66:aa:66:00:00
 partner sys_priority: 65534
 partner port_id: 3
 partner port_priority: 65535
 partner key: 4
 partner state: activity timeout aggregation synchronized collecting distributing

slave: p1: current attached
 port_id: 2
 port_priority: 65535
 may_enable: true

 actor sys_id: aa:55:aa:55:00:00
 actor sys_priority: 65534
 actor port_id: 2
 actor port_priority: 65535
 actor key: 1
 actor state: activity timeout aggregation synchronized collecting distributing

 partner sys_id: aa:66:aa:66:00:00
 partner sys_priority: 65534
 partner port_id: 4
 partner port_priority: 65535
 partner key: 4
 partner state: activity timeout aggregation synchronized collecting distributing
---- bond1 ----
 status: active negotiated
 sys_id: aa:66:aa:66:00:00
 sys_priority: 65534
 aggregation key: 4
 lacp_time: fast

slave: p2: current attached
 port_id: 3
 port_priority: 65535
 may_enable: true

 actor sys_id: aa:66:aa:66:00:00
 actor sys_priority: 65534
 actor port_id: 3
 actor port_priority: 65535
 actor key: 4
 actor state: activity timeout aggregation synchronized collecting distributing

 partner sys_id: aa:55:aa:55:00:00
 partner sys_priority: 65534
 partner port_id: 1
 partner port_priority: 65535
 partner key: 1
 partner state: activity timeout aggregation synchronized collecting distributing

slave: p3: current attached
 port_id: 4
 port_priority: 65535
 may_enable: true

 actor sys_id: aa:66:aa:66:00:00
 actor sys_priority: 65534
 actor port_id: 4
 actor port_priority: 65535
 actor key: 4
 actor state: activity timeout aggregation synchronized collecting distributing

 partner sys_id: aa:55:aa:55:00:00
 partner sys_priority: 65534
 partner port_id: 2
 partner port_priority: 65535
 partner key: 1
 partner state: activity timeout aggregation synchronized collecting distributing
---- bond0 ----
bond_mode: balance-tcp
bond-hash-basis: 0
updelay: 0 ms
downdelay: 0 ms
lacp_status: negotiated

slave p0: enabled
 active slave
 may_enable: true

slave p1: enabled
 may_enable: true

---- bond1 ----
bond_mode: balance-tcp
bond-hash-basis: 0
updelay: 0 ms
downdelay: 0 ms
lacp_status: negotiated

slave p2: enabled
 active slave
 may_enable: true

slave p3: enabled
 may_enable: true

../../tests/lacp.at:173: sed '/active slave/d' stdout
--- - 2013-06-10 09:43:39.258068941 +0000
+++ /build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/at-groups/3/stdout 2013-06-10 09:43:39.000000000 +0000
@@ -2,7 +2,7 @@
  status: active negotiated
  sys_id: aa:55:aa:55:00:00
  sys_priority: 65534
- aggregation key: 2
+ aggregation key: 1
  lacp_time: fast

 slave: p0: current attached
@@ -14,7 +14,7 @@
  actor sys_priority: 65534
  actor port_id: 1
  actor port_priority: 65535
- actor key: 2
+ actor key: 1
  actor state: activity timeout aggregation synchronized collecting distributing

  partner sys_id: aa:66:aa:66:00:00
@@ -33,7 +33,7 @@
  actor sys_priority: 65534
  actor port_id: 2
  actor port_priority: 65535
- actor key: 2
+ actor key: 1
  actor state: activity timeout aggregation synchronized collecting distributing

  partner sys_id: aa:66:aa:66:00:00
@@ -65,7 +65,7 @@
  partner sys_priority: 65534
  partner port_id: 1
  partner port_priority: 65535
- partner key: 2
+ partner key: 1
  partner state: activity timeout aggregation synchronized collecting distributing

 slave: p3: current attached
@@ -84,7 +84,7 @@
  partner sys_priority: 65534
  partner port_id: 2
  partner port_priority: 65535
- partner key: 2
+ partner key: 1
  partner state: activity timeout aggregation synchronized collecting distributing
 ---- bond0 ----
 bond_mode: balance-tcp
ovsdb-server.log:
> 2013-06-10T09:43:38Z|00001|vlog|INFO|opened log file /build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/ovsdb-server.log
> 2013-06-10T09:43:38Z|00002|ovsdb_server|INFO|ovsdb-server (Open vSwitch) 1.10.0
ovs-vswitchd.log:
> 2013-06-10T09:43:38Z|00001|vlog|INFO|opened log file /build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/ovs-vswitchd.log
> 2013-06-10T09:43:38Z|00002|worker(worker)|INFO|worker process started
> 2013-06-10T09:43:38Z|00002|reconnect|INFO|unix:/build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/db.sock: connecting...
> 2013-06-10T09:43:38Z|00003|reconnect|INFO|unix:/build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/db.sock: connected
> 2013-06-10T09:43:38Z|00004|bridge|INFO|ovs-vswitchd (Open vSwitch) 1.10.0
> 2013-06-10T09:43:38Z|00005|bridge|INFO|bridge br1: added interface p3 on port 4
> 2013-06-10T09:43:38Z|00006|bridge|INFO|bridge br1: added interface p2 on port 3
> 2013-06-10T09:43:38Z|00007|bridge|INFO|bridge br1: added interface br1 on port 65534
> 2013-06-10T09:43:38Z|00008|bridge|INFO|bridge br0: added interface p0 on port 1
> 2013-06-10T09:43:38Z|00009|bridge|INFO|bridge br0: added interface br0 on port 65534
> 2013-06-10T09:43:38Z|00010|bridge|INFO|bridge br0: added interface p1 on port 2
> 2013-06-10T09:43:38Z|00011|bridge|INFO|bridge br1: using datapath ID 0000aa66aa660000
> 2013-06-10T09:43:38Z|00012|bond|WARN|interface p3: enabled
> 2013-06-10T09:43:38Z|00013|bond|WARN|interface p2: enabled
> 2013-06-10T09:43:38Z|00014|connmgr|INFO|br1: added service controller "punix:/build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/br1.mgmt"
> 2013-06-10T09:43:38Z|00015|bridge|INFO|bridge br0: using datapath ID fedcba9876543210
> 2013-06-10T09:43:38Z|00016|bond|WARN|interface p0: enabled
> 2013-06-10T09:43:38Z|00017|bond|WARN|interface p1: enabled
> 2013-06-10T09:43:38Z|00018|connmgr|INFO|br0: added service controller "punix:/build/buildd/openvswitch-1.10.0/_debian/tests/testsuite.dir/0003/br0.mgmt"
> 2013-06-10T09:43:38Z|00019|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2013-06-10T09:43:38Z|00020|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2013-06-10T09:43:38Z|00021|bond|INFO|interface p2: link state down
> 2013-06-10T09:43:38Z|00022|bond|WARN|interface p2: disabled
> 2013-06-10T09:43:38Z|00023|bond|INFO|interface p3: link state down
> 2013-06-10T09:43:38Z|00024|bond|WARN|interface p3: disabled
> 2013-06-10T09:43:38Z|00025|bond|INFO|interface p0: link state down
> 2013-06-10T09:43:38Z|00026|bond|WARN|interface p0: disabled
> 2013-06-10T09:43:38Z|00027|bond|INFO|interface p1: link state down
> 2013-06-10T09:43:38Z|00028|bond|WARN|interface p1: disabled
> 2013-06-10T09:43:38Z|00029|bond|INFO|interface p2: link state up
> 2013-06-10T09:43:38Z|00030|bond|WARN|interface p2: enabled
> 2013-06-10T09:43:38Z|00031|bond|INFO|interface p3: link state up
> 2013-06-10T09:43:38Z|00032|bond|WARN|interface p3: enabled
> 2013-06-10T09:43:38Z|00033|bond|INFO|bond bond1: active interface is now p2
> 2013-06-10T09:43:38Z|00034|bond|INFO|interface p0: link state up
> 2013-06-10T09:43:38Z|00035|bond|WARN|interface p0: enabled
> 2013-06-10T09:43:38Z|00036|bond|INFO|interface p1: link state up
> 2013-06-10T09:43:38Z|00037|bond|WARN|interface p1: enabled
> 2013-06-10T09:43:38Z|00038|bond|INFO|bond bond0: active interface is now p0
bond0:
> ---- bond0 ----
> status: active negotiated
> sys_id: aa:55:aa:55:00:00
> sys_priority: 65534
> aggregation key: 1
> lacp_time: fast
>
> slave: p0: current attached
> port_id: 1
> port_priority: 65535
> may_enable: true
>
> actor sys_id: aa:55:aa:55:00:00
> actor sys_priority: 65534
> actor port_id: 1
> actor port_priority: 65535
> actor key: 1
> actor state: activity timeout aggregation synchronized collecting distributing
>
> partner sys_id: aa:66:aa:66:00:00
> partner sys_priority: 65534
> partner port_id: 3
> partner port_priority: 65535
> partner key: 4
> partner state: activity timeout aggregation synchronized collecting distributing
>
> slave: p1: current attached
> port_id: 2
> port_priority: 65535
> may_enable: true
>
> actor sys_id: aa:55:aa:55:00:00
> actor sys_priority: 65534
> actor port_id: 2
> actor port_priority: 65535
> actor key: 1
> actor state: activity timeout aggregation synchronized collecting distributing
>
> partner sys_id: aa:66:aa:66:00:00
> partner sys_priority: 65534
> partner port_id: 4
> partner port_priority: 65535
> partner key: 4
> partner state: activity timeout aggregation synchronized collecting distributing
bond1:
> ---- bond1 ----
> status: active negotiated
> sys_id: aa:66:aa:66:00:00
> sys_priority: 65534
> aggregation key: 4
> lacp_time: fast
>
> slave: p2: current attached
> port_id: 3
> port_priority: 65535
> may_enable: true
>
> actor sys_id: aa:66:aa:66:00:00
> actor sys_priority: 65534
> actor port_id: 3
> actor port_priority: 65535
> actor key: 4
> actor state: activity timeout aggregation synchronized collecting distributing
>
> partner sys_id: aa:55:aa:55:00:00
> partner sys_priority: 65534
> partner port_id: 1
> partner port_priority: 65535
> partner key: 1
> partner state: activity timeout aggregation synchronized collecting distributing
>
> slave: p3: current attached
> port_id: 4
> port_priority: 65535
> may_enable: true
>
> actor sys_id: aa:66:aa:66:00:00
> actor sys_priority: 65534
> actor port_id: 4
> actor port_priority: 65535
> actor key: 4
> actor state: activity timeout aggregation synchronized collecting distributing
>
> partner sys_id: aa:55:aa:55:00:00
> partner sys_priority: 65534
> partner port_id: 2
> partner port_priority: 65535
> partner key: 1
> partner state: activity timeout aggregation synchronized collecting distributing
3. lacp.at:121: 3. lacp - negotiation (lacp.at:121): FAILED (lacp.at:173)

James Page (james-page) on 2013-06-10
Changed in openvswitch (Ubuntu Saucy):
importance: Undecided → Medium
Ben Pfaff (blp-nicira) wrote :

This test has a race condition. If it's causing trouble in Ubuntu builds, then the best thing to do is to disable the test.

James Page (james-page) wrote :

Hi Ben

Yeah - I did a blanket disable for powerpc for the time being; I'll make it a bit more specific for my next upload.

Ben Pfaff (blp-nicira) wrote :

> Yeah - I did a blanket disable for powerpc for the time being; I'll make it a bit more specific for my next upload.

Yeah, sorry about the trouble, I'll put it on my to-do list to find and fix the race in the test.

James Page (james-page) on 2013-08-13
no longer affects: openvswitch (Ubuntu Saucy)
James Page (james-page) on 2013-08-16
Changed in openvswitch (Ubuntu):
status: New → Triaged
To post a comment you must log in.
This report contains Public information  Edit
Everyone can see this information.

Other bug subscribers