nameko travis flaky test?

gistfile1.txt

# https://s3.amazonaws.com/archive.travis-ci.org/jobs/127208999/log.txt

Using worker: worker-linux-docker-f359b10b.prod.travis-ci.org:travis-linux-4

travis_fold:start:system_info
Build system information
Build language: python
Build group: stable
Build dist: precise
Build image provisioning date and time
Thu Feb  5 15:09:33 UTC 2015
Operating System Details
Distributor ID:	Ubuntu
Description:	Ubuntu 12.04.5 LTS
Release:	12.04
Codename:	precise
Linux Version
3.13.0-29-generic
Cookbooks Version
a68419e https://github.com/travis-ci/travis-cookbooks/tree/a68419e
GCC version
gcc (Ubuntu/Linaro 4.6.3-1ubuntu5) 4.6.3
Copyright (C) 2011 Free Software Foundation, Inc.
This is free software; see the source for copying conditions.  There is NO
warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.

LLVM version
clang version 3.4 (tags/RELEASE_34/final)
Target: x86_64-unknown-linux-gnu
Thread model: posix
Pre-installed Ruby versions
ruby-1.9.3-p551
Pre-installed Node.js versions
v0.10.36
Pre-installed Go versions
1.4.1
Redis version
redis-server 2.8.19
riak version
2.0.2
MongoDB version
MongoDB 2.4.12
CouchDB version
couchdb 1.6.1
Neo4j version
1.9.4
RabbitMQ Version
3.4.3
ElasticSearch version
1.4.0
Installed Sphinx versions
2.0.10
2.1.9
2.2.6
Default Sphinx version
2.2.6
Installed Firefox version
firefox 31.0esr
PhantomJS version
1.9.8
ant -version
Apache Ant(TM) version 1.8.2 compiled on December 3 2011
mvn -version
Apache Maven 3.2.5 (12a6b3acb947671f09b81f49094c53f426d8cea1; 2014-12-14T17:29:23+00:00)
Maven home: /usr/local/maven
Java version: 1.7.0_76, vendor: Oracle Corporation
Java home: /usr/lib/jvm/java-7-oracle/jre
Default locale: en_US, platform encoding: ANSI_X3.4-1968
OS name: "linux", version: "3.13.0-29-generic", arch: "amd64", family: "unix"
travis_fold:end:system_info

travis_fold:start:fix.CVE-2015-7547
$ export DEBIAN_FRONTEND=noninteractive
W: Size of file /var/lib/apt/lists/us.archive.ubuntu.com_ubuntu_dists_precise-backports_multiverse_source_Sources.gz is not what the server reported 5886 5888
W: Size of file /var/lib/apt/lists/ppa.launchpad.net_ubuntugis_ppa_ubuntu_dists_precise_main_binary-amd64_Packages.gz is not what the server reported 36669 36677
W: Size of file /var/lib/apt/lists/ppa.launchpad.net_ubuntugis_ppa_ubuntu_dists_precise_main_binary-i386_Packages.gz is not what the server reported 36729 36733
Reading package lists...
Building dependency tree...
Reading state information...
The following extra packages will be installed:
  libc-bin libc-dev-bin libc6-dev
Suggested packages:
  glibc-doc
The following packages will be upgraded:
  libc-bin libc-dev-bin libc6 libc6-dev
4 upgraded, 0 newly installed, 0 to remove and 224 not upgraded.
Need to get 8,844 kB of archives.
After this operation, 9,216 B disk space will be freed.
Get:1 http://us.archive.ubuntu.com/ubuntu/ precise-updates/main libc6-dev amd64 2.15-0ubuntu10.13 [2,943 kB]
Get:2 http://us.archive.ubuntu.com/ubuntu/ precise-updates/main libc-dev-bin amd64 2.15-0ubuntu10.13 [84.7 kB]
Get:3 http://us.archive.ubuntu.com/ubuntu/ precise-updates/main libc-bin amd64 2.15-0ubuntu10.13 [1,179 kB]
Get:4 http://us.archive.ubuntu.com/ubuntu/ precise-updates/main libc6 amd64 2.15-0ubuntu10.13 [4,637 kB]
Fetched 8,844 kB in 0s (29.5 MB/s)
Preconfiguring packages ...
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 72431 files and directories currently installed.)
Preparing to replace libc6-dev 2.15-0ubuntu10.10 (using .../libc6-dev_2.15-0ubuntu10.13_amd64.deb) ...
Unpacking replacement libc6-dev ...
Preparing to replace libc-dev-bin 2.15-0ubuntu10.10 (using .../libc-dev-bin_2.15-0ubuntu10.13_amd64.deb) ...
Unpacking replacement libc-dev-bin ...
Preparing to replace libc-bin 2.15-0ubuntu10.10 (using .../libc-bin_2.15-0ubuntu10.13_amd64.deb) ...
Unpacking replacement libc-bin ...
Processing triggers for man-db ...
Setting up libc-bin (2.15-0ubuntu10.13) ...
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 72431 files and directories currently installed.)
Preparing to replace libc6 2.15-0ubuntu10.10 (using .../libc6_2.15-0ubuntu10.13_amd64.deb) ...
Unpacking replacement libc6 ...
Setting up libc6 (2.15-0ubuntu10.13) ...
Setting up libc-dev-bin (2.15-0ubuntu10.13) ...
Setting up libc6-dev (2.15-0ubuntu10.13) ...
Processing triggers for libc-bin ...
ldconfig deferred processing now taking place
travis_fold:end:fix.CVE-2015-7547
travis_fold:start:git.checkout
travis_time:start:27ecfb17
$ git clone --depth=50 https://github.com/onefinestay/nameko.git onefinestay/nameko
Cloning into 'onefinestay/nameko'...
remote: Counting objects: 4053, done.
remote: Compressing objects:   0% (1/1487)   
remote: Compressing objects:   1% (15/1487)   
remote: Compressing objects:   2% (30/1487)   
remote: Compressing objects:   3% (45/1487)   
remote: Compressing objects:   4% (60/1487)   
remote: Compressing objects:   5% (75/1487)   
remote: Compressing objects:   6% (90/1487)   
remote: Compressing objects:   7% (105/1487)   
remote: Compressing objects:   8% (119/1487)   
remote: Compressing objects:   9% (134/1487)   
remote: Compressing objects:  10% (149/1487)   
remote: Compressing objects:  11% (164/1487)   
remote: Compressing objects:  12% (179/1487)   
remote: Compressing objects:  13% (194/1487)   
remote: Compressing objects:  14% (209/1487)   
remote: Compressing objects:  15% (224/1487)   
remote: Compressing objects:  16% (238/1487)   
remote: Compressing objects:  17% (253/1487)   
remote: Compressing objects:  18% (268/1487)   
remote: Compressing objects:  19% (283/1487)   
remote: Compressing objects:  20% (298/1487)   
remote: Compressing objects:  21% (313/1487)   
remote: Compressing objects:  22% (328/1487)   
remote: Compressing objects:  23% (343/1487)   
remote: Compressing objects:  24% (357/1487)   
remote: Compressing objects:  25% (372/1487)   
remote: Compressing objects:  26% (387/1487)   
remote: Compressing objects:  27% (402/1487)   
remote: Compressing objects:  28% (417/1487)   
remote: Compressing objects:  29% (432/1487)   
remote: Compressing objects:  30% (447/1487)   
remote: Compressing objects:  31% (461/1487)   
remote: Compressing objects:  32% (476/1487)   
remote: Compressing objects:  33% (491/1487)   
remote: Compressing objects:  34% (506/1487)   
remote: Compressing objects:  35% (521/1487)   
remote: Compressing objects:  36% (536/1487)   
remote: Compressing objects:  37% (551/1487)   
remote: Compressing objects:  38% (566/1487)   
remote: Compressing objects:  39% (580/1487)   
remote: Compressing objects:  40% (595/1487)   
remote: Compressing objects:  41% (610/1487)   
remote: Compressing objects:  42% (625/1487)   
remote: Compressing objects:  43% (640/1487)   
remote: Compressing objects:  44% (655/1487)   
remote: Compressing objects:  45% (670/1487)   
remote: Compressing objects:  46% (685/1487)   
remote: Compressing objects:  47% (699/1487)   
remote: Compressing objects:  48% (714/1487)   
remote: Compressing objects:  49% (729/1487)   
remote: Compressing objects:  50% (744/1487)   
remote: Compressing objects:  51% (759/1487)   
remote: Compressing objects:  52% (774/1487)   
remote: Compressing objects:  53% (789/1487)   
remote: Compressing objects:  54% (803/1487)   
remote: Compressing objects:  55% (818/1487)   
remote: Compressing objects:  56% (833/1487)   
remote: Compressing objects:  57% (848/1487)   
remote: Compressing objects:  58% (863/1487)   
remote: Compressing objects:  59% (878/1487)   
remote: Compressing objects:  60% (893/1487)   
remote: Compressing objects:  61% (908/1487)   
remote: Compressing objects:  62% (922/1487)   
remote: Compressing objects:  63% (937/1487)   
remote: Compressing objects:  64% (952/1487)   
remote: Compressing objects:  65% (967/1487)   
remote: Compressing objects:  66% (982/1487)   
remote: Compressing objects:  67% (997/1487)   
remote: Compressing objects:  68% (1012/1487)   
remote: Compressing objects:  69% (1027/1487)   
remote: Compressing objects:  70% (1041/1487)   
remote: Compressing objects:  71% (1056/1487)   
remote: Compressing objects:  72% (1071/1487)   
remote: Compressing objects:  73% (1086/1487)   
remote: Compressing objects:  74% (1101/1487)   
remote: Compressing objects:  75% (1116/1487)   
remote: Compressing objects:  76% (1131/1487)   
remote: Compressing objects:  77% (1145/1487)   
remote: Compressing objects:  78% (1160/1487)   
remote: Compressing objects:  79% (1175/1487)   
remote: Compressing objects:  80% (1190/1487)   
remote: Compressing objects:  81% (1205/1487)   
remote: Compressing objects:  82% (1220/1487)   
remote: Compressing objects:  83% (1235/1487)   
remote: Compressing objects:  84% (1250/1487)   
remote: Compressing objects:  85% (1264/1487)   
remote: Compressing objects:  86% (1279/1487)   
remote: Compressing objects:  87% (1294/1487)   
remote: Compressing objects:  88% (1309/1487)   
remote: Compressing objects:  89% (1324/1487)   
remote: Compressing objects:  90% (1339/1487)   
remote: Compressing objects:  91% (1354/1487)   
remote: Compressing objects:  92% (1369/1487)   
remote: Compressing objects:  93% (1383/1487)   
remote: Compressing objects:  94% (1398/1487)   
remote: Compressing objects:  95% (1413/1487)   
remote: Compressing objects:  96% (1428/1487)   
remote: Compressing objects:  97% (1443/1487)   
remote: Compressing objects:  98% (1458/1487)   
remote: Compressing objects:  99% (1473/1487)   
remote: Compressing objects: 100% (1487/1487)   
remote: Compressing objects: 100% (1487/1487), done.
Receiving objects:   0% (1/4053)   
Receiving objects:   1% (41/4053)   
Receiving objects:   2% (82/4053)   
Receiving objects:   3% (122/4053)   
Receiving objects:   4% (163/4053)   
Receiving objects:   5% (203/4053)   
Receiving objects:   6% (244/4053)   
Receiving objects:   7% (284/4053)   
Receiving objects:   8% (325/4053)   
Receiving objects:   9% (365/4053)   
Receiving objects:  10% (406/4053)   
Receiving objects:  11% (446/4053)   
Receiving objects:  12% (487/4053)   
Receiving objects:  13% (527/4053)   
Receiving objects:  14% (568/4053)   
Receiving objects:  15% (608/4053)   
Receiving objects:  16% (649/4053)   
Receiving objects:  17% (690/4053)   
Receiving objects:  18% (730/4053)   
Receiving objects:  19% (771/4053)   
Receiving objects:  20% (811/4053)   
Receiving objects:  21% (852/4053)   
Receiving objects:  22% (892/4053)   
Receiving objects:  23% (933/4053)   
Receiving objects:  24% (973/4053)   
Receiving objects:  25% (1014/4053)   
Receiving objects:  26% (1054/4053)   
Receiving objects:  27% (1095/4053)   
Receiving objects:  28% (1135/4053)   
Receiving objects:  29% (1176/4053)   
Receiving objects:  30% (1216/4053)   
Receiving objects:  31% (1257/4053)   
Receiving objects:  32% (1297/4053)   
Receiving objects:  33% (1338/4053)   
Receiving objects:  34% (1379/4053)   
Receiving objects:  35% (1419/4053)   
Receiving objects:  36% (1460/4053)   
Receiving objects:  37% (1500/4053)   
Receiving objects:  38% (1541/4053)   
Receiving objects:  39% (1581/4053)   
Receiving objects:  40% (1622/4053)   
Receiving objects:  41% (1662/4053)   
Receiving objects:  42% (1703/4053)   
Receiving objects:  43% (1743/4053)   
Receiving objects:  44% (1784/4053)   
Receiving objects:  45% (1824/4053)   
Receiving objects:  46% (1865/4053)   
Receiving objects:  47% (1905/4053)   
Receiving objects:  48% (1946/4053)   
Receiving objects:  49% (1986/4053)   
Receiving objects:  50% (2027/4053)   
Receiving objects:  51% (2068/4053)   
Receiving objects:  52% (2108/4053)   
Receiving objects:  53% (2149/4053)   
Receiving objects:  54% (2189/4053)   
Receiving objects:  55% (2230/4053)   
Receiving objects:  56% (2270/4053)   
Receiving objects:  57% (2311/4053)   
Receiving objects:  58% (2351/4053)   
Receiving objects:  59% (2392/4053)   
Receiving objects:  60% (2432/4053)   
Receiving objects:  61% (2473/4053)   
Receiving objects:  62% (2513/4053)   
Receiving objects:  63% (2554/4053)   
Receiving objects:  64% (2594/4053)   
Receiving objects:  65% (2635/4053)   
Receiving objects:  66% (2675/4053)   
Receiving objects:  67% (2716/4053)   
Receiving objects:  68% (2757/4053)   
Receiving objects:  69% (2797/4053)   
Receiving objects:  70% (2838/4053)   
Receiving objects:  71% (2878/4053)   
Receiving objects:  72% (2919/4053)   
Receiving objects:  73% (2959/4053)   
Receiving objects:  74% (3000/4053)   
Receiving objects:  75% (3040/4053)   
Receiving objects:  76% (3081/4053)   
Receiving objects:  77% (3121/4053)   
Receiving objects:  78% (3162/4053)   
Receiving objects:  79% (3202/4053)   
Receiving objects:  80% (3243/4053)   
Receiving objects:  81% (3283/4053)   
Receiving objects:  82% (3324/4053)   
Receiving objects:  83% (3364/4053)   
Receiving objects:  84% (3405/4053)   
Receiving objects:  85% (3446/4053)   
Receiving objects:  86% (3486/4053)   
Receiving objects:  87% (3527/4053)   
remote: Total 4053 (delta 2930), reused 3605 (delta 2555), pack-reused 0
Receiving objects:  88% (3567/4053)   
Receiving objects:  89% (3608/4053)   
Receiving objects:  90% (3648/4053)   
Receiving objects:  91% (3689/4053)   
Receiving objects:  92% (3729/4053)   
Receiving objects:  93% (3770/4053)   
Receiving objects:  94% (3810/4053)   
Receiving objects:  95% (3851/4053)   
Receiving objects:  96% (3891/4053)   
Receiving objects:  97% (3932/4053)   
Receiving objects:  98% (3972/4053)   
Receiving objects:  99% (4013/4053)   
Receiving objects: 100% (4053/4053)   
Receiving objects: 100% (4053/4053), 720.55 KiB | 0 bytes/s, done.
Resolving deltas:   0% (0/2930)   
Resolving deltas:   1% (40/2930)   
Resolving deltas:   2% (62/2930)   
Resolving deltas:   3% (94/2930)   
Resolving deltas:   4% (132/2930)   
Resolving deltas:   5% (148/2930)   
Resolving deltas:   6% (192/2930)   
Resolving deltas:   7% (208/2930)   
Resolving deltas:  10% (304/2930)   
Resolving deltas:  11% (337/2930)   
Resolving deltas:  12% (369/2930)   
Resolving deltas:  13% (385/2930)   
Resolving deltas:  14% (422/2930)   
Resolving deltas:  17% (515/2930)   
Resolving deltas:  18% (531/2930)   
Resolving deltas:  19% (574/2930)   
Resolving deltas:  20% (600/2930)   
Resolving deltas:  21% (643/2930)   
Resolving deltas:  22% (648/2930)   
Resolving deltas:  24% (716/2930)   
Resolving deltas:  25% (739/2930)   
Resolving deltas:  26% (765/2930)   
Resolving deltas:  27% (818/2930)   
Resolving deltas:  28% (823/2930)   
Resolving deltas:  30% (897/2930)   
Resolving deltas:  31% (922/2930)   
Resolving deltas:  32% (941/2930)   
Resolving deltas:  33% (988/2930)   
Resolving deltas:  34% (1000/2930)   
Resolving deltas:  35% (1028/2930)   
Resolving deltas:  45% (1337/2930)   
Resolving deltas:  46% (1356/2930)   
Resolving deltas:  47% (1395/2930)   
Resolving deltas:  50% (1480/2930)   
Resolving deltas:  54% (1589/2930)   
Resolving deltas:  55% (1635/2930)   
Resolving deltas:  57% (1686/2930)   
Resolving deltas:  58% (1706/2930)   
Resolving deltas:  59% (1731/2930)   
Resolving deltas:  60% (1759/2930)   
Resolving deltas:  61% (1797/2930)   
Resolving deltas:  62% (1817/2930)   
Resolving deltas:  63% (1846/2930)   
Resolving deltas:  64% (1895/2930)   
Resolving deltas:  66% (1939/2930)   
Resolving deltas:  67% (1971/2930)   
Resolving deltas:  68% (2012/2930)   
Resolving deltas:  69% (2022/2930)   
Resolving deltas:  70% (2079/2930)   
Resolving deltas:  71% (2084/2930)   
Resolving deltas:  72% (2123/2930)   
Resolving deltas:  73% (2140/2930)   
Resolving deltas:  74% (2171/2930)   
Resolving deltas:  75% (2211/2930)   
Resolving deltas:  76% (2232/2930)   
Resolving deltas:  77% (2276/2930)   
Resolving deltas:  78% (2307/2930)   
Resolving deltas:  79% (2322/2930)   
Resolving deltas:  80% (2348/2930)   
Resolving deltas:  81% (2374/2930)   
Resolving deltas:  82% (2404/2930)   
Resolving deltas:  83% (2437/2930)   
Resolving deltas:  84% (2465/2930)   
Resolving deltas:  85% (2502/2930)   
Resolving deltas:  86% (2527/2930)   
Resolving deltas:  87% (2553/2930)   
Resolving deltas:  88% (2589/2930)   
Resolving deltas:  89% (2616/2930)   
Resolving deltas:  90% (2646/2930)   
Resolving deltas:  91% (2667/2930)   
Resolving deltas:  92% (2714/2930)   
Resolving deltas:  93% (2729/2930)   
Resolving deltas:  94% (2761/2930)   
Resolving deltas:  95% (2788/2930)   
Resolving deltas:  96% (2823/2930)   
Resolving deltas:  97% (2843/2930)   
Resolving deltas:  98% (2877/2930)   
Resolving deltas:  99% (2903/2930)   
Resolving deltas: 100% (2930/2930)   
Resolving deltas: 100% (2930/2930), done.
Checking connectivity... done.

travis_time:end:27ecfb17:start=1462193590449260618,finish=1462193591238283230,duration=789022612
$ cd onefinestay/nameko
travis_time:start:0c23da14
$ git fetch origin +refs/pull/318/merge:
remote: Counting objects: 25, done.
remote: Compressing objects:   4% (1/23)   
remote: Compressing objects:   8% (2/23)   
remote: Compressing objects:  13% (3/23)   
remote: Compressing objects:  17% (4/23)   
remote: Compressing objects:  21% (5/23)   
remote: Compressing objects:  26% (6/23)   
remote: Compressing objects:  30% (7/23)   
remote: Compressing objects:  34% (8/23)   
remote: Compressing objects:  39% (9/23)   
remote: Compressing objects:  43% (10/23)   
remote: Compressing objects:  47% (11/23)   
remote: Compressing objects:  52% (12/23)   
remote: Compressing objects:  56% (13/23)   
remote: Compressing objects:  60% (14/23)   
remote: Compressing objects:  65% (15/23)   
remote: Compressing objects:  69% (16/23)   
remote: Compressing objects:  73% (17/23)   
remote: Compressing objects:  78% (18/23)   
remote: Compressing objects:  82% (19/23)   
remote: Compressing objects:  86% (20/23)   
remote: Compressing objects:  91% (21/23)   
remote: Compressing objects:  95% (22/23)   
remote: Compressing objects: 100% (23/23)   
remote: Compressing objects: 100% (23/23), done.
remote: Total 25 (delta 19), reused 7 (delta 2), pack-reused 0
Unpacking objects:   4% (1/25)   
Unpacking objects:   8% (2/25)   
Unpacking objects:  12% (3/25)   
Unpacking objects:  16% (4/25)   
Unpacking objects:  20% (5/25)   
Unpacking objects:  24% (6/25)   
Unpacking objects:  28% (7/25)   
Unpacking objects:  32% (8/25)   
Unpacking objects:  36% (9/25)   
Unpacking objects:  40% (10/25)   
Unpacking objects:  44% (11/25)   
Unpacking objects:  48% (12/25)   
Unpacking objects:  52% (13/25)   
Unpacking objects:  56% (14/25)   
Unpacking objects:  60% (15/25)   
Unpacking objects:  64% (16/25)   
Unpacking objects:  68% (17/25)   
Unpacking objects:  72% (18/25)   
Unpacking objects:  76% (19/25)   
Unpacking objects:  80% (20/25)   
Unpacking objects:  84% (21/25)   
Unpacking objects:  88% (22/25)   
Unpacking objects:  92% (23/25)   
Unpacking objects:  96% (24/25)   
Unpacking objects: 100% (25/25)   
Unpacking objects: 100% (25/25), done.
From https://github.com/onefinestay/nameko
 * branch            refs/pull/318/merge -> FETCH_HEAD

travis_time:end:0c23da14:start=1462193591244301209,finish=1462193591656945510,duration=412644301
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout
travis_fold:start:apt
Installing APT Packages (BETA)
$ export DEBIAN_FRONTEND=noninteractive
travis_time:start:30f76ca1
$ sudo -E apt-get -yq update &>> ~/apt-get-update.log

travis_time:end:30f76ca1:start=1462193591677962684,finish=1462193599003626352,duration=7325663668
travis_time:start:07346b7a
$ sudo -E apt-get -yq --no-install-suggests --no-install-recommends --force-yes install libenchant-dev
Reading package lists...
Building dependency tree...
Reading state information...
The following extra packages will be installed:
  aspell aspell-en dictionaries-common libaspell15 libenchant1c2a
  libhunspell-1.3-0 libtext-iconv-perl
Suggested packages:
  aspell-doc spellutils ispell jed-extra libenchant-voikko
Recommended packages:
  enchant hunspell-en-us hunspell-dictionary myspell-dictionary
The following NEW packages will be installed:
  aspell aspell-en dictionaries-common libaspell15 libenchant-dev
  libenchant1c2a libhunspell-1.3-0 libtext-iconv-perl
0 upgraded, 8 newly installed, 0 to remove and 224 not upgraded.
Need to get 1,529 kB of archives.
After this operation, 5,245 kB of additional disk space will be used.
Get:1 http://us.archive.ubuntu.com/ubuntu/ precise/main libhunspell-1.3-0 amd64 1.3.2-4 [142 kB]
Get:2 http://us.archive.ubuntu.com/ubuntu/ precise/main libtext-iconv-perl amd64 1.7-5 [15.6 kB]
Get:3 http://us.archive.ubuntu.com/ubuntu/ precise/main libaspell15 amd64 0.60.7~20110707-1 [639 kB]
Get:4 http://us.archive.ubuntu.com/ubuntu/ precise/main dictionaries-common all 1.12.1ubuntu2 [246 kB]
Get:5 http://us.archive.ubuntu.com/ubuntu/ precise/main aspell amd64 0.60.7~20110707-1 [92.5 kB]
Get:6 http://us.archive.ubuntu.com/ubuntu/ precise/main aspell-en all 6.0-0-6ubuntu2 [250 kB]
Get:7 http://us.archive.ubuntu.com/ubuntu/ precise/main libenchant1c2a amd64 1.6.0-7 [79.4 kB]
Get:8 http://us.archive.ubuntu.com/ubuntu/ precise/main libenchant-dev amd64 1.6.0-7 [64.7 kB]
Fetched 1,529 kB in 0s (12.7 MB/s)
Preconfiguring packages ...
Selecting previously unselected package libhunspell-1.3-0.
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 72431 files and directories currently installed.)
Unpacking libhunspell-1.3-0 (from .../libhunspell-1.3-0_1.3.2-4_amd64.deb) ...
Selecting previously unselected package libtext-iconv-perl.
Unpacking libtext-iconv-perl (from .../libtext-iconv-perl_1.7-5_amd64.deb) ...
Selecting previously unselected package libaspell15.
Unpacking libaspell15 (from .../libaspell15_0.60.7~20110707-1_amd64.deb) ...
Selecting previously unselected package dictionaries-common.
Unpacking dictionaries-common (from .../dictionaries-common_1.12.1ubuntu2_all.deb) ...
Adding 'diversion of /usr/share/dict/words to /usr/share/dict/words.pre-dictionaries-common by dictionaries-common'
Selecting previously unselected package aspell.
Unpacking aspell (from .../aspell_0.60.7~20110707-1_amd64.deb) ...
Selecting previously unselected package aspell-en.
Unpacking aspell-en (from .../aspell-en_6.0-0-6ubuntu2_all.deb) ...
Selecting previously unselected package libenchant1c2a.
Unpacking libenchant1c2a (from .../libenchant1c2a_1.6.0-7_amd64.deb) ...
Selecting previously unselected package libenchant-dev.
Unpacking libenchant-dev (from .../libenchant-dev_1.6.0-7_amd64.deb) ...
Processing triggers for man-db ...
Setting up libhunspell-1.3-0 (1.3.2-4) ...
Setting up libtext-iconv-perl (1.7-5) ...
Setting up libaspell15 (0.60.7~20110707-1) ...
Setting up dictionaries-common (1.12.1ubuntu2) ...
install/dictionaries-common: Byte-compiling for emacsen flavour emacs23
Wrote /usr/share/emacs23/site-lisp/dictionaries-common/debian-ispell.elc
Wrote /usr/share/emacs23/site-lisp/dictionaries-common/ispell.elc
Wrote /usr/share/emacs23/site-lisp/dictionaries-common/flyspell.elc
Processing triggers for dictionaries-common ...
aspell-autobuildhash: processing: en [en-common]
aspell-autobuildhash: processing: en [en-variant_0]
aspell-autobuildhash: processing: en [en-variant_1]
aspell-autobuildhash: processing: en [en-variant_2]
aspell-autobuildhash: processing: en [en_CA-w_accents-only]
aspell-autobuildhash: processing: en [en_CA-wo_accents-only]
aspell-autobuildhash: processing: en [en_GB-ise-w_accents-only]
aspell-autobuildhash: processing: en [en_GB-ise-wo_accents-only]
aspell-autobuildhash: processing: en [en_GB-ize-w_accents-only]
aspell-autobuildhash: processing: en [en_GB-ize-wo_accents-only]
aspell-autobuildhash: processing: en [en_US-w_accents-only]
aspell-autobuildhash: processing: en [en_US-wo_accents-only]
Setting up aspell (0.60.7~20110707-1) ...
Processing triggers for dictionaries-common ...
Setting up aspell-en (6.0-0-6ubuntu2) ...
Processing triggers for dictionaries-common ...
Setting up libenchant1c2a (1.6.0-7) ...
Setting up libenchant-dev (1.6.0-7) ...
Processing triggers for libc-bin ...
ldconfig deferred processing now taking place

travis_time:end:07346b7a:start=1462193599009015840,finish=1462193615515679181,duration=16506663341
travis_fold:end:apt
travis_fold:start:services
travis_time:start:1fb96fb6
$ sudo service rabbitmq-server start
 * Starting message broker rabbitmq-server        
[ OK ]

travis_time:end:1fb96fb6:start=1462193615521501726,finish=1462193618635051762,duration=3113550036
travis_fold:end:services

This job is running on container-based infrastructure, which does not allow use of 'sudo', setuid and setguid executables.
If you require sudo, add 'sudo: required' to your .travis.yml
See https://docs.travis-ci.com/user/workers/container-based-infrastructure/ for details.

Setting environment variables from .travis.yml
$ export TOX_ENV=py33-oldest-lib

travis_time:start:0eb7ff40
$ source ~/virtualenv/python2.7/bin/activate

travis_time:end:0eb7ff40:start=1462193625451110091,finish=1462193625456780044,duration=5669953
travis_fold:start:cache.1
Setting up build cache
$ export CASHER_DIR=$HOME/.casher
travis_time:start:13b6dbd8
$ Installing caching utilities

travis_time:end:13b6dbd8:start=1462193625774910238,finish=1462193625827372746,duration=52462508
travis_time:start:071e728c

travis_time:end:071e728c:start=1462193625837188493,finish=1462193625842193278,duration=5004785
travis_time:start:2ea278c2
attempting to download cache archive
fetching PR.318/cache--python-2.7.tgz
found cache

travis_time:end:2ea278c2:start=1462193625849884083,finish=1462193626984885270,duration=1135001187
travis_time:start:027270b0

travis_time:end:027270b0:start=1462193626990350506,finish=1462193626995327377,duration=4976871
travis_time:start:153d9c1a
adding /home/travis/.cache/pip to cache

travis_time:end:153d9c1a:start=1462193627001065332,finish=1462193628520484605,duration=1519419273
travis_fold:end:cache.1
$ python --version
Python 2.7.9
$ pip --version
pip 6.0.7 from /home/travis/virtualenv/python2.7.9/lib/python2.7/site-packages (python 2.7)
travis_fold:start:install
travis_time:start:0fa2cd28
$ pip install tox
Collecting tox
  Using cached tox-2.3.1-py2.py3-none-any.whl
Collecting virtualenv>=1.11.2 (from tox)
  Using cached virtualenv-15.0.1-py2.py3-none-any.whl
Requirement already satisfied (use --upgrade to upgrade): py>=1.4.17 in /home/travis/virtualenv/python2.7.9/lib/python2.7/site-packages (from tox)
Collecting pluggy<0.4.0,>=0.3.0 (from tox)
  Using cached pluggy-0.3.1-py2.py3-none-any.whl
Installing collected packages: pluggy, virtualenv, tox



Successfully installed pluggy-0.3.1 tox-2.3.1 virtualenv-15.0.1

travis_time:end:0fa2cd28:start=1462193629323358836,finish=1462193631991798977,duration=2668440141
travis_fold:end:install
travis_time:start:2a51c96a
$ tox -e $TOX_ENV
py33-oldest-lib create: /home/travis/build/onefinestay/nameko/.tox/py33-oldest-lib
py33-oldest-lib installdeps: eventlet==0.17.4, kombu==3.0.12, mock==1.0.1, path.py==6.2, requests==2.0.0, six==1.9.0, werkzeug==0.9
py33-oldest-lib installed: amqp==1.4.9,anyjson==0.3.3,eventlet==0.17.4,greenlet==0.4.9,kombu==3.0.12,mock==1.0.1,path.py==6.2,requests==2.0.0,six==1.9.0,Werkzeug==0.9
py33-oldest-lib runtests: PYTHONHASHSEED='2686732112'
py33-oldest-lib runtests: commands[0] | pip install --editable .[dev]
Obtaining file:///home/travis/build/onefinestay/nameko
Requirement already satisfied (use --upgrade to upgrade): eventlet>=0.16.1 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): kombu>=3.0.1 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): mock>=1.0.1 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): path.py>=6.2 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Collecting pyyaml>=3.10 (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): requests>=1.2.0 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): six>=1.9.0 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): werkzeug>=0.9 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from nameko==2.3.0)
Collecting coverage==4.0 (from nameko==2.3.0)
Collecting flake8==2.1.0 (from nameko==2.3.0)
Collecting mccabe==0.3 (from nameko==2.3.0)
  Using cached mccabe-0.3-py2.py3-none-any.whl
Collecting pep8==1.6.1 (from nameko==2.3.0)
  Using cached pep8-1.6.1-py2.py3-none-any.whl
Collecting pyflakes==0.8.1 (from nameko==2.3.0)
  Using cached pyflakes-0.8.1-py2.py3-none-any.whl
Collecting pylint==1.5.1 (from nameko==2.3.0)
  Using cached pylint-1.5.1-py2.py3-none-any.whl
Collecting pytest==2.7.2 (from nameko==2.3.0)
  Using cached pytest-2.7.2-py2.py3-none-any.whl
Collecting pytest-cov==2.1.0 (from nameko==2.3.0)
  Using cached pytest_cov-2.1.0-py2.py3-none-any.whl
Collecting pytest-timeout==0.4 (from nameko==2.3.0)
  Using cached pytest_timeout-0.4-py2.py3-none-any.whl
Collecting urllib3==1.10.2 (from nameko==2.3.0)
Collecting websocket-client==0.23.0 (from nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): greenlet>=0.3 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from eventlet>=0.16.1->nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): amqp<2.0,>=1.4.2 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from kombu>=3.0.1->nameko==2.3.0)
Requirement already satisfied (use --upgrade to upgrade): anyjson>=0.3.3 in ./.tox/py33-oldest-lib/lib/python3.3/site-packages (from kombu>=3.0.1->nameko==2.3.0)
Collecting colorama (from pylint==1.5.1->nameko==2.3.0)
  Using cached colorama-0.3.7-py2.py3-none-any.whl
Collecting astroid>=1.4.1 (from pylint==1.5.1->nameko==2.3.0)
  Using cached astroid-1.4.5-py2.py3-none-any.whl
Collecting py>=1.4.29 (from pytest==2.7.2->nameko==2.3.0)
  Using cached py-1.4.31-py2.py3-none-any.whl
Collecting wrapt (from astroid>=1.4.1->pylint==1.5.1->nameko==2.3.0)
Collecting lazy-object-proxy (from astroid>=1.4.1->pylint==1.5.1->nameko==2.3.0)
  Using cached lazy_object_proxy-1.2.2-cp33-cp33m-manylinux1_x86_64.whl
Installing collected packages: pyyaml, coverage, mccabe, pyflakes, pep8, flake8, colorama, wrapt, lazy-object-proxy, astroid, pylint, py, pytest, pytest-cov, pytest-timeout, urllib3, websocket-client, nameko
  Running setup.py develop for nameko
Successfully installed astroid-1.4.5 colorama-0.3.7 coverage-4.0 flake8-2.1.0 lazy-object-proxy-1.2.2 mccabe-0.3 nameko pep8-1.6.1 py-1.4.31 pyflakes-0.8.1 pylint-1.5.1 pytest-2.7.2 pytest-cov-2.1.0 pytest-timeout-0.4 pyyaml-3.11 urllib3-1.10.2 websocket-client-0.23.0 wrapt-1.10.8
py33-oldest-lib runtests: commands[1] | make flake8
flake8 nameko test
py33-oldest-lib runtests: commands[2] | make pylint
pylint --rcfile=pylintrc nameko -E
py33-oldest-lib runtests: commands[3] | make test_lib
py.test test --cov=/home/travis/build/onefinestay/nameko/nameko --cov-config=/home/travis/build/onefinestay/nameko/.coveragerc
============================= test session starts ==============================
platform linux -- Python 3.3.5 -- py-1.4.31 -- pytest-2.7.2
rootdir: /home/travis/build/onefinestay/nameko, inifile: 
plugins: nameko, cov, timeout

collecting 0 items
collecting 4 items
collecting 9 items
collecting 13 items
collecting 34 items
collecting 40 items
collecting 43 items
collecting 51 items
collecting 53 items
collecting 53 items
collecting 65 items
collecting 83 items
collecting 87 items
collecting 104 items
collecting 105 items
collecting 108 items
collecting 117 items
collecting 118 items
collecting 127 items
collecting 150 items
collecting 156 items
collecting 158 items
collecting 165 items
collecting 165 items
collecting 165 items
collecting 165 items
collecting 165 items
collecting 171 items
collecting 174 items
collecting 188 items
collecting 188 items
collecting 189 items
collecting 194 items
collecting 200 items
collecting 214 items
collecting 221 items
collecting 223 items
collecting 224 items
collecting 224 items
collecting 224 items
collecting 226 items
collecting 226 items
collecting 226 items
collecting 232 items
collecting 233 items
collecting 254 items
collecting 266 items
collecting 283 items
collecting 292 items
collecting 301 items
collecting 306 items
collecting 320 items
collected 320 items 

test/test_amqp.py ....
test/test_broker.py .....
test/test_call_id_stack.py ....
test/test_container.py .....................
test/test_contextdata.py ......
test/test_entrypoints.py ...
test/test_errors.py ........
test/test_events.py ..............
test/test_exceptions.py ..................
test/test_extension_sharing.py ....
test/test_extensions.py .................
test/test_handle_result.py .
test/test_log_helpers.py ...
test/test_messaging.py .........
test/test_provider_collector.py .
test/test_queue_consumer.py .........
test/test_rpc.py ........F..............
test/test_rpc_responder.py ......
test/test_sensitive_variables.py ..
test/test_serialization.py .......
test/test_service_runner.py ......
test/test_timers.py ...
test/test_utils.py ...............
test/cli/test_backdoor.py .....
test/cli/test_main.py ......
test/cli/test_run.py ..............
test/cli/test_shell.py .......
test/nameko_doc/test_entity_rendering.py ..
test/nameko_doc/test_method_extraction.py .
test/nameko_doc/test_processor.py ..
test/nameko_doc/test_rst_renderer.py ......
test/standalone/test_event_dispatcher.py .
test/standalone/test_rpc_proxy.py .....................
test/testing/test_pytest_plugin.py ............
test/testing/test_services.py .................
test/testing/test_utils.py .........
test/web/test_http_handler.py .........
test/web/test_server.py .....
test/web/test_websocket.py ..............

=================================== FAILURES ===================================
_______________________________ test_rpc_headers _______________________________

container_factory = <function container_factory.<locals>.make_container at 0x2ba997eba680>
rabbit_config = {'AMQP_URI': 'amqp://guest:guest@localhost:5672/test_hibkukhclt', 'username': 'guest', 'vhost': 'test_hibkukhclt'}

    @pytest.mark.usefixtures("predictable_call_ids")
    def test_rpc_headers(container_factory, rabbit_config):
    
        container = container_factory(ExampleService, rabbit_config)
    
        context_data = {
            'language': 'en',
            'bogus_header': '123456789'
        }
    
        headers = {}
        rpc_consumer = get_extension(container, RpcConsumer)
        handle_message = rpc_consumer.handle_message
    
        with patch.object(
                rpc_consumer, 'handle_message', autospec=True) as patched_handler:
            def side_effect(body, message):
                headers.update(message.headers)  # extract message headers
                return handle_message(body, message)
    
            patched_handler.side_effect = side_effect
            container.start()
    
        # use a standalone rpc proxy to call exampleservice.say_hello()
        with ServiceRpcProxy(
            "exampleservice", rabbit_config, context_data
        ) as proxy:
>           proxy.say_hello()

test/test_rpc.py:366: 
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
nameko/rpc.py:351: in __call__
    reply = self._call(*args, **kwargs)
nameko/rpc.py:418: in _call
    retry_policy=DEFAULT_RETRY_POLICY
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/messaging.py:166: in publish
    routing_key, mandatory, immediate, exchange, declare)
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/connection.py:440: in _ensured
    return fun(*args, **kwargs)
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/messaging.py:171: in _publish
    channel = self.channel
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/messaging.py:188: in _get_channel
    channel = self._channel = channel()
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/utils/__init__.py:408: in __call__
    value = self.__value__ = self.__contract__()
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/messaging.py:203: in <lambda>
    channel = ChannelPromise(lambda: connection.default_channel)
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/connection.py:769: in default_channel
    self.connection
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/connection.py:754: in connection
    self._connection = self._establish_connection()
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/connection.py:713: in _establish_connection
    conn = self.transport.establish_connection()
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/transport/pyamqp.py:111: in establish_connection
    conn = self.Connection(**opts)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/connection.py:165: in __init__
    self.transport = self.Transport(host, connect_timeout, ssl)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/connection.py:186: in Transport
    return create_transport(host, connect_timeout, ssl)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/transport.py:299: in create_transport
    return TCPTransport(host, connect_timeout)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/transport.py:84: in __init__
    self.sock.connect(sa)
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/greenio/base.py:250: in connect
    timeout_exc=socket.timeout("timed out"))
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/greenio/base.py:201: in _trampoline
    mark_as_closed=self._mark_as_closed)
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/hubs/__init__.py:162: in trampoline
    return hub.switch()
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/hubs/hub.py:294: in switch
    return self.greenlet.switch()
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/hubs/hub.py:332: in run
    self.close_one()
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/hubs/hub.py:265: in close_one
    listener.tb(IOClosed(errno.ENOTCONN, "Operation on closed file"))
.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/greenthread.py:214: in main
    result = function(*args, **kwargs)
nameko/rpc.py:351: in __call__
    reply = self._call(*args, **kwargs)
nameko/rpc.py:418: in _call
    retry_policy=DEFAULT_RETRY_POLICY
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/messaging.py:166: in publish
    routing_key, mandatory, immediate, exchange, declare)
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/connection.py:440: in _ensured
    return fun(*args, **kwargs)
.tox/py33-oldest-lib/lib/python3.3/site-packages/kombu/messaging.py:182: in _publish
    mandatory=mandatory, immediate=immediate,
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/channel.py:2132: in basic_publish_confirm
    self.wait([(60, 80), (60, 120)])
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/abstract_channel.py:67: in wait
    self.channel_id, allowed_methods, timeout)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/connection.py:241: in _wait_method
    channel, method_sig, args, content = read_timeout(timeout)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/connection.py:330: in read_timeout
    return self.method_reader.read_method()
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/method_framing.py:189: in read_method
    raise m
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/method_framing.py:107: in _next_method
    frame_type, channel, payload = read_frame()
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/transport.py:154: in read_frame
    frame_header = read(7, True)
.tox/py33-oldest-lib/lib/python3.3/site-packages/amqp/transport.py:277: in _read
    s = recv(n - len(rbuf))
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 

self = <eventlet.greenio.base.GreenSocket object at 0x2ba9978459d0>, buflen = 7
flags = 0

    def recv(self, buflen, flags=0):
        fd = self.fd
        if self.act_non_blocking:
            return fd.recv(buflen, flags)
        while True:
            try:
                return fd.recv(buflen, flags)
            except socket.error as e:
                if get_errno(e) in SOCKET_BLOCKING:
                    pass
                elif get_errno(e) in SOCKET_CLOSED:
                    return ''
                else:
                    raise
            try:
                self._trampoline(
                    fd,
                    read=True,
                    timeout=self.gettimeout(),
                    timeout_exc=socket.timeout("timed out"))
            except IOClosed as e:
                # Perhaps we should return '' instead?
>               raise EOFError()
E               EOFError

.tox/py33-oldest-lib/lib/python3.3/site-packages/eventlet/greenio/base.py:329: EOFError
---------------------------- Captured stderr setup -----------------------------
DEBUG:requests.packages.urllib3.connectionpool:Setting read timeout to None
DEBUG:requests.packages.urllib3.connectionpool:"PUT /api/vhosts/test_hibkukhclt HTTP/1.1" 204 0
DEBUG:requests.packages.urllib3.connectionpool:Setting read timeout to None
DEBUG:requests.packages.urllib3.connectionpool:"PUT /api/permissions/test_hibkukhclt/guest HTTP/1.1" 204 0
----------------------------- Captured stderr call -----------------------------
DEBUG:nameko.containers:starting <ServiceContainer [exampleservice] at 0x2ba997843390>
DEBUG:nameko.extensions:registering provider <ReplyListener at 0x2ba997d1ac10> for <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.events:starting <EventHandler [exampleservice.async_task] at 0x2ba997d1a890>
DEBUG:nameko.extensions:registering provider <EventHandler [exampleservice.async_task] at 0x2ba997d1a890> for <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.echo] at 0x2ba997d1a110> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.call_async] at 0x2ba997d1a950> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.task_a] at 0x2ba997d1ad10> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <RpcConsumer at 0x2ba997d1aa50> for <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.broken] at 0x2ba997d1a910> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.task_b] at 0x2ba997d1aed0> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.say_hello] at 0x2ba997d1a090> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.call_unknown] at 0x2ba997d1a390> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:nameko.extensions:registering provider <Rpc [exampleservice.very_broken] at 0x2ba997d1abd0> for <RpcConsumer at 0x2ba997d1aa50>
DEBUG:amqp:Start from server, version: 0.9, properties: {'version': '3.4.3', 'product': 'RabbitMQ', 'capabilities': {'exchange_exchange_bindings': True, 'consumer_priorities': True, 'per_consumer_qos': True, 'consumer_cancel_notify': True, 'connection.blocked': True, 'publisher_confirms': True, 'basic.nack': True, 'authentication_failure_close': True}, 'platform': 'Erlang/OTP', 'copyright': 'Copyright (C) 2007-2014 GoPivotal, Inc.', 'cluster_name': 'rabbit@testing-worker-linux-docker-f359b10b-3365-linux-4.prod.travis-ci.org', 'information': 'Licensed under the MPL.  See http://www.rabbitmq.com/'}, mechanisms: ['PLAIN', 'AMQPLAIN'], locales: ['en_US']
DEBUG:amqp:Open OK!
DEBUG:nameko.messaging:starting <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.messaging:waiting for consumer ready <QueueConsumer at 0x2ba997d1a590>
DEBUG:amqp:Start from server, version: 0.9, properties: {'version': '3.4.3', 'product': 'RabbitMQ', 'capabilities': {'exchange_exchange_bindings': True, 'consumer_priorities': True, 'per_consumer_qos': True, 'consumer_cancel_notify': True, 'connection.blocked': True, 'publisher_confirms': True, 'basic.nack': True, 'authentication_failure_close': True}, 'platform': 'Erlang/OTP', 'copyright': 'Copyright (C) 2007-2014 GoPivotal, Inc.', 'cluster_name': 'rabbit@testing-worker-linux-docker-f359b10b-3365-linux-4.prod.travis-ci.org', 'information': 'Licensed under the MPL.  See http://www.rabbitmq.com/'}, mechanisms: ['PLAIN', 'AMQPLAIN'], locales: ['en_US']
DEBUG:amqp:Open OK!
INFO:kombu.mixins:Connected to amqp://[email protected]:5672/test_hibkukhclt
DEBUG:amqp:using channel_id: 1
DEBUG:amqp:Channel open
DEBUG:nameko.messaging:setting up consumers <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.messaging:consumer started <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.messaging:started <QueueConsumer at 0x2ba997d1a590>
DEBUG:nameko.containers:started <ServiceContainer [exampleservice] at 0x2ba997843390> in 0.127s
DEBUG:amqp:Start from server, version: 0.9, properties: {'version': '3.4.3', 'product': 'RabbitMQ', 'capabilities': {'exchange_exchange_bindings': True, 'consumer_priorities': True, 'per_consumer_qos': True, 'consumer_cancel_notify': True, 'connection.blocked': True, 'publisher_confirms': True, 'basic.nack': True, 'authentication_failure_close': True}, 'platform': 'Erlang/OTP', 'copyright': 'Copyright (C) 2007-2014 GoPivotal, Inc.', 'cluster_name': 'rabbit@testing-worker-linux-docker-f359b10b-3365-linux-4.prod.travis-ci.org', 'information': 'Licensed under the MPL.  See http://www.rabbitmq.com/'}, mechanisms: ['PLAIN', 'AMQPLAIN'], locales: ['en_US']
DEBUG:amqp:Open OK!
DEBUG:amqp:Start from server, version: 0.9, properties: {'version': '3.4.3', 'product': 'RabbitMQ', 'capabilities': {'exchange_exchange_bindings': True, 'consumer_priorities': True, 'per_consumer_qos': True, 'consumer_cancel_notify': True, 'connection.blocked': True, 'publisher_confirms': True, 'basic.nack': True, 'authentication_failure_close': True}, 'platform': 'Erlang/OTP', 'copyright': 'Copyright (C) 2007-2014 GoPivotal, Inc.', 'cluster_name': 'rabbit@testing-worker-linux-docker-f359b10b-3365-linux-4.prod.travis-ci.org', 'information': 'Licensed under the MPL.  See http://www.rabbitmq.com/'}, mechanisms: ['PLAIN', 'AMQPLAIN'], locales: ['en_US']
DEBUG:amqp:Open OK!
DEBUG:amqp:using channel_id: 1
DEBUG:amqp:Channel open
DEBUG:nameko.rpc:invoking <proxy method: exampleservice.say_hello>
DEBUG:amqp:Closed channel #1
---------------- coverage: platform linux, python 3.3.5-final-0 ----------------
Name                                    Stmts   Miss  Cover   Missing
---------------------------------------------------------------------
nameko/__init__.py                          0      0   100%   
nameko/amqp.py                             24      0   100%   
nameko/cli/__init__.py                      0      0   100%   
nameko/cli/actions.py                      19      0   100%   
nameko/cli/backdoor.py                     35      0   100%   
nameko/cli/main.py                         20      0   100%   
nameko/cli/run.py                         102      0   100%   
nameko/cli/shell.py                        58      0   100%   
nameko/constants.py                        16      0   100%   
nameko/containers.py                      232      0   100%   
nameko/contextdata.py                      14      0   100%   
nameko/events.py                           60      0   100%   
nameko/exceptions.py                       75      0   100%   
nameko/extensions.py                      138      0   100%   
nameko/log_helpers.py                      14      0   100%   
nameko/messaging.py                       264      0   100%   
nameko/nameko_doc/__init__.py               0      0   100%   
nameko/nameko_doc/entities.py              37      0   100%   
nameko/nameko_doc/method_extractor.py      33      0   100%   
nameko/nameko_doc/processor.py             20      0   100%   
nameko/nameko_doc/rst_render.py            83      0   100%   
nameko/rpc.py                             224      0   100%   
nameko/runners.py                          52      0   100%   
nameko/standalone/__init__.py               0      0   100%   
nameko/standalone/events.py                23      0   100%   
nameko/standalone/rpc.py                  137      0   100%   
nameko/testing/__init__.py                  0      0   100%   
nameko/testing/pytest.py                  134      0   100%   
nameko/testing/rabbit.py                   70      0   100%   
nameko/testing/services.py                115      0   100%   
nameko/testing/utils.py                    74      0   100%   
nameko/testing/websocket.py                48      0   100%   
nameko/timer.py                            37      0   100%   
nameko/utils.py                            73      0   100%   
nameko/web/__init__.py                      0      0   100%   
nameko/web/handlers.py                     64      0   100%   
nameko/web/server.py                       83      0   100%   
nameko/web/websocket.py                   165      0   100%   
---------------------------------------------------------------------
TOTAL                                    2543      0   100%   
==================== 1 failed, 319 passed in 122.00 seconds ====================
make: *** [test_lib] Error 1
ERROR: InvocationError: '/usr/bin/make test_lib'
___________________________________ summary ____________________________________
ERROR:   py33-oldest-lib: commands failed

travis_time:end:2a51c96a:start=1462193631998243929,finish=1462193800679167617,duration=168680923688

The command "tox -e $TOX_ENV" exited with 1.
travis_fold:start:cache.2
store build cache
travis_time:start:032cada7

travis_time:end:032cada7:start=1462193800686062959,finish=1462193800691425516,duration=5362557
travis_time:start:13b98ecc
change detected:
/home/travis/.cache/pip/http/0/8/3/b/6/083b6a2226baba69fd6f620abdfd14c40450979d40f1a0343341e5a2
/home/travis/.cache/pip/http/0/c/7/8/c/0c78cc5ddccddcf3238359feeac7e1cddd59216d6ded4659cf5965cd
/home/travis/.cache/pip/http/1/8/5/9/8/185980b85261663fdee800755a6e3a643da2b448eb070f33b258c0ed
/home/travis/.cache/pip/http/1/9/1/e/2/191e2772496ab7c2761201e27896a90312f5405d5f1f2986dfe65a51
/home/travis/.cache/pip/http/2/3/9/9/c/2399ce14e5d071a00b24636db2a7d8f9ef4a63fbd2d60206408e2290
/home/travis/.cache/pip/http/2/7/b/0/1/27b0179c0da897387dfc3bddcb657107b50273249f66301c5b64c7c9
/home/travis/.cache/pip/http/3/a/f/3/a/3af3addf06e983a6c02f46e7bea70c221d3ff95bf1418fa6da354e14
/home/travis/.cache/pip/http/3/d/0/7/9/3d0790aa6d8aba43447ad4d8fdc684c544812f2cc57ad084f4b1b2db
/home/travis/.cache/pip/http/4/0/6/e/9/406e9b27a390d51d6f5ba6446b20eddf170d46e00b171bdfa0fbde6b
/home/travis/.cache/pip/http/4/3/5/8/9/435895f5c58d1fbe5d6efd64c4a3afa3e8b280691afe2988aaf12f5c
/home/travis/.cache/pip/http/4/b/b/8/4/4b

...

changes detected, packing new archive
uploading archive

travis_time:end:13b98ecc:start=1462193800697534005,finish=1462193804395835926,duration=3698301921
travis_fold:end:cache.2

Done. Your build exited with 1.