ovs 2.3.2, unit test failure, powerpc - test 717

Only impacts powerpc; skipping test for now until we can dig in further.

Full log: https://launchpadlibrarian.net/210894394/buildlog_ubuntu-wily-powerpc.openvswitch_2.3.2-0ubuntu1_BUILDING.txt.gz

## ----------------------------- ##
## openvswitch 2.3.2 test suite. ##
## ----------------------------- ##
717: ofproto-dpif, balance-tcp bonding, different recirc flow FAILED (ofproto-dpif.at:253)

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

ERROR: 1 test was run,
1 failed unexpectedly.
## -------------------------- ##
## testsuite.log was created. ##
## -------------------------- ##

Please send `tests/testsuite.log' and all information you think might help:

   To: <email address hidden>
   Subject: [openvswitch 2.3.2] testsuite: 717 failed

You may investigate any problem if you feel able to do so, in which
case the test suite provides a good starting point. Its output may
be found below `tests/testsuite.dir'.

make[5]: *** [check-local] Error 1
Makefile:4471: recipe for target 'check-local' failed
make[5]: Leaving directory '/«PKGBUILDDIR»'
make[4]: *** [check-am] Error 2
Makefile:3798: recipe for target 'check-am' failed
make[4]: Leaving directory '/«PKGBUILDDIR»'
make[3]: *** [check-recursive] Error 1
Makefile:3507: recipe for target 'check-recursive' failed
make[3]: Leaving directory '/«PKGBUILDDIR»'
make[2]: *** [check] Error 2
Makefile:3801: recipe for target 'check' failed
make[2]: Leaving directory '/«PKGBUILDDIR»'
## ----------------------------- ##
## openvswitch 2.3.2 test suite. ##
## ----------------------------- ##

testsuite: command line was:
  $ ./tests/testsuite -C tests AUTOTEST_PATH=utilities:vswitchd:ovsdb:vtep:tests --recheck

## --------- ##
## Platform. ##
## --------- ##

hostname = sagari
uname -m = ppc
uname -r = 3.2.0-68-powerpc64-smp
uname -s = Linux
uname -v = #102-Ubuntu SMP Tue Aug 12 22:09:42 UTC 2014

/usr/bin/uname -p = unknown
/bin/uname -X = unknown

/bin/arch = unknown
/usr/bin/arch -k = unknown
/usr/convex/getsysinfo = unknown
/usr/bin/hostinfo = unknown
/bin/machine = unknown
/usr/bin/oslevel = unknown
/bin/universe = unknown

PATH: /«PKGBUILDDIR»/utilities
PATH: /«PKGBUILDDIR»/vswitchd
PATH: /«PKGBUILDDIR»/ovsdb
PATH: /«PKGBUILDDIR»/vtep
PATH: /«PKGBUILDDIR»/tests
PATH: /usr/local/sbin
PATH: /usr/local/bin
PATH: /usr/sbin
PATH: /usr/bin
PATH: /sbin
PATH: /bin
PATH: /usr/games
PATH: /usr/local/games

testsuite: atconfig:
| # Configurable variable values for building test suites.
| # Generated by ./config.status.
| # Copyright (C) 2012 Free Software Foundation, Inc.
|
| # The test suite will define top_srcdir=/../.. etc.
| at_testdir='tests'
| abs_builddir='/«PKGBUILDDIR»/tests'
| at_srcdir='.'
| abs_srcdir='/«PKGBUILDDIR»/tests'
| at_top_srcdir='..'
| abs_top_srcdir='/«PKGBUILDDIR»'
| at_top_build_prefix='../'
| abs_top_builddir='/«PKGBUILDDIR»'
|
| # Backward compatibility with Autotest <= 2.59b:
| at_top_builddir=$at_top_build_prefix
|
| AUTOTEST_PATH='tests'
|
| SHELL=${CONFIG_SHELL-'/bin/bash'}

testsuite: atlocal:
| # -*- shell-script -*-
| HAVE_OPENSSL='yes'
| HAVE_PYTHON='yes'
| EGREP='/bin/grep -E'
| PERL='/usr/bin/perl'
|
| if test x"$PYTHON" = x; then
| PYTHON='/usr/bin/python'
| fi
|
| PYTHONPATH=$abs_top_srcdir/python:$abs_top_builddir/tests:$PYTHONPATH
| export PYTHONPATH
|
| PYTHONIOENCODING=utf_8
| export PYTHONIOENCODING
|
| # PYTHONDONTWRITEBYTECODE=yes keeps Python 2.6+ from creating .pyc and .pyo
| # files. Creating .py[co] works OK for any given version of Open
| # vSwitch, but it causes trouble if you switch from a version with
| # foo/__init__.py into an (older) version with plain foo.py, since
| # foo/__init__.pyc will cause Python to ignore foo.py.
| #
| # Python before version 2.6 always creates .pyc files, so if you develop
| # with such an older version then you're out of luck.
| PYTHONDONTWRITEBYTECODE=yes
| export PYTHONDONTWRITEBYTECODE
|
| # Test whether the current working directory name is all ASCII
| # characters. Some Python code doesn't tolerate non-ASCII characters
| # in filenames very well, so if the current working directory is
| # non-ASCII then we skip the tests that run those programs.
| #
| # This would be just papering over a real problem, except that the
| # tests that we skip are launched from initscripts and thus normally
| # run in system directories with ASCII names. (This problem only came
| # up at all because the Debian autobuilders do build in a top-level
| # directory named /«BUILDDIR».)
| case `pwd | tr -d ' -~'` in
| '') non_ascii_cwd=false ;;
| *) non_ascii_cwd=true
| esac
|
| if test $HAVE_PYTHON = yes; then
| if python -m argparse 2>/dev/null; then
| :
| else
| PYTHONPATH=$PYTHONPATH:$abs_top_srcdir/python/compat
| export PYTHONPATH
| fi
| fi
|
| # Enable malloc debugging features.
| case `uname` in
| Linux)
| MALLOC_PERTURB_=165; export MALLOC_PERTURB_
|
| # Before glibc 2.11, the feature enabled by MALLOC_CHECK_ was not
| # thread-safe. See https://bugzilla.redhat.com/show_bug.cgi?id=585674 and
| # in particular the patch attached there, which was applied to glibc CVS as
| # "Restore locking in free_check." between 1.11 and 1.11.1.
| vswitchd=$abs_top_builddir/vswitchd/ovs-vswitchd
| glibc=`ldd $vswitchd | sed -n 's/^ libc\.[^ ]* => \([^ ]*\) .*/\1/p'`
| glibc_version=`$glibc | sed -n '1s/.*version \([0-9]\{1,\}\.[0-9]\{1,\}\).*/\1/p'`
| case $glibc_version in
| 2.[0-9] | 2.1[01]) mcheck=disabled ;;
| *) mcheck=enabled ;;
| esac
| if test $mcheck = enabled; then
| MALLOC_CHECK_=2; export MALLOC_CHECK_
| else
| echo >&2 "glibc $glibc_version detected, disabling memory checking"
| fi
| ;;
| FreeBSD)
| case `uname -r` in
| [789].*)
| MALLOC_CONF=AJ
| ;;
| *)
| MALLOC_CONF=abort:true,junk:true,redzone:true
| ;;
| esac
| export MALLOC_CONF
| esac
|
| # The name of loopback interface
| case `uname` in
| Linux)
| LOOPBACK_INTERFACE=lo
| ;;
| FreeBSD|NetBSD)
| LOOPBACK_INTERFACE=lo0
| ;;
| esac
|
| # Check for MINGW platform.
| case `uname` in
| MINGW*)
| IS_WIN32="yes"
| ;;
| *)
| IS_WIN32="no"
| ;;
| esac

## ---------------- ##
## Tested programs. ##
## ---------------- ##

./testsuite.at:1: /«PKGBUILDDIR»/vswitchd/ovs-vswitchd --version
ovs-vswitchd (Open vSwitch) 2.3.2
Compiled Jul 6 2015 11:34:56
OpenFlow versions 0x1:0x1

./testsuite.at:1: /«PKGBUILDDIR»/utilities/ovs-vsctl --version
ovs-vsctl (Open vSwitch) 2.3.2
Compiled Jul 6 2015 11:34:35
DB Schema 7.6.2

./testsuite.at:1: /usr/bin/perl --version

This is perl 5, version 20, subversion 2 (v5.20.2) built for powerpc-linux-gnu-thread-multi-64int
(with 51 registered patches, see perl -V for more detail)

Copyright 1987-2015, Larry Wall

Perl may be copied only under the terms of either the Artistic License or the
GNU General Public License, which may be found in the Perl 5 source kit.

Complete documentation for Perl, including FAQ lists, should be found on
this system using "man perl" or "perldoc perl". If you have access to the
Internet, point your browser at http://www.perl.org/, the Perl Home Page.

## ------------------ ##
## Running the tests. ##
## ------------------ ##
testsuite: starting at: Mon Jul 6 11:44:19 UTC 2015
testsuite: ending at: Mon Jul 6 11:44:22 UTC 2015
testsuite: test suite duration: 0h 0m 3s

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

ERROR: 1 test was run,
1 failed unexpectedly.

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

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

 717: ofproto-dpif.at:203 ofproto-dpif, balance-tcp bonding, different recirc flow

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

# -*- compilation -*-
717. ofproto-dpif.at:203: testing ofproto-dpif, balance-tcp bonding, different recirc flow ...
./ofproto-dpif.at:204: ovsdb-tool create conf.db $abs_top_srcdir/vswitchd/vswitch.ovsschema
./ofproto-dpif.at:204: ovsdb-server --detach --no-chdir --pidfile --log-file --remote=punix:$OVS_RUNDIR/db.sock
stderr:
2015-07-06T11:44:19Z|00001|vlog|INFO|opened log file /«PKGBUILDDIR»/tests/testsuite.dir/0717/ovsdb-server.log
./ofproto-dpif.at:204: sed < stderr '
/vlog|INFO|opened log file/d
/ovsdb_server|INFO|ovsdb-server (Open vSwitch)/d'
./ofproto-dpif.at:204: ovs-vsctl --no-wait init
./ofproto-dpif.at:204: ovs-vswitchd --detach --no-chdir --pidfile --enable-dummy --disable-system --log-file -vvconn -vofproto_dpif
stderr:
2015-07-06T11:44:19Z|00001|vlog|INFO|opened log file /«PKGBUILDDIR»/tests/testsuite.dir/0717/ovs-vswitchd.log
2015-07-06T11:44:19Z|00002|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/db.sock: connecting...
2015-07-06T11:44:19Z|00003|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/db.sock: connected
./ofproto-dpif.at:204: 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'
./ofproto-dpif.at:204: 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,OpenFlow14,OpenFlow15] fail-mode=secure -- add-bond br0 bond0 p1 p2 bond_mode=balance-tcp lacp=active \
       other-config:lacp-time=fast other-config:bond-rebalance-interval=0 -- \
   set interface p1 type=dummy options:pstream=punix:$OVS_RUNDIR/p1.sock ofport_request=1 -- \
   set interface p2 type=dummy options:pstream=punix:$OVS_RUNDIR/p2.sock 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=standalone -- \
   add-bond br1 bond1 p3 p4 bond_mode=balance-tcp lacp=active \
       other-config:lacp-time=fast other-config:bond-rebalance-interval=0 -- \
   set interface p3 type=dummy options:stream=unix:$OVS_RUNDIR/p1.sock ofport_request=3 -- \
   set interface p4 type=dummy options:stream=unix:$OVS_RUNDIR/p2.sock ofport_request=4 -- \
   add-port br1 br1- -- set interface br1- type=patch options:peer=br1+ ofport_request=100 -- \
   add-br br-int -- \
   set bridge br-int other-config:hwaddr=aa:77:aa:77:00:00 -- \
   set bridge br-int datapath-type=dummy other-config:datapath-id=1235 \
       fail-mode=secure -- \
   add-port br-int br1+ -- set interface br1+ type=patch options:peer=br1- ofport_request=101 -- \
   add-port br-int p5 -- set interface p5 ofport_request=5 type=dummy

./ofproto-dpif.at:225: ovs-appctl netdev-dummy/set-admin-state up
./ofproto-dpif.at:229: ovs_wait_cond () { test `ovs-appctl bond/show | grep -- "may_enable: true" | wc -l` -ge 4
}
ovs_wait
stderr:
stdout:
./ofproto-dpif.at:237: ovs-ofctl add-flows br-int flows.txt
./ofproto-dpif.at:241: ovs-appctl netdev-dummy/receive p5 "in_port(5),eth(src=50:54:00:00:00:05,dst=50:54:00:00:01:00),eth_type(0x0800),ipv4(src=10.0.0.2,dst=10.0.0.1,proto=1,tos=0,ttl=64,frag=no)"
./ofproto-dpif.at:244: ovs-appctl upcall/disable-megaflows
./ofproto-dpif.at:247: ovs-appctl upcall/enable-megaflows
./ofproto-dpif.at:253: ovs-appctl bridge/dump-flows br1 | ofctl_strip | grep -- "n_packets" | grep -- "table_id" | sed -e 's/output:[0-9]\+/output/'
--- - 2015-07-06 11:44:21.997656673 +0000
+++ /«PKGBUILDDIR»/tests/testsuite.dir/at-groups/717/stdout 2015-07-06 11:44:21.995196044 +0000
@@ -1,2 +1,2 @@
-table_id=254, n_packets=1, n_bytes=64, priority=20,recirc_id=0x12d,dp_hash=0xcf/0xff,actions=output
+table_id=254, n_packets=1, n_bytes=64, priority=20,recirc_id=0x12c,dp_hash=0x72/0xff,actions=output

ovsdb-server.log:
> 2015-07-06T11:44:19.773Z|00001|vlog|INFO|opened log file /«PKGBUILDDIR»/tests/testsuite.dir/0717/ovsdb-server.log
> 2015-07-06T11:44:19.778Z|00002|ovsdb_server|INFO|ovsdb-server (Open vSwitch) 2.3.2
ovs-vswitchd.log:
> 2015-07-06T11:44:19.794Z|00001|vlog|INFO|opened log file /«PKGBUILDDIR»/tests/testsuite.dir/0717/ovs-vswitchd.log
> 2015-07-06T11:44:19.795Z|00002|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/db.sock: connecting...
> 2015-07-06T11:44:19.795Z|00003|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/db.sock: connected
> 2015-07-06T11:44:19.796Z|00004|bridge|INFO|ovs-vswitchd (Open vSwitch) 2.3.2
> 2015-07-06T11:44:19.816Z|00005|ofproto_dpif|INFO|dummy@ovs-dummy: Datapath supports recirculation
> 2015-07-06T11:44:19.816Z|00006|ofproto_dpif|INFO|dummy@ovs-dummy: MPLS label stack length probed as 3
> 2015-07-06T11:44:19.819Z|00007|bridge|INFO|bridge br-int: added interface br1+ on port 101
> 2015-07-06T11:44:19.819Z|00008|bridge|INFO|bridge br-int: added interface p5 on port 5
> 2015-07-06T11:44:19.819Z|00009|bridge|INFO|bridge br-int: added interface br-int on port 65534
> 2015-07-06T11:44:19.819Z|00010|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p1.sock: connecting...
> 2015-07-06T11:44:19.819Z|00011|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p1.sock: connection attempt failed (No such file or directory)
> 2015-07-06T11:44:19.819Z|00012|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p1.sock: waiting 1 seconds before reconnect
> 2015-07-06T11:44:19.819Z|00013|bridge|INFO|bridge br1: added interface p3 on port 3
> 2015-07-06T11:44:19.819Z|00014|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p2.sock: connecting...
> 2015-07-06T11:44:19.819Z|00015|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p2.sock: connection attempt failed (No such file or directory)
> 2015-07-06T11:44:19.819Z|00016|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p2.sock: waiting 1 seconds before reconnect
> 2015-07-06T11:44:19.819Z|00017|bridge|INFO|bridge br1: added interface p4 on port 4
> 2015-07-06T11:44:19.819Z|00018|bridge|INFO|bridge br1: added interface br1- on port 100
> 2015-07-06T11:44:19.819Z|00019|bridge|INFO|bridge br1: added interface br1 on port 65534
> 2015-07-06T11:44:19.819Z|00020|bridge|INFO|bridge br0: added interface p2 on port 2
> 2015-07-06T11:44:19.819Z|00021|bridge|INFO|bridge br0: added interface p1 on port 1
> 2015-07-06T11:44:19.819Z|00022|bridge|INFO|bridge br0: added interface br0 on port 65534
> 2015-07-06T11:44:19.819Z|00023|bridge|INFO|bridge br-int: using datapath ID 0000aa77aa770000
> 2015-07-06T11:44:19.819Z|00024|connmgr|INFO|br-int: added service controller "punix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/br-int.mgmt"
> 2015-07-06T11:44:19.820Z|00025|bridge|INFO|bridge br1: using datapath ID 0000aa66aa660000
> 2015-07-06T11:44:19.820Z|00026|connmgr|INFO|br1: added service controller "punix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/br1.mgmt"
> 2015-07-06T11:44:19.820Z|00027|bridge|INFO|bridge br0: using datapath ID fedcba9876543210
> 2015-07-06T11:44:19.820Z|00028|connmgr|INFO|br0: added service controller "punix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/br0.mgmt"
> 2015-07-06T11:44:19.824Z|00029|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2015-07-06T11:44:19.824Z|00030|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2015-07-06T11:44:19.824Z|00031|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2015-07-06T11:44:19.833Z|00032|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2015-07-06T11:44:19.833Z|00033|ofproto_dpif|DBG|need revalidate in ofproto_wait_cb()
> 2015-07-06T11:44:20.822Z|00034|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p1.sock: connecting...
> 2015-07-06T11:44:20.822Z|00035|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p1.sock: connected
> 2015-07-06T11:44:20.822Z|00036|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p2.sock: connecting...
> 2015-07-06T11:44:20.822Z|00037|reconnect|INFO|unix:/«PKGBUILDDIR»/tests/testsuite.dir/0717/p2.sock: connected
> 2015-07-06T11:44:21.823Z|00038|bond|INFO|interface p1: link state up
> 2015-07-06T11:44:21.823Z|00039|bond|INFO|interface p1: enabled
> 2015-07-06T11:44:21.823Z|00040|bond|INFO|interface p2: link state up
> 2015-07-06T11:44:21.823Z|00041|bond|INFO|interface p2: enabled
> 2015-07-06T11:44:21.823Z|00042|bond|INFO|bond bond0: active interface is now p1
> 2015-07-06T11:44:21.824Z|00043|bond|INFO|interface p3: link state up
> 2015-07-06T11:44:21.824Z|00044|bond|INFO|interface p3: enabled
> 2015-07-06T11:44:21.824Z|00045|bond|INFO|interface p4: link state up
> 2015-07-06T11:44:21.824Z|00046|bond|INFO|interface p4: enabled
> 2015-07-06T11:44:21.824Z|00047|bond|INFO|bond bond1: active interface is now p3
> 2015-07-06T11:44:21.961Z|00048|vconn|DBG|unix: sent (Success): OFPT_HELLO (OF1.3) (xid=0x1):
> version bitmap: 0x01, 0x02, 0x03, 0x04
> 2015-07-06T11:44:21.961Z|00049|vconn|DBG|unix: received: OFPT_HELLO (xid=0x1):
> version bitmap: 0x01
> 2015-07-06T11:44:21.961Z|00050|vconn|DBG|unix: negotiated OpenFlow version 0x01 (we support version 0x04 and earlier, peer supports version 0x01)
> 2015-07-06T11:44:21.961Z|00051|vconn|DBG|unix: received: NXT_FLOW_MOD_TABLE_ID (xid=0x2): enable
> 2015-07-06T11:44:21.961Z|00052|vconn|DBG|unix: received: OFPT_BARRIER_REQUEST (xid=0x3):
> 2015-07-06T11:44:21.961Z|00053|vconn|DBG|unix: sent (Success): OFPT_BARRIER_REPLY (xid=0x3):
> 2015-07-06T11:44:21.961Z|00054|vconn|DBG|unix: received: OFPT_FLOW_MOD (xid=0x4): ADD priority=1,in_port=5 actions=mod_vlan_vid:1,output:101
> 2015-07-06T11:44:21.961Z|00055|vconn|DBG|unix: received: OFPT_BARRIER_REQUEST (xid=0x5):
> 2015-07-06T11:44:21.961Z|00056|vconn|DBG|unix: sent (Success): OFPT_BARRIER_REPLY (xid=0x5):
> 2015-07-06T11:44:21.961Z|00057|vconn|DBG|unix: received: OFPT_FLOW_MOD (xid=0x6): ADD priority=2,in_port=5,dl_vlan=1 actions=drop
> 2015-07-06T11:44:21.962Z|00058|vconn|DBG|unix: received: OFPT_BARRIER_REQUEST (xid=0x7):
> 2015-07-06T11:44:21.962Z|00059|vconn|DBG|unix: sent (Success): OFPT_BARRIER_REPLY (xid=0x7):
> 2015-07-06T11:44:21.962Z|00060|connmgr|INFO|br-int<->unix: 2 flow_mods in the last 0 s (2 adds)
717. ofproto-dpif.at:203: 717. ofproto-dpif, balance-tcp bonding, different recirc flow (ofproto-dpif.at:203): FAILED (ofproto-dpif.at:253)