Push event to branch master 12:04:58 Connecting to https://api.github.com using docker-jenkins/****** (docker-jenkins GitHub credentials) Obtained Jenkinsfile from 2a046c0bdbf256adf1d6fe107f5ab64770368af1 Running in Durability level: PERFORMANCE_OPTIMIZED Loading library jps@master Examining docker/jps Attempting to resolve master as a branch Resolved master as branch master at revision 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 The recommended git tool is: NONE using credential jps-reader > git rev-parse --resolve-git-dir /var/cloudbees-jenkins-distribution/workspace/libcompose_master@libs/jps/.git # timeout=10 Fetching changes from the remote Git repository > git config remote.origin.url https://github.com/docker/jps.git # timeout=10 Fetching without tags Fetching upstream changes from https://github.com/docker/jps.git > git --version # timeout=10 > git --version # 'git version 2.20.1' using GIT_ASKPASS to set credentials GitHub credentials for docker/jps > git fetch --no-tags --force --progress -- https://github.com/docker/jps.git +refs/heads/master:refs/remotes/origin/master # timeout=10 Checking out Revision 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 (master) > git config core.sparsecheckout # timeout=10 > git checkout -f 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 # timeout=10 Commit message: "Merge pull request #25 from ingshtrom/in-2733-load-from-file" > git rev-list --no-walk ead69454b4062f17d1f8389642089a002b037213 # timeout=10 Excluding src/test/ from checkout of git https://github.com/docker/jps.git so that shared library test code cannot be accessed by Pipelines. To remove this log message, move the test code outside of src/. To restore the previous behavior that allowed access to files in src/test/, pass -Dorg.jenkinsci.plugins.workflow.libs.SCMSourceRetriever.INCLUDE_SRC_TEST_IN_LIBRARIES=true to the java command used to start Jenkins. [Pipeline] Start of Pipeline [Pipeline] node Running on EC2 (managed-agents) - ubuntu-1804-overlay2 (i-0c173efbbb148117b) in /home/ubuntu/workspace/libcompose_master [Pipeline] { [Pipeline] withDockerRegistry $ docker login -u dockerbuildbot -p ******** https://index.docker.io/v1/ WARNING! Using --password via the CLI is insecure. Use --password-stdin. WARNING! Your password will be stored unencrypted in /home/ubuntu/workspace/libcompose_master@tmp/95756c05-bc6a-4065-978a-9c719ecf0413/config.json. Configure a credential helper to remove this warning. See https://docs.docker.com/engine/reference/commandline/login/#credentials-store Login Succeeded [Pipeline] { [Pipeline] wrap [Pipeline] { [Pipeline] wrap [Pipeline] { [Pipeline] deleteDir [Pipeline] checkout 12:04:59 The recommended git tool is: git 12:04:59 using credential docker-jenkins-github-credentials 12:04:59 Cloning the remote Git repository 12:04:59 Cloning with configured refspecs honoured and without tags 12:05:00 Avoid second fetch 12:05:00 Checking out Revision 2a046c0bdbf256adf1d6fe107f5ab64770368af1 (master) 12:05:00 Commit message: "Merge pull request #532 from mat007/deprecation" Cloning repository https://github.com/docker/libcompose.git > git init /home/ubuntu/workspace/libcompose_master # timeout=10 Fetching upstream changes from https://github.com/docker/libcompose.git > git --version # timeout=10 > git --version # 'git version 2.17.1' using GIT_ASKPASS to set credentials docker-jenkins GitHub credentials > git fetch --no-tags --progress -- https://github.com/docker/libcompose.git +refs/heads/master:refs/remotes/origin/master # timeout=10 > git config remote.origin.url https://github.com/docker/libcompose.git # timeout=10 > git config --add remote.origin.fetch +refs/heads/master:refs/remotes/origin/master # timeout=10 > git config core.sparsecheckout # timeout=10 > git checkout -f 2a046c0bdbf256adf1d6fe107f5ab64770368af1 # timeout=10 > git rev-list --no-walk 143e0f3f1ab9caa587b9821028c60d41d203de3e # timeout=10 [Pipeline] stage 12:05:00 Using the ‘stage’ step without a block argument is deprecated 12:05:00 Entering stage build image 12:05:00 Proceeding [Pipeline] sh 12:05:01 + git rev-parse HEAD [Pipeline] isUnix [Pipeline] sh 12:05:01 + docker build -t dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 . 12:05:01 Sending build context to Docker daemon 6.576MB 12:05:01 Step 1/14 : FROM golang:1.12.7 12:05:01 ---> be63d15101cb 12:05:01 Step 2/14 : RUN apt-get update && apt-get install -y iptables build-essential --no-install-recommends && apt-get install -y python-pip && pip install virtualenv 12:05:01 ---> Running in 079d249ad3af 12:05:02 Get:1 http://security.debian.org/debian-security buster/updates InRelease [65.4 kB] 12:05:02 Get:2 http://deb.debian.org/debian buster InRelease [121 kB] 12:05:02 Get:3 http://deb.debian.org/debian buster-updates InRelease [51.9 kB] 12:05:02 Get:4 http://security.debian.org/debian-security buster/updates/main amd64 Packages [292 kB] 12:05:02 Get:5 http://deb.debian.org/debian buster/main amd64 Packages [7907 kB] 12:05:02 Get:6 http://deb.debian.org/debian buster-updates/main amd64 Packages [10.9 kB] 12:05:04 Fetched 8449 kB in 2s (4948 kB/s) 12:05:04 Reading package lists... 12:05:04 Reading package lists... 12:05:05 Building dependency tree... 12:05:05 Reading state information... 12:05:05 The following additional packages will be installed: 12:05:05 bzip2 dpkg-dev libbz2-1.0 libip4tc0 libip6tc0 libiptc0 12:05:05 libnetfilter-conntrack3 libnfnetlink0 libnftnl11 patch xz-utils 12:05:05 Suggested packages: 12:05:05 bzip2-doc debian-keyring kmod ed diffutils-doc 12:05:05 Recommended packages: 12:05:05 fakeroot libalgorithm-merge-perl nftables 12:05:05 The following NEW packages will be installed: 12:05:05 build-essential bzip2 dpkg-dev iptables libip4tc0 libip6tc0 libiptc0 12:05:05 libnetfilter-conntrack3 libnfnetlink0 libnftnl11 patch xz-utils 12:05:05 The following packages will be upgraded: 12:05:05 libbz2-1.0 12:05:05 1 upgraded, 12 newly installed, 0 to remove and 67 not upgraded. 12:05:05 Need to get 2895 kB of archives. 12:05:05 After this operation, 6404 kB of additional disk space will be used. 12:05:05 Get:1 http://deb.debian.org/debian buster/main amd64 libbz2-1.0 amd64 1.0.6-9.2~deb10u1 [45.3 kB] 12:05:05 Get:2 http://deb.debian.org/debian buster/main amd64 libip4tc0 amd64 1.8.2-4 [70.2 kB] 12:05:05 Get:3 http://deb.debian.org/debian buster/main amd64 libip6tc0 amd64 1.8.2-4 [70.5 kB] 12:05:05 Get:4 http://deb.debian.org/debian buster/main amd64 libiptc0 amd64 1.8.2-4 [59.6 kB] 12:05:05 Get:5 http://deb.debian.org/debian buster/main amd64 libnfnetlink0 amd64 1.0.1-3+b1 [13.9 kB] 12:05:05 Get:6 http://deb.debian.org/debian buster/main amd64 libnetfilter-conntrack3 amd64 1.0.7-1 [42.4 kB] 12:05:05 Get:7 http://deb.debian.org/debian buster/main amd64 libnftnl11 amd64 1.1.2-2 [56.2 kB] 12:05:05 Get:8 http://deb.debian.org/debian buster/main amd64 iptables amd64 1.8.2-4 [399 kB] 12:05:05 Get:9 http://deb.debian.org/debian buster/main amd64 bzip2 amd64 1.0.6-9.2~deb10u1 [48.4 kB] 12:05:05 Get:10 http://deb.debian.org/debian buster/main amd64 xz-utils amd64 5.2.4-1 [183 kB] 12:05:05 Get:11 http://deb.debian.org/debian buster/main amd64 patch amd64 2.7.6-3+deb10u1 [126 kB] 12:05:05 Get:12 http://deb.debian.org/debian buster/main amd64 dpkg-dev all 1.19.7 [1773 kB] 12:05:05 Get:13 http://deb.debian.org/debian buster/main amd64 build-essential amd64 12.6 [7576 B] 12:05:05 debconf: delaying package configuration, since apt-utils is not installed 12:05:05 Fetched 2895 kB in 0s (9946 kB/s) 12:05:05 (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 ... 15394 files and directories currently installed.) 12:05:05 Preparing to unpack .../libbz2-1.0_1.0.6-9.2~deb10u1_amd64.deb ... 12:05:05 Unpacking libbz2-1.0:amd64 (1.0.6-9.2~deb10u1) over (1.0.6-9.1) ... 12:05:05 Setting up libbz2-1.0:amd64 (1.0.6-9.2~deb10u1) ... 12:05:05 Selecting previously unselected package libip4tc0:amd64. 12:05:05 (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 ... 15394 files and directories currently installed.) 12:05:05 Preparing to unpack .../00-libip4tc0_1.8.2-4_amd64.deb ... 12:05:05 Unpacking libip4tc0:amd64 (1.8.2-4) ... 12:05:05 Selecting previously unselected package libip6tc0:amd64. 12:05:05 Preparing to unpack .../01-libip6tc0_1.8.2-4_amd64.deb ... 12:05:05 Unpacking libip6tc0:amd64 (1.8.2-4) ... 12:05:05 Selecting previously unselected package libiptc0:amd64. 12:05:05 Preparing to unpack .../02-libiptc0_1.8.2-4_amd64.deb ... 12:05:05 Unpacking libiptc0:amd64 (1.8.2-4) ... 12:05:05 Selecting previously unselected package libnfnetlink0:amd64. 12:05:05 Preparing to unpack .../03-libnfnetlink0_1.0.1-3+b1_amd64.deb ... 12:05:05 Unpacking libnfnetlink0:amd64 (1.0.1-3+b1) ... 12:05:05 Selecting previously unselected package libnetfilter-conntrack3:amd64. 12:05:05 Preparing to unpack .../04-libnetfilter-conntrack3_1.0.7-1_amd64.deb ... 12:05:05 Unpacking libnetfilter-conntrack3:amd64 (1.0.7-1) ... 12:05:05 Selecting previously unselected package libnftnl11:amd64. 12:05:05 Preparing to unpack .../05-libnftnl11_1.1.2-2_amd64.deb ... 12:05:05 Unpacking libnftnl11:amd64 (1.1.2-2) ... 12:05:05 Selecting previously unselected package iptables. 12:05:05 Preparing to unpack .../06-iptables_1.8.2-4_amd64.deb ... 12:05:05 Unpacking iptables (1.8.2-4) ... 12:05:06 Selecting previously unselected package bzip2. 12:05:06 Preparing to unpack .../07-bzip2_1.0.6-9.2~deb10u1_amd64.deb ... 12:05:06 Unpacking bzip2 (1.0.6-9.2~deb10u1) ... 12:05:06 Selecting previously unselected package xz-utils. 12:05:06 Preparing to unpack .../08-xz-utils_5.2.4-1_amd64.deb ... 12:05:06 Unpacking xz-utils (5.2.4-1) ... 12:05:06 Selecting previously unselected package patch. 12:05:06 Preparing to unpack .../09-patch_2.7.6-3+deb10u1_amd64.deb ... 12:05:06 Unpacking patch (2.7.6-3+deb10u1) ... 12:05:06 Selecting previously unselected package dpkg-dev. 12:05:06 Preparing to unpack .../10-dpkg-dev_1.19.7_all.deb ... 12:05:06 Unpacking dpkg-dev (1.19.7) ... 12:05:06 Selecting previously unselected package build-essential. 12:05:06 Preparing to unpack .../11-build-essential_12.6_amd64.deb ... 12:05:06 Unpacking build-essential (12.6) ... 12:05:06 Setting up libip6tc0:amd64 (1.8.2-4) ... 12:05:06 Setting up libip4tc0:amd64 (1.8.2-4) ... 12:05:06 Setting up libnftnl11:amd64 (1.1.2-2) ... 12:05:06 Setting up bzip2 (1.0.6-9.2~deb10u1) ... 12:05:06 Setting up libiptc0:amd64 (1.8.2-4) ... 12:05:06 Setting up xz-utils (5.2.4-1) ... 12:05:06 update-alternatives: using /usr/bin/xz to provide /usr/bin/lzma (lzma) in auto mode 12:05:06 Setting up patch (2.7.6-3+deb10u1) ... 12:05:06 Setting up libnfnetlink0:amd64 (1.0.1-3+b1) ... 12:05:06 Setting up dpkg-dev (1.19.7) ... 12:05:06 Setting up libnetfilter-conntrack3:amd64 (1.0.7-1) ... 12:05:06 Setting up build-essential (12.6) ... 12:05:06 Setting up iptables (1.8.2-4) ... 12:05:06 update-alternatives: using /usr/sbin/iptables-legacy to provide /usr/sbin/iptables (iptables) in auto mode 12:05:06 update-alternatives: using /usr/sbin/ip6tables-legacy to provide /usr/sbin/ip6tables (ip6tables) in auto mode 12:05:06 update-alternatives: using /usr/sbin/iptables-nft to provide /usr/sbin/iptables (iptables) in auto mode 12:05:06 update-alternatives: using /usr/sbin/ip6tables-nft to provide /usr/sbin/ip6tables (ip6tables) in auto mode 12:05:06 update-alternatives: using /usr/sbin/arptables-nft to provide /usr/sbin/arptables (arptables) in auto mode 12:05:06 update-alternatives: using /usr/sbin/ebtables-nft to provide /usr/sbin/ebtables (ebtables) in auto mode 12:05:06 Processing triggers for libc-bin (2.28-10) ... 12:05:07 Reading package lists... 12:05:07 Building dependency tree... 12:05:07 Reading state information... 12:05:07 The following additional packages will be installed: 12:05:07 dbus gir1.2-glib-2.0 javascript-common libapparmor1 libdbus-1-3 libexpat1 12:05:07 libexpat1-dev libgirepository-1.0-1 libjs-jquery libjs-sphinxdoc 12:05:07 libjs-underscore libpython-all-dev libpython-dev libpython2-dev libpython2.7 12:05:07 libpython2.7-dev libpython2.7-minimal libpython2.7-stdlib python-all 12:05:07 python-all-dev python-asn1crypto python-cffi-backend python-configparser 12:05:07 python-crypto python-cryptography python-dbus python-dev python-entrypoints 12:05:07 python-enum34 python-gi python-ipaddress python-keyring python-keyrings.alt 12:05:07 python-pip-whl python-pkg-resources python-secretstorage python-setuptools 12:05:07 python-six python-wheel python-xdg python2-dev python2.7 python2.7-dev 12:05:07 python2.7-minimal 12:05:07 Suggested packages: 12:05:07 default-dbus-session-bus | dbus-session-bus apache2 | lighttpd | httpd 12:05:07 python-crypto-doc python-cryptography-doc python-cryptography-vectors 12:05:07 python-dbus-dbg python-dbus-doc python-enum34-doc python-gi-cairo 12:05:07 gnome-keyring libkf5wallet-bin gir1.2-gnomekeyring-1.0 python-gdata 12:05:07 python-keyczar python-secretstorage-doc python-setuptools-doc python2.7-doc 12:05:07 binfmt-support 12:05:07 The following NEW packages will be installed: 12:05:07 dbus gir1.2-glib-2.0 javascript-common libapparmor1 libdbus-1-3 12:05:07 libexpat1-dev libgirepository-1.0-1 libjs-jquery libjs-sphinxdoc 12:05:07 libjs-underscore libpython-all-dev libpython-dev libpython2-dev libpython2.7 12:05:07 libpython2.7-dev python-all python-all-dev python-asn1crypto 12:05:07 python-cffi-backend python-configparser python-crypto python-cryptography 12:05:07 python-dbus python-dev python-entrypoints python-enum34 python-gi 12:05:07 python-ipaddress python-keyring python-keyrings.alt python-pip 12:05:07 python-pip-whl python-pkg-resources python-secretstorage python-setuptools 12:05:07 python-six python-wheel python-xdg python2-dev python2.7-dev 12:05:07 The following packages will be upgraded: 12:05:07 libexpat1 libpython2.7-minimal libpython2.7-stdlib python2.7 12:05:07 python2.7-minimal 12:05:08 5 upgraded, 40 newly installed, 0 to remove and 62 not upgraded. 12:05:08 Need to get 42.1 MB of archives. 12:05:08 After this operation, 74.6 MB of additional disk space will be used. 12:05:08 Get:1 http://security.debian.org/debian-security buster/updates/main amd64 libjs-underscore all 1.9.1~dfsg-1+deb10u1 [100.0 kB] 12:05:08 Get:2 http://deb.debian.org/debian buster/main amd64 python2.7 amd64 2.7.16-2+deb10u1 [305 kB] 12:05:08 Get:3 http://deb.debian.org/debian buster/main amd64 python2.7-minimal amd64 2.7.16-2+deb10u1 [1369 kB] 12:05:08 Get:4 http://deb.debian.org/debian buster/main amd64 libpython2.7-stdlib amd64 2.7.16-2+deb10u1 [1912 kB] 12:05:08 Get:5 http://deb.debian.org/debian buster/main amd64 libpython2.7-minimal amd64 2.7.16-2+deb10u1 [395 kB] 12:05:08 Get:6 http://deb.debian.org/debian buster/main amd64 libexpat1 amd64 2.2.6-2+deb10u1 [106 kB] 12:05:08 Get:7 http://deb.debian.org/debian buster/main amd64 libapparmor1 amd64 2.13.2-10 [94.7 kB] 12:05:08 Get:8 http://deb.debian.org/debian buster/main amd64 libdbus-1-3 amd64 1.12.20-0+deb10u1 [215 kB] 12:05:08 Get:9 http://deb.debian.org/debian buster/main amd64 dbus amd64 1.12.20-0+deb10u1 [236 kB] 12:05:08 Get:10 http://deb.debian.org/debian buster/main amd64 libgirepository-1.0-1 amd64 1.58.3-2 [92.8 kB] 12:05:08 Get:11 http://deb.debian.org/debian buster/main amd64 gir1.2-glib-2.0 amd64 1.58.3-2 [143 kB] 12:05:08 Get:12 http://deb.debian.org/debian buster/main amd64 javascript-common all 11 [6120 B] 12:05:08 Get:13 http://deb.debian.org/debian buster/main amd64 libexpat1-dev amd64 2.2.6-2+deb10u1 [153 kB] 12:05:08 Get:14 http://deb.debian.org/debian buster/main amd64 libjs-jquery all 3.3.1~dfsg-3+deb10u1 [332 kB] 12:05:08 Get:15 http://deb.debian.org/debian buster/main amd64 libjs-sphinxdoc all 1.8.4-1 [95.9 kB] 12:05:08 Get:16 http://deb.debian.org/debian buster/main amd64 libpython2.7 amd64 2.7.16-2+deb10u1 [1036 kB] 12:05:08 Get:17 http://deb.debian.org/debian buster/main amd64 libpython2.7-dev amd64 2.7.16-2+deb10u1 [31.6 MB] 12:05:08 Get:18 http://deb.debian.org/debian buster/main amd64 libpython2-dev amd64 2.7.16-1 [20.9 kB] 12:05:08 Get:19 http://deb.debian.org/debian buster/main amd64 libpython-dev amd64 2.7.16-1 [20.9 kB] 12:05:08 Get:20 http://deb.debian.org/debian buster/main amd64 libpython-all-dev amd64 2.7.16-1 [1064 B] 12:05:08 Get:21 http://deb.debian.org/debian buster/main amd64 python-all amd64 2.7.16-1 [1044 B] 12:05:08 Get:22 http://deb.debian.org/debian buster/main amd64 python2.7-dev amd64 2.7.16-2+deb10u1 [294 kB] 12:05:08 Get:23 http://deb.debian.org/debian buster/main amd64 python2-dev amd64 2.7.16-1 [1212 B] 12:05:08 Get:24 http://deb.debian.org/debian buster/main amd64 python-dev amd64 2.7.16-1 [1192 B] 12:05:08 Get:25 http://deb.debian.org/debian buster/main amd64 python-all-dev amd64 2.7.16-1 [1068 B] 12:05:08 Get:26 http://deb.debian.org/debian buster/main amd64 python-asn1crypto all 0.24.0-1 [78.1 kB] 12:05:08 Get:27 http://deb.debian.org/debian buster/main amd64 python-cffi-backend amd64 1.12.2-1 [78.8 kB] 12:05:08 Get:28 http://deb.debian.org/debian buster/main amd64 python-configparser all 3.5.0b2-1 [59.2 kB] 12:05:08 Get:29 http://deb.debian.org/debian buster/main amd64 python-crypto amd64 2.6.1-9+b1 [259 kB] 12:05:08 Get:30 http://deb.debian.org/debian buster/main amd64 python-enum34 all 1.1.6-2 [35.3 kB] 12:05:08 Get:31 http://deb.debian.org/debian buster/main amd64 python-ipaddress all 1.0.17-1 [18.1 kB] 12:05:08 Get:32 http://deb.debian.org/debian buster/main amd64 python-six all 1.12.0-1 [15.7 kB] 12:05:08 Get:33 http://deb.debian.org/debian buster/main amd64 python-cryptography amd64 2.6.1-3+deb10u2 [218 kB] 12:05:08 Get:34 http://deb.debian.org/debian buster/main amd64 python-dbus amd64 1.2.8-3 [103 kB] 12:05:08 Get:35 http://deb.debian.org/debian buster/main amd64 python-entrypoints all 0.3-1 [5444 B] 12:05:08 Get:36 http://deb.debian.org/debian buster/main amd64 python-gi amd64 3.30.4-1 [227 kB] 12:05:08 Get:37 http://deb.debian.org/debian buster/main amd64 python-secretstorage all 2.3.1-2 [13.8 kB] 12:05:08 Get:38 http://deb.debian.org/debian buster/main amd64 python-keyring all 17.1.1-1 [47.1 kB] 12:05:08 Get:39 http://deb.debian.org/debian buster/main amd64 python-keyrings.alt all 3.1.1-1 [18.3 kB] 12:05:08 Get:40 http://deb.debian.org/debian buster/main amd64 python-pip-whl all 18.1-5 [1591 kB] 12:05:08 Get:41 http://deb.debian.org/debian buster/main amd64 python-pip all 18.1-5 [215 kB] 12:05:08 Get:42 http://deb.debian.org/debian buster/main amd64 python-pkg-resources all 40.8.0-1 [182 kB] 12:05:08 Get:43 http://deb.debian.org/debian buster/main amd64 python-setuptools all 40.8.0-1 [382 kB] 12:05:08 Get:44 http://deb.debian.org/debian buster/main amd64 python-wheel all 0.32.3-2 [19.3 kB] 12:05:08 Get:45 http://deb.debian.org/debian buster/main amd64 python-xdg all 0.25-5 [35.9 kB] 12:05:08 debconf: delaying package configuration, since apt-utils is not installed 12:05:08 Fetched 42.1 MB in 1s (60.1 MB/s) 12:05:08 (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 ... 15958 files and directories currently installed.) 12:05:08 Preparing to unpack .../00-python2.7_2.7.16-2+deb10u1_amd64.deb ... 12:05:08 Unpacking python2.7 (2.7.16-2+deb10u1) over (2.7.16-2) ... 12:05:08 Preparing to unpack .../01-python2.7-minimal_2.7.16-2+deb10u1_amd64.deb ... 12:05:08 Unpacking python2.7-minimal (2.7.16-2+deb10u1) over (2.7.16-2) ... 12:05:08 Preparing to unpack .../02-libpython2.7-stdlib_2.7.16-2+deb10u1_amd64.deb ... 12:05:08 Unpacking libpython2.7-stdlib:amd64 (2.7.16-2+deb10u1) over (2.7.16-2) ... 12:05:10 Preparing to unpack .../03-libpython2.7-minimal_2.7.16-2+deb10u1_amd64.deb ... 12:05:10 Unpacking libpython2.7-minimal:amd64 (2.7.16-2+deb10u1) over (2.7.16-2) ... 12:05:10 Preparing to unpack .../04-libexpat1_2.2.6-2+deb10u1_amd64.deb ... 12:05:10 Unpacking libexpat1:amd64 (2.2.6-2+deb10u1) over (2.2.6-2) ... 12:05:10 Selecting previously unselected package libapparmor1:amd64. 12:05:10 Preparing to unpack .../05-libapparmor1_2.13.2-10_amd64.deb ... 12:05:10 Unpacking libapparmor1:amd64 (2.13.2-10) ... 12:05:10 Selecting previously unselected package libdbus-1-3:amd64. 12:05:10 Preparing to unpack .../06-libdbus-1-3_1.12.20-0+deb10u1_amd64.deb ... 12:05:10 Unpacking libdbus-1-3:amd64 (1.12.20-0+deb10u1) ... 12:05:10 Selecting previously unselected package dbus. 12:05:10 Preparing to unpack .../07-dbus_1.12.20-0+deb10u1_amd64.deb ... 12:05:10 Unpacking dbus (1.12.20-0+deb10u1) ... 12:05:10 Selecting previously unselected package libgirepository-1.0-1:amd64. 12:05:10 Preparing to unpack .../08-libgirepository-1.0-1_1.58.3-2_amd64.deb ... 12:05:10 Unpacking libgirepository-1.0-1:amd64 (1.58.3-2) ... 12:05:10 Selecting previously unselected package gir1.2-glib-2.0:amd64. 12:05:10 Preparing to unpack .../09-gir1.2-glib-2.0_1.58.3-2_amd64.deb ... 12:05:10 Unpacking gir1.2-glib-2.0:amd64 (1.58.3-2) ... 12:05:10 Selecting previously unselected package javascript-common. 12:05:10 Preparing to unpack .../10-javascript-common_11_all.deb ... 12:05:11 Unpacking javascript-common (11) ... 12:05:11 Selecting previously unselected package libexpat1-dev:amd64. 12:05:11 Preparing to unpack .../11-libexpat1-dev_2.2.6-2+deb10u1_amd64.deb ... 12:05:11 Unpacking libexpat1-dev:amd64 (2.2.6-2+deb10u1) ... 12:05:11 Selecting previously unselected package libjs-jquery. 12:05:11 Preparing to unpack .../12-libjs-jquery_3.3.1~dfsg-3+deb10u1_all.deb ... 12:05:11 Unpacking libjs-jquery (3.3.1~dfsg-3+deb10u1) ... 12:05:11 Selecting previously unselected package libjs-underscore. 12:05:11 Preparing to unpack .../13-libjs-underscore_1.9.1~dfsg-1+deb10u1_all.deb ... 12:05:11 Unpacking libjs-underscore (1.9.1~dfsg-1+deb10u1) ... 12:05:11 Selecting previously unselected package libjs-sphinxdoc. 12:05:11 Preparing to unpack .../14-libjs-sphinxdoc_1.8.4-1_all.deb ... 12:05:11 Unpacking libjs-sphinxdoc (1.8.4-1) ... 12:05:11 Selecting previously unselected package libpython2.7:amd64. 12:05:11 Preparing to unpack .../15-libpython2.7_2.7.16-2+deb10u1_amd64.deb ... 12:05:11 Unpacking libpython2.7:amd64 (2.7.16-2+deb10u1) ... 12:05:11 Selecting previously unselected package libpython2.7-dev:amd64. 12:05:11 Preparing to unpack .../16-libpython2.7-dev_2.7.16-2+deb10u1_amd64.deb ... 12:05:11 Unpacking libpython2.7-dev:amd64 (2.7.16-2+deb10u1) ... 12:05:13 Selecting previously unselected package libpython2-dev:amd64. 12:05:13 Preparing to unpack .../17-libpython2-dev_2.7.16-1_amd64.deb ... 12:05:13 Unpacking libpython2-dev:amd64 (2.7.16-1) ... 12:05:13 Selecting previously unselected package libpython-dev:amd64. 12:05:13 Preparing to unpack .../18-libpython-dev_2.7.16-1_amd64.deb ... 12:05:13 Unpacking libpython-dev:amd64 (2.7.16-1) ... 12:05:13 Selecting previously unselected package libpython-all-dev:amd64. 12:05:13 Preparing to unpack .../19-libpython-all-dev_2.7.16-1_amd64.deb ... 12:05:13 Unpacking libpython-all-dev:amd64 (2.7.16-1) ... 12:05:13 Selecting previously unselected package python-all. 12:05:13 Preparing to unpack .../20-python-all_2.7.16-1_amd64.deb ... 12:05:13 Unpacking python-all (2.7.16-1) ... 12:05:13 Selecting previously unselected package python2.7-dev. 12:05:13 Preparing to unpack .../21-python2.7-dev_2.7.16-2+deb10u1_amd64.deb ... 12:05:13 Unpacking python2.7-dev (2.7.16-2+deb10u1) ... 12:05:13 Selecting previously unselected package python2-dev. 12:05:13 Preparing to unpack .../22-python2-dev_2.7.16-1_amd64.deb ... 12:05:13 Unpacking python2-dev (2.7.16-1) ... 12:05:13 Selecting previously unselected package python-dev. 12:05:13 Preparing to unpack .../23-python-dev_2.7.16-1_amd64.deb ... 12:05:13 Unpacking python-dev (2.7.16-1) ... 12:05:13 Selecting previously unselected package python-all-dev. 12:05:13 Preparing to unpack .../24-python-all-dev_2.7.16-1_amd64.deb ... 12:05:13 Unpacking python-all-dev (2.7.16-1) ... 12:05:13 Selecting previously unselected package python-asn1crypto. 12:05:13 Preparing to unpack .../25-python-asn1crypto_0.24.0-1_all.deb ... 12:05:13 Unpacking python-asn1crypto (0.24.0-1) ... 12:05:13 Selecting previously unselected package python-cffi-backend. 12:05:13 Preparing to unpack .../26-python-cffi-backend_1.12.2-1_amd64.deb ... 12:05:13 Unpacking python-cffi-backend (1.12.2-1) ... 12:05:13 Selecting previously unselected package python-configparser. 12:05:13 Preparing to unpack .../27-python-configparser_3.5.0b2-1_all.deb ... 12:05:13 Unpacking python-configparser (3.5.0b2-1) ... 12:05:13 Selecting previously unselected package python-crypto. 12:05:13 Preparing to unpack .../28-python-crypto_2.6.1-9+b1_amd64.deb ... 12:05:13 Unpacking python-crypto (2.6.1-9+b1) ... 12:05:13 Selecting previously unselected package python-enum34. 12:05:13 Preparing to unpack .../29-python-enum34_1.1.6-2_all.deb ... 12:05:13 Unpacking python-enum34 (1.1.6-2) ... 12:05:13 Selecting previously unselected package python-ipaddress. 12:05:13 Preparing to unpack .../30-python-ipaddress_1.0.17-1_all.deb ... 12:05:13 Unpacking python-ipaddress (1.0.17-1) ... 12:05:13 Selecting previously unselected package python-six. 12:05:13 Preparing to unpack .../31-python-six_1.12.0-1_all.deb ... 12:05:13 Unpacking python-six (1.12.0-1) ... 12:05:13 Selecting previously unselected package python-cryptography. 12:05:13 Preparing to unpack .../32-python-cryptography_2.6.1-3+deb10u2_amd64.deb ... 12:05:13 Unpacking python-cryptography (2.6.1-3+deb10u2) ... 12:05:13 Selecting previously unselected package python-dbus. 12:05:13 Preparing to unpack .../33-python-dbus_1.2.8-3_amd64.deb ... 12:05:13 Unpacking python-dbus (1.2.8-3) ... 12:05:13 Selecting previously unselected package python-entrypoints. 12:05:13 Preparing to unpack .../34-python-entrypoints_0.3-1_all.deb ... 12:05:13 Unpacking python-entrypoints (0.3-1) ... 12:05:13 Selecting previously unselected package python-gi. 12:05:13 Preparing to unpack .../35-python-gi_3.30.4-1_amd64.deb ... 12:05:13 Unpacking python-gi (3.30.4-1) ... 12:05:13 Selecting previously unselected package python-secretstorage. 12:05:13 Preparing to unpack .../36-python-secretstorage_2.3.1-2_all.deb ... 12:05:13 Unpacking python-secretstorage (2.3.1-2) ... 12:05:13 Selecting previously unselected package python-keyring. 12:05:13 Preparing to unpack .../37-python-keyring_17.1.1-1_all.deb ... 12:05:13 Unpacking python-keyring (17.1.1-1) ... 12:05:13 Selecting previously unselected package python-keyrings.alt. 12:05:13 Preparing to unpack .../38-python-keyrings.alt_3.1.1-1_all.deb ... 12:05:13 Unpacking python-keyrings.alt (3.1.1-1) ... 12:05:13 Selecting previously unselected package python-pip-whl. 12:05:13 Preparing to unpack .../39-python-pip-whl_18.1-5_all.deb ... 12:05:13 Unpacking python-pip-whl (18.1-5) ... 12:05:14 Selecting previously unselected package python-pip. 12:05:14 Preparing to unpack .../40-python-pip_18.1-5_all.deb ... 12:05:14 Unpacking python-pip (18.1-5) ... 12:05:14 Selecting previously unselected package python-pkg-resources. 12:05:14 Preparing to unpack .../41-python-pkg-resources_40.8.0-1_all.deb ... 12:05:14 Unpacking python-pkg-resources (40.8.0-1) ... 12:05:14 Selecting previously unselected package python-setuptools. 12:05:14 Preparing to unpack .../42-python-setuptools_40.8.0-1_all.deb ... 12:05:14 Unpacking python-setuptools (40.8.0-1) ... 12:05:14 Selecting previously unselected package python-wheel. 12:05:14 Preparing to unpack .../43-python-wheel_0.32.3-2_all.deb ... 12:05:14 Unpacking python-wheel (0.32.3-2) ... 12:05:14 Selecting previously unselected package python-xdg. 12:05:14 Preparing to unpack .../44-python-xdg_0.25-5_all.deb ... 12:05:14 Unpacking python-xdg (0.25-5) ... 12:05:14 Setting up libexpat1:amd64 (2.2.6-2+deb10u1) ... 12:05:14 Setting up javascript-common (11) ... 12:05:14 Setting up python-enum34 (1.1.6-2) ... 12:05:15 Setting up python-crypto (2.6.1-9+b1) ... 12:05:15 Setting up libapparmor1:amd64 (2.13.2-10) ... 12:05:15 Setting up python-wheel (0.32.3-2) ... 12:05:16 Setting up python-pkg-resources (40.8.0-1) ... 12:05:16 Setting up python-asn1crypto (0.24.0-1) ... 12:05:17 Setting up python-six (1.12.0-1) ... 12:05:17 Setting up python-xdg (0.25-5) ... 12:05:18 Setting up libpython2.7-minimal:amd64 (2.7.16-2+deb10u1) ... 12:05:18 Setting up libexpat1-dev:amd64 (2.2.6-2+deb10u1) ... 12:05:18 Setting up python2.7-minimal (2.7.16-2+deb10u1) ... 12:05:18 Setting up libdbus-1-3:amd64 (1.12.20-0+deb10u1) ... 12:05:18 Setting up dbus (1.12.20-0+deb10u1) ... 12:05:18 invoke-rc.d: could not determine current runlevel 12:05:18 invoke-rc.d: policy-rc.d denied execution of start. 12:05:18 Setting up python-setuptools (40.8.0-1) ... 12:05:19 Setting up libgirepository-1.0-1:amd64 (1.58.3-2) ... 12:05:19 Setting up python-pip-whl (18.1-5) ... 12:05:19 Setting up libjs-jquery (3.3.1~dfsg-3+deb10u1) ... 12:05:19 Setting up python-ipaddress (1.0.17-1) ... 12:05:19 Setting up libjs-underscore (1.9.1~dfsg-1+deb10u1) ... 12:05:19 Setting up python-cffi-backend (1.12.2-1) ... 12:05:20 Setting up python-keyrings.alt (3.1.1-1) ... 12:05:20 Setting up python-cryptography (2.6.1-3+deb10u2) ... 12:05:21 Setting up libpython2.7-stdlib:amd64 (2.7.16-2+deb10u1) ... 12:05:21 Setting up python-dbus (1.2.8-3) ... 12:05:21 Setting up python-pip (18.1-5) ... 12:05:22 Setting up gir1.2-glib-2.0:amd64 (1.58.3-2) ... 12:05:22 Setting up libjs-sphinxdoc (1.8.4-1) ... 12:05:22 Setting up python-gi (3.30.4-1) ... 12:05:22 Setting up libpython2.7:amd64 (2.7.16-2+deb10u1) ... 12:05:22 Setting up libpython2.7-dev:amd64 (2.7.16-2+deb10u1) ... 12:05:22 Setting up python-secretstorage (2.3.1-2) ... 12:05:23 Setting up python2.7 (2.7.16-2+deb10u1) ... 12:05:24 Setting up python-all (2.7.16-1) ... 12:05:24 Setting up python-configparser (3.5.0b2-1) ... 12:05:24 Setting up libpython2-dev:amd64 (2.7.16-1) ... 12:05:24 Setting up python2.7-dev (2.7.16-2+deb10u1) ... 12:05:24 Setting up python2-dev (2.7.16-1) ... 12:05:24 Setting up python-entrypoints (0.3-1) ... 12:05:25 Setting up libpython-dev:amd64 (2.7.16-1) ... 12:05:25 Setting up python-dev (2.7.16-1) ... 12:05:25 Setting up libpython-all-dev:amd64 (2.7.16-1) ... 12:05:25 Setting up python-keyring (17.1.1-1) ... 12:05:25 Setting up python-all-dev (2.7.16-1) ... 12:05:25 Processing triggers for libc-bin (2.28-10) ... 12:05:25 Processing triggers for mime-support (3.62) ... 12:05:26 Collecting virtualenv 12:05:31 Downloading https://files.pythonhosted.org/packages/03/08/f819421002e85a71d58368f7bffbe0b1921325e0e8ca7857cb5fb0e1f7c1/virtualenv-20.4.7-py2.py3-none-any.whl (7.2MB) 12:05:32 Collecting importlib-metadata>=0.12; python_version < "3.8" (from virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/98/b8/8ec57a8ef46fbe7f185318c7ff7df9a06c9df451d9a59a067bfa851bb828/importlib_metadata-2.1.1-py2.py3-none-any.whl 12:05:32 Collecting filelock<4,>=3.0.0 (from virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/14/ec/6ee2168387ce0154632f856d5cc5592328e9cf93127c5c9aeca92c8c16cb/filelock-3.0.12.tar.gz 12:05:32 Collecting distlib<1,>=0.3.1 (from virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/87/26/f6a23dd3e578132cf924e0dd5d4e055af0cd4ab43e2a9f10b7568bfb39d9/distlib-0.3.2-py2.py3-none-any.whl (338kB) 12:05:32 Collecting importlib-resources>=1.0; python_version < "3.7" (from virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/8d/94/2f6ceee0c4e63bff0177c07e68d27c937a19f6bc77c4739755b49f5adb04/importlib_resources-3.3.1-py2.py3-none-any.whl 12:05:32 Collecting appdirs<2,>=1.4.3 (from virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/3b/00/2344469e2084fb287c2e0b57b72910309874c3245463acd6cf5e3db69324/appdirs-1.4.4-py2.py3-none-any.whl 12:05:32 Collecting pathlib2<3,>=2.3.3; python_version < "3.4" and sys_platform != "win32" (from virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/e9/45/9c82d3666af4ef9f221cbb954e1d77ddbb513faf552aea6df5f37f1a4859/pathlib2-2.3.5-py2.py3-none-any.whl 12:05:32 Requirement already satisfied: six<2,>=1.9.0 in /usr/lib/python2.7/dist-packages (from virtualenv) (1.12.0) 12:05:32 Collecting configparser>=3.5; python_version < "3" (from importlib-metadata>=0.12; python_version < "3.8"->virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/7a/2a/95ed0501cf5d8709490b1d3a3f9b5cf340da6c433f896bbe9ce08dbe6785/configparser-4.0.2-py2.py3-none-any.whl 12:05:32 Collecting contextlib2; python_version < "3" (from importlib-metadata>=0.12; python_version < "3.8"->virtualenv) 12:05:32 Downloading https://files.pythonhosted.org/packages/85/60/370352f7ef6aa96c52fb001831622f50f923c1d575427d021b8ab3311236/contextlib2-0.6.0.post1-py2.py3-none-any.whl 12:05:33 Collecting zipp>=0.5 (from importlib-metadata>=0.12; python_version < "3.8"->virtualenv) 12:05:33 Downloading https://files.pythonhosted.org/packages/96/0a/67556e9b7782df7118c1f49bdc494da5e5e429c93aa77965f33e81287c8c/zipp-1.2.0-py2.py3-none-any.whl 12:05:33 Collecting typing; python_version < "3.5" (from importlib-resources>=1.0; python_version < "3.7"->virtualenv) 12:05:33 Downloading https://files.pythonhosted.org/packages/0b/cb/da856e81731833b94da70a08712f658416266a5fb2a9d9e426c8061becef/typing-3.10.0.0-py2-none-any.whl 12:05:33 Collecting singledispatch; python_version < "3.4" (from importlib-resources>=1.0; python_version < "3.7"->virtualenv) 12:05:33 Downloading https://files.pythonhosted.org/packages/cd/d1/6a9e922826e03f5af7bf348cfb75bcb0bc4c67e19c36805c2545f34427e5/singledispatch-3.6.2-py2.py3-none-any.whl 12:05:33 Collecting scandir; python_version < "3.5" (from pathlib2<3,>=2.3.3; python_version < "3.4" and sys_platform != "win32"->virtualenv) 12:05:33 Downloading https://files.pythonhosted.org/packages/df/f5/9c052db7bd54d0cbf1bc0bb6554362bba1012d03e5888950a4f5c5dadc4e/scandir-1.10.0.tar.gz 12:05:33 Building wheels for collected packages: filelock, scandir 12:05:33 Running setup.py bdist_wheel for filelock: started 12:05:33 Running setup.py bdist_wheel for filelock: finished with status 'done' 12:05:33 Stored in directory: /root/.cache/pip/wheels/66/13/60/ef107438d90e4aad6320e3424e50cfce5e16d1e9aad6d38294 12:05:33 Running setup.py bdist_wheel for scandir: started 12:05:33 Running setup.py bdist_wheel for scandir: finished with status 'done' 12:05:33 Stored in directory: /root/.cache/pip/wheels/91/95/75/19c98a91239878abbc7c59970abd3b4e0438a7dd5b61778335 12:05:33 Successfully built filelock scandir 12:05:33 Installing collected packages: configparser, contextlib2, scandir, pathlib2, zipp, importlib-metadata, filelock, distlib, typing, singledispatch, importlib-resources, appdirs, virtualenv 12:05:33 Found existing installation: configparser 3.5.0b2 12:05:33 Not uninstalling configparser at /usr/lib/python2.7/dist-packages, outside environment /usr 12:05:33 Can't uninstall 'configparser'. No files were found to uninstall. 12:05:34 Successfully installed appdirs-1.4.4 configparser-4.0.2 contextlib2-0.6.0.post1 distlib-0.3.2 filelock-3.0.12 importlib-metadata-2.1.1 importlib-resources-3.3.1 pathlib2-2.3.5 scandir-1.10.0 singledispatch-3.6.2 typing-3.10.0.0 virtualenv-20.4.7 zipp-1.2.0 12:05:37 Removing intermediate container 079d249ad3af 12:05:37 ---> ac32a6af0bc1 12:05:37 Step 3/14 : RUN GO111MODULE=off go get github.com/aktau/github-release && GO111MODULE=off go get golang.org/x/tools/cmd/cover && GO111MODULE=off go get golang.org/x/lint/golint 12:05:37 ---> Running in 6af57ca0d7d6 12:05:55 Removing intermediate container 6af57ca0d7d6 12:05:55 ---> 0197d7e822eb 12:05:55 Step 4/14 : ENV DOCKER_VERSIONS 1.9.1 1.10.3 1.13.1 17.03.2 17.06.0 12:05:55 ---> Running in 508071eb0938 12:05:55 Removing intermediate container 508071eb0938 12:05:55 ---> 0c5c284b6af9 12:05:55 Step 5/14 : ENV DEFAULT_DOCKER_VERSION 17.03.2 12:05:55 ---> Running in e6f6c75bf78c 12:05:55 Removing intermediate container e6f6c75bf78c 12:05:55 ---> 0d94c7d587b0 12:05:55 Step 6/14 : RUN set -e; set -x; for v in $(echo ${DOCKER_VERSIONS} | cut -f1); do if test "${v}" = "1.9.1" || test "${v}" = "1.10.3"; then mkdir -p /usr/local/bin/docker-${v}/; curl https://get.docker.com/builds/Linux/x86_64/docker-${v} -o /usr/local/bin/docker-${v}/docker; chmod +x /usr/local/bin/docker-${v}/docker; elif test "${v}" = "1.13.1"; then curl https://get.docker.com/builds/Linux/x86_64/docker-${v}.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; else curl https://download.docker.com/linux/static/stable/x86_64/docker-${v}-ce.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; fi done 12:05:55 ---> Running in be3fe779cc62 12:05:55 + echo 1.9.1 1.10.3 1.13.1 17.03.2 17.06.0 12:05:55 + cut -f1 12:05:55 + test 1.9.1 = 1.9.1 12:05:55 + mkdir -p /usr/local/bin/docker-1.9.1/ 12:05:55 + curl https://get.docker.com/builds/Linux/x86_64/docker-1.9.1 -o /usr/local/bin/docker-1.9.1/docker 12:05:55  % Total % Received % Xferd Average Speed Time Time Time Current 12:05:55 Dload Upload Total Spent Left Speed 12:05:55 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 83 28.8M 83 24.0M 0 0 21.6M 0 0:00:01 0:00:01 --:--:-- 21.6M 100 28.8M 100 28.8M 0 0 23.7M 0 0:00:01 0:00:01 --:--:-- 23.7M 12:05:55 + chmod +x /usr/local/bin/docker-1.9.1/docker 12:05:55 + test 1.10.3 = 1.9.1 12:05:55 + test 1.10.3 = 1.10.3 12:05:55 + mkdir -p /usr/local/bin/docker-1.10.3/ 12:05:55 + curl https://get.docker.com/builds/Linux/x86_64/docker-1.10.3 -o /usr/local/bin/docker-1.10.3/docker 12:05:55  % Total % Received % Xferd Average Speed Time   Time Time Current 12:05:55 Dload Upload Total Spent Left Speed 12:05:55 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 33.2M 100 33.2M 0 0 95.0M 0 --:--:-- --:--:-- --:--:-- 95.0M 12:05:55 + chmod +x /usr/local/bin/docker-1.10.3/docker 12:05:55 + test 1.13.1 = 1.9.1 12:05:55 + test 1.13.1 = 1.10.3 12:05:55 + test 1.13.1 = 1.13.1 12:05:55 + curl https://get.docker.com/builds/Linux/x86_64/docker-1.13.1.tgz -o docker-1.13.1.tgz 12:05:55  % Total  % Received % Xferd Average Speed Time Time Time Current 12:05:55   Dload Upload  Total Spent Left Speed 12:05:55   0 0 0 0 0 0 0  0 --:--:-- --:--:-- --:--:-- 0 41 26.5M 41 11.1M 0  0 18.0M 0 0:00:01 --:--:-- 0:00:01 18.0M 100 26.5M 100 26.5M  0 0 27.5M 0 --:--:-- --:--:-- --:--:-- 27.5M 12:05:55 + tar xzf docker-1.13.1.tgz -C /usr/local/bin/ 12:05:56 + mv /usr/local/bin/docker /usr/local/bin/docker-1.13.1 12:05:56 + rm docker-1.13.1.tgz 12:05:56 + test 17.03.2 = 1.9.1 12:05:56 + test 17.03.2 = 1.10.3 12:05:56 + test 17.03.2 = 1.13.1 12:05:56 + curl https://download.docker.com/linux/static/stable/x86_64/docker-17.03.2-ce.tgz -o docker-17.03.2.tgz 12:05:56  % Total % Received % Xferd Average Speed Time Time Time Current 12:05:56 Dload Upload Total Spent Left Speed 12:05:57 0 0 0 0  0 0 0 0 --:--:-- --:--:-- --:--:-- 0 89 26.5M 89 23.8M 0 0 27.4M 0 --:--:-- --:--:-- --:--:-- 27.4M 100 26.5M 100 26.5M 0 0 28.4M 0 --:--:-- --:--:-- --:--:-- 28.4M 12:05:57 + tar xzf docker-17.03.2.tgz -C /usr/local/bin/ 12:05:58 + mv /usr/local/bin/docker /usr/local/bin/docker-17.03.2 12:05:58 + rm docker-17.03.2.tgz 12:05:58 + test 17.06.0 = 1.9.1 12:05:58 + test 17.06.0 = 1.10.3 12:05:58 + test 17.06.0 = 1.13.1 12:05:58 + curl https://download.docker.com/linux/static/stable/x86_64/docker-17.06.0-ce.tgz -o docker-17.06.0.tgz 12:05:58  % Total % Received % Xferd Average Speed Time Time Time Current 12:05:58 Dload Upload Total Spent Left Speed 12:05:58  0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 4 28.2M 4 1258k  0 0 5376k 0 0:00:05 --:--:-- 0:00:05 5353k 100 28.2M 100 28.2M 0 0 71.2M 0 --:--:-- --:--:-- --:--:-- 71.0M 12:05:58 + tar xzf docker-17.06.0.tgz -C /usr/local/bin/ 12:05:59 + mv /usr/local/bin/docker /usr/local/bin/docker-17.06.0 12:05:59 + rm docker-17.06.0.tgz 12:06:07 Removing intermediate container be3fe779cc62 12:06:07 ---> 5de95b94ca80 12:06:07 Step 7/14 : RUN ln -s /usr/local/bin/docker-${DEFAULT_DOCKER_VERSION} /usr/local/bin/docker 12:06:07 ---> Running in ae093719830e 12:06:08 Removing intermediate container ae093719830e 12:06:08 ---> f3720aca4edd 12:06:08 Step 8/14 : WORKDIR /go/src/github.com/docker/libcompose 12:06:08 ---> Running in bdd8a655e03e 12:06:08 Removing intermediate container bdd8a655e03e 12:06:08 ---> cc78b7416318 12:06:08 Step 9/14 : ENV COMPOSE_COMMIT 1.9.0 12:06:08 ---> Running in f95bdcad54d0 12:06:08 Removing intermediate container f95bdcad54d0 12:06:08 ---> 5a43dd4377d3 12:06:08 Step 10/14 : RUN virtualenv venv && git clone https://github.com/docker/compose.git venv/compose && cd venv/compose && git checkout -q "$COMPOSE_COMMIT" && ../bin/pip install -r requirements.txt -r requirements-dev.txt 12:06:08 ---> Running in 78ec282b530a 12:06:09 created virtual environment CPython2.7.16.final.0-64 in 556ms 12:06:09 creator CPython2Posix(dest=/go/src/github.com/docker/libcompose/venv, clear=False, no_vcs_ignore=False, global=False) 12:06:09 seeder FromAppData(download=False, pip=bundle, wheel=bundle, setuptools=bundle, via=copy, app_data_dir=/root/.local/share/virtualenv) 12:06:09 added seed packages: pip==20.3.4, setuptools==44.1.1, wheel==0.36.2 12:06:09 activators PythonActivator,CShellActivator,FishActivator,PowerShellActivator,BashActivator 12:06:09 Cloning into 'venv/compose'... 12:06:12 DEPRECATION: Python 2.7 reached the end of its life on January 1st, 2020. Please upgrade your Python as Python 2.7 is no longer maintained. pip 21.0 will drop support for Python 2.7 in January 2021. More details about Python 2 support in pip can be found at https://pip.pypa.io/en/latest/development/release-process/#python-2-support pip 21.0 will remove support for this functionality. 12:06:12 Ignoring pypiwin32: markers 'sys_platform == "win32"' don't match your environment 12:06:16 Collecting PyYAML==3.11 12:06:17 Downloading PyYAML-3.11.tar.gz (248 kB) 12:06:18 Collecting backports.ssl-match-hostname==3.5.0.1 12:06:18 Downloading backports.ssl_match_hostname-3.5.0.1.tar.gz (5.6 kB) 12:06:18 Collecting cached-property==1.2.0 12:06:18 Downloading cached_property-1.2.0-py2.py3-none-any.whl (8.3 kB) 12:06:18 Collecting docker-py==1.10.6 12:06:18 Downloading docker_py-1.10.6-py2.py3-none-any.whl (50 kB) 12:06:18 Collecting dockerpty==0.4.1 12:06:18 Downloading dockerpty-0.4.1.tar.gz (13 kB) 12:06:18 Collecting docopt==0.6.1 12:06:18 Downloading docopt-0.6.1.tar.gz (25 kB) 12:06:18 Collecting enum34==1.0.4 12:06:18 Downloading enum34-1.0.4.tar.gz (32 kB) 12:06:18 Collecting functools32==3.2.3.post2 12:06:18 Downloading functools32-3.2.3-2.tar.gz (31 kB) 12:06:18 Collecting ipaddress==1.0.16 12:06:18 Downloading ipaddress-1.0.16-py27-none-any.whl (17 kB) 12:06:18 Collecting jsonschema==2.5.1 12:06:18 Downloading jsonschema-2.5.1-py2.py3-none-any.whl (38 kB) 12:06:19 Collecting requests==2.11.1 12:06:19 Downloading requests-2.11.1-py2.py3-none-any.whl (514 kB) 12:06:19 Collecting six==1.10.0 12:06:19 Downloading six-1.10.0-py2.py3-none-any.whl (10 kB) 12:06:19 Collecting texttable==0.8.4 12:06:19 Downloading texttable-0.8.4.tar.gz (9.7 kB) 12:06:19 Collecting websocket-client==0.32.0 12:06:19 Downloading websocket_client-0.32.0.tar.gz (192 kB) 12:06:19 Collecting coverage==3.7.1 12:06:19 Downloading coverage-3.7.1.tar.gz (284 kB) 12:06:20 Collecting mock>=1.0.1 12:06:20 Downloading mock-3.0.5-py2.py3-none-any.whl (25 kB) 12:06:20 Collecting pytest==2.7.2 12:06:20 Downloading pytest-2.7.2-py2.py3-none-any.whl (127 kB) 12:06:20 Collecting pytest-cov==2.1.0 12:06:20 Downloading pytest_cov-2.1.0-py2.py3-none-any.whl (16 kB) 12:06:20 Collecting docker-pycreds>=0.2.1 12:06:20 Downloading docker_pycreds-0.4.0-py2.py3-none-any.whl (9.0 kB) 12:06:20 Collecting funcsigs>=1; python_version < "3.3" 12:06:20 Downloading funcsigs-1.0.2-py2.py3-none-any.whl (17 kB) 12:06:20 Collecting py>=1.4.29 12:06:20 Downloading py-1.10.0-py2.py3-none-any.whl (97 kB) 12:06:20 Building wheels for collected packages: PyYAML, backports.ssl-match-hostname, dockerpty, docopt, enum34, functools32, texttable, websocket-client, coverage 12:06:20 Building wheel for PyYAML (setup.py): started 12:06:20 Building wheel for PyYAML (setup.py): finished with status 'done' 12:06:20 Created wheel for PyYAML: filename=PyYAML-3.11-cp27-cp27mu-linux_x86_64.whl size=43798 sha256=0853082136ba940e0086a383aa179bd3d3ddc8acc8ddd27354e136420aaa2ae2 12:06:20 Stored in directory: /root/.cache/pip/wheels/49/4a/a0/82418a772ad2d883345e7b8d84b41eef459defb9d7cb8cf87f 12:06:20 Building wheel for backports.ssl-match-hostname (setup.py): started 12:06:20 Building wheel for backports.ssl-match-hostname (setup.py): finished with status 'done' 12:06:20 Created wheel for backports.ssl-match-hostname: filename=backports.ssl_match_hostname-3.5.0.1-py2-none-any.whl size=5210 sha256=f8e5d32efe5f8e73434c5fec04ca2a87cebe1a4e2775929a2731af3c37fc705f 12:06:20 Stored in directory: /root/.cache/pip/wheels/19/dd/8c/6cad8e6c38a9fe006dd7423ca1655bb1eb3cb94c5ce1814e4f 12:06:20 Building wheel for dockerpty (setup.py): started 12:06:21 Building wheel for dockerpty (setup.py): finished with status 'done' 12:06:21 Created wheel for dockerpty: filename=dockerpty-0.4.1-py2-none-any.whl size=16605 sha256=35273a51db1b0fa15633b3a394eb4cd17210eaeb8cef2a8c2f8968fdd857cc95 12:06:21 Stored in directory: /root/.cache/pip/wheels/2c/8d/0b/b44b8a42ff445c282dd42fee40cf93afb2b0325ba996695506 12:06:21 Building wheel for docopt (setup.py): started 12:06:21 Building wheel for docopt (setup.py): finished with status 'done' 12:06:21 Created wheel for docopt: filename=docopt-0.6.1-py2-none-any.whl size=13679 sha256=27fcb2bf35b73a290575fe5731d0e7f92c1d27c118c4a4a48e53f0ccb23d4ca4 12:06:21 Stored in directory: /root/.cache/pip/wheels/0a/ab/01/3cb99bbaa0662465dc448e56f0130ff49b9006a95793397c3a 12:06:21 Building wheel for enum34 (setup.py): started 12:06:21 Building wheel for enum34 (setup.py): finished with status 'done' 12:06:21 Created wheel for enum34: filename=enum34-1.0.4-py2-none-any.whl size=42883 sha256=ca1f436fb9f4cdbd765ddc4b212b36d1069f652e4587a34b0a623b986b48ef1e 12:06:21 Stored in directory: /root/.cache/pip/wheels/a6/b9/2c/46c4c9c528819485a1b2e7d2781ac0e94b360f29b010aa6983 12:06:21 Building wheel for functools32 (setup.py): started 12:06:21 Building wheel for functools32 (setup.py): finished with status 'done' 12:06:21 Created wheel for functools32: filename=functools32-3.2.3.post2-py2-none-any.whl size=14556 sha256=e982d2582da3363e2eb2d786636799bf41abb57bc91e64371ca735b661518f40 12:06:21 Stored in directory: /root/.cache/pip/wheels/c2/ea/a3/25af52265fad6418a74df0b8d9ca8b89e0b3735dbd4d0d3794 12:06:21 Building wheel for texttable (setup.py): started 12:06:21 Building wheel for texttable (setup.py): finished with status 'done' 12:06:21 Created wheel for texttable: filename=texttable-0.8.4-py2-none-any.whl size=9558 sha256=774e459dfe27784b8c7bb3e82174a2efda70b12840afda8cf47408d493d282ed 12:06:21 Stored in directory: /root/.cache/pip/wheels/77/73/95/d3c99a9620dc6aef1de5f708915ffafa9cc2c022ea852cc657 12:06:21 Building wheel for websocket-client (setup.py): started 12:06:21 Building wheel for websocket-client (setup.py): finished with status 'done' 12:06:21 Created wheel for websocket-client: filename=websocket_client-0.32.0-py2-none-any.whl size=195670 sha256=536cd637ef3bba443213be36944b3123a91197c08f47d6fd0eca3574e4d0bc7d 12:06:21 Stored in directory: /root/.cache/pip/wheels/95/6a/21/6ae43920d58661faa1751bc5469183517853bb8936fb284ba7 12:06:21 Building wheel for coverage (setup.py): started 12:06:22 Building wheel for coverage (setup.py): finished with status 'done' 12:06:22 Created wheel for coverage: filename=coverage-3.7.1-cp27-cp27mu-linux_x86_64.whl size=128021 sha256=be79665325910726964aa66d3943c8dff53d543ceb75da6af5aacd5eaa00692c 12:06:22 Stored in directory: /root/.cache/pip/wheels/a0/7c/11/ea29ce2c4f2d0c8e4d0f5f09ca6a333da532ae1e4641f29d04 12:06:22 Successfully built PyYAML backports.ssl-match-hostname dockerpty docopt enum34 functools32 texttable websocket-client coverage 12:06:22 Installing collected packages: PyYAML, backports.ssl-match-hostname, cached-property, six, ipaddress, requests, docker-pycreds, websocket-client, docker-py, dockerpty, docopt, enum34, functools32, jsonschema, texttable, coverage, funcsigs, mock, py, pytest, pytest-cov 12:06:23 Successfully installed PyYAML-3.11 backports.ssl-match-hostname-3.5.0.1 cached-property-1.2.0 coverage-3.7.1 docker-py-1.10.6 docker-pycreds-0.4.0 dockerpty-0.4.1 docopt-0.6.1 enum34-1.0.4 funcsigs-1.0.2 functools32-3.2.3.post2 ipaddress-1.0.16 jsonschema-2.5.1 mock-3.0.5 py-1.10.0 pytest-2.7.2 pytest-cov-2.1.0 requests-2.11.1 six-1.10.0 texttable-0.8.4 websocket-client-0.32.0 12:06:29 Removing intermediate container 78ec282b530a 12:06:29 ---> 2e25effa23dd 12:06:29 Step 11/14 : ENV COMPOSE_BINARY /go/src/github.com/docker/libcompose/libcompose-cli 12:06:29 ---> Running in c49d3ffe57fb 12:06:29 Removing intermediate container c49d3ffe57fb 12:06:29 ---> 74a69ff8454c 12:06:29 Step 12/14 : ENV USER root 12:06:29 ---> Running in 33a0ee5708cb 12:06:29 Removing intermediate container 33a0ee5708cb 12:06:29 ---> aae04cb7e5c1 12:06:29 Step 13/14 : ENTRYPOINT ["hack/dind"] 12:06:29 ---> Running in 92b81dfcd648 12:06:29 Removing intermediate container 92b81dfcd648 12:06:29 ---> 442fdfff171c 12:06:29 Step 14/14 : COPY . /go/src/github.com/docker/libcompose 12:06:29 ---> a855da89c5b5 12:06:29 Successfully built a855da89c5b5 12:06:29 Successfully tagged dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 [Pipeline] stage 12:06:29 Using the ‘stage’ step without a block argument is deprecated 12:06:29 Entering stage validate 12:06:29 Proceeding [Pipeline] withEnv [Pipeline] { [Pipeline] timeout 12:06:29 Timeout set to expire in 1 hr 0 min [Pipeline] { [Pipeline] sh 12:06:30 + make -e validate 12:06:30 mkdir bundles 12:06:30 docker build -t "dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1" . 12:06:30 Sending build context to Docker daemon 6.576MB 12:06:30 Step 1/14 : FROM golang:1.12.7 12:06:30 ---> be63d15101cb 12:06:30 Step 2/14 : RUN apt-get update && apt-get install -y iptables build-essential --no-install-recommends && apt-get install -y python-pip && pip install virtualenv 12:06:30 ---> Using cache 12:06:30 ---> ac32a6af0bc1 12:06:30 Step 3/14 : RUN GO111MODULE=off go get github.com/aktau/github-release && GO111MODULE=off go get golang.org/x/tools/cmd/cover && GO111MODULE=off go get golang.org/x/lint/golint 12:06:30 ---> Using cache 12:06:30 ---> 0197d7e822eb 12:06:30 Step 4/14 : ENV DOCKER_VERSIONS 1.9.1 1.10.3 1.13.1 17.03.2 17.06.0 12:06:30 ---> Using cache 12:06:30 ---> 0c5c284b6af9 12:06:30 Step 5/14 : ENV DEFAULT_DOCKER_VERSION 17.03.2 12:06:30 ---> Using cache 12:06:30 ---> 0d94c7d587b0 12:06:30 Step 6/14 : RUN set -e; set -x; for v in $(echo ${DOCKER_VERSIONS} | cut -f1); do if test "${v}" = "1.9.1" || test "${v}" = "1.10.3"; then mkdir -p /usr/local/bin/docker-${v}/; curl https://get.docker.com/builds/Linux/x86_64/docker-${v} -o /usr/local/bin/docker-${v}/docker; chmod +x /usr/local/bin/docker-${v}/docker; elif test "${v}" = "1.13.1"; then curl https://get.docker.com/builds/Linux/x86_64/docker-${v}.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; else curl https://download.docker.com/linux/static/stable/x86_64/docker-${v}-ce.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; fi done 12:06:30 ---> Using cache 12:06:30 ---> 5de95b94ca80 12:06:30 Step 7/14 : RUN ln -s /usr/local/bin/docker-${DEFAULT_DOCKER_VERSION} /usr/local/bin/docker 12:06:30 ---> Using cache 12:06:30 ---> f3720aca4edd 12:06:30 Step 8/14 : WORKDIR /go/src/github.com/docker/libcompose 12:06:30 ---> Using cache 12:06:30 ---> cc78b7416318 12:06:30 Step 9/14 : ENV COMPOSE_COMMIT 1.9.0 12:06:30 ---> Using cache 12:06:30 ---> 5a43dd4377d3 12:06:30 Step 10/14 : RUN virtualenv venv && git clone https://github.com/docker/compose.git venv/compose && cd venv/compose && git checkout -q "$COMPOSE_COMMIT" && ../bin/pip install -r requirements.txt -r requirements-dev.txt 12:06:30 ---> Using cache 12:06:30 ---> 2e25effa23dd 12:06:30 Step 11/14 : ENV COMPOSE_BINARY /go/src/github.com/docker/libcompose/libcompose-cli 12:06:30 ---> Using cache 12:06:30 ---> 74a69ff8454c 12:06:30 Step 12/14 : ENV USER root 12:06:30 ---> Using cache 12:06:30 ---> aae04cb7e5c1 12:06:30 Step 13/14 : ENTRYPOINT ["hack/dind"] 12:06:30 ---> Using cache 12:06:30 ---> 442fdfff171c 12:06:30 Step 14/14 : COPY . /go/src/github.com/docker/libcompose 12:06:30 ---> Using cache 12:06:30 ---> a855da89c5b5 12:06:30 Successfully built a855da89c5b5 12:06:30 Successfully tagged dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 12:06:30 docker run --rm -i --privileged -e DAEMON_VERSION=""default"" -e OS_PLATFORM_ARG -e OS_ARCH_ARG -e DOCKER_TEST_HOST -e TESTDIRS -e TESTFLAGS -e SHOWWARNING -e TESTVERBOSE -v "/home/ubuntu/workspace/libcompose_master/bundles:/go/src/github.com/docker/libcompose/bundles" "dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1" ./hack/make.sh validate-dco validate-git-marks validate-gofmt validate-lint validate-vet 12:06:30 ---> Making bundle: validate-dco (in .) 12:06:30 0 adds, 0 deletions; nothing to validate! :) 12:06:30 12:06:30 ---> Making bundle: validate-git-marks (in .) 12:06:30 Congratulations! There is no conflict. 12:06:30 12:06:30 ---> Making bundle: validate-gofmt (in .) 12:06:30 Congratulations! All Go source files are properly formatted. 12:06:30 12:06:30 ---> Making bundle: validate-lint (in .) 12:06:30 Congratulations! All Go source files have been linted. 12:06:30 12:06:30 ---> Making bundle: validate-vet (in .) 12:06:30 Congratulations! All Go source files have been vetted. 12:06:30 [Pipeline] } [Pipeline] // timeout [Pipeline] echo 12:06:31 chowning workspace [Pipeline] sh 12:06:31 + pwd 12:06:31 + id -u 12:06:31 + id -g 12:06:31 + docker run --rm -v /home/ubuntu/workspace/libcompose_master:/workspace busybox chown -R 1000:1000 /workspace [Pipeline] } [Pipeline] // withEnv [Pipeline] stage 12:06:31 Using the ‘stage’ step without a block argument is deprecated 12:06:31 Entering stage test 12:06:31 Proceeding [Pipeline] withEnv [Pipeline] { [Pipeline] timeout 12:06:31 Timeout set to expire in 1 hr 0 min [Pipeline] { [Pipeline] sh 12:06:32 + make -e test 12:06:32 docker build -t "dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1" . 12:06:32 Sending build context to Docker daemon 6.576MB 12:06:32 Step 1/14 : FROM golang:1.12.7 12:06:32 ---> be63d15101cb 12:06:32 Step 2/14 : RUN apt-get update && apt-get install -y iptables build-essential --no-install-recommends && apt-get install -y python-pip && pip install virtualenv 12:06:32 ---> Using cache 12:06:32 ---> ac32a6af0bc1 12:06:32 Step 3/14 : RUN GO111MODULE=off go get github.com/aktau/github-release && GO111MODULE=off go get golang.org/x/tools/cmd/cover && GO111MODULE=off go get golang.org/x/lint/golint 12:06:32 ---> Using cache 12:06:32 ---> 0197d7e822eb 12:06:32 Step 4/14 : ENV DOCKER_VERSIONS 1.9.1 1.10.3 1.13.1 17.03.2 17.06.0 12:06:32 ---> Using cache 12:06:32 ---> 0c5c284b6af9 12:06:32 Step 5/14 : ENV DEFAULT_DOCKER_VERSION 17.03.2 12:06:32 ---> Using cache 12:06:32 ---> 0d94c7d587b0 12:06:32 Step 6/14 : RUN set -e; set -x; for v in $(echo ${DOCKER_VERSIONS} | cut -f1); do if test "${v}" = "1.9.1" || test "${v}" = "1.10.3"; then mkdir -p /usr/local/bin/docker-${v}/; curl https://get.docker.com/builds/Linux/x86_64/docker-${v} -o /usr/local/bin/docker-${v}/docker; chmod +x /usr/local/bin/docker-${v}/docker; elif test "${v}" = "1.13.1"; then curl https://get.docker.com/builds/Linux/x86_64/docker-${v}.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; else curl https://download.docker.com/linux/static/stable/x86_64/docker-${v}-ce.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; fi done 12:06:32 ---> Using cache 12:06:32 ---> 5de95b94ca80 12:06:32 Step 7/14 : RUN ln -s /usr/local/bin/docker-${DEFAULT_DOCKER_VERSION} /usr/local/bin/docker 12:06:32 ---> Using cache 12:06:32 ---> f3720aca4edd 12:06:32 Step 8/14 : WORKDIR /go/src/github.com/docker/libcompose 12:06:32 ---> Using cache 12:06:32 ---> cc78b7416318 12:06:32 Step 9/14 : ENV COMPOSE_COMMIT 1.9.0 12:06:32 ---> Using cache 12:06:32 ---> 5a43dd4377d3 12:06:32 Step 10/14 : RUN virtualenv venv && git clone https://github.com/docker/compose.git venv/compose && cd venv/compose && git checkout -q "$COMPOSE_COMMIT" && ../bin/pip install -r requirements.txt -r requirements-dev.txt 12:06:32 ---> Using cache 12:06:32 ---> 2e25effa23dd 12:06:32 Step 11/14 : ENV COMPOSE_BINARY /go/src/github.com/docker/libcompose/libcompose-cli 12:06:32 ---> Using cache 12:06:32 ---> 74a69ff8454c 12:06:32 Step 12/14 : ENV USER root 12:06:32 ---> Using cache 12:06:32 ---> aae04cb7e5c1 12:06:32 Step 13/14 : ENTRYPOINT ["hack/dind"] 12:06:32 ---> Using cache 12:06:32 ---> 442fdfff171c 12:06:32 Step 14/14 : COPY . /go/src/github.com/docker/libcompose 12:06:32 ---> Using cache 12:06:32 ---> a855da89c5b5 12:06:32 Successfully built a855da89c5b5 12:06:32 Successfully tagged dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 12:06:32 docker run --rm -i --privileged -e DAEMON_VERSION="all" -e OS_PLATFORM_ARG -e OS_ARCH_ARG -e DOCKER_TEST_HOST -e TESTDIRS -e TESTFLAGS -e SHOWWARNING -e TESTVERBOSE -v "/home/ubuntu/workspace/libcompose_master/bundles:/go/src/github.com/docker/libcompose/bundles" "dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1" ./hack/make.sh binary test-unit test-integration test-acceptance 12:06:32 ---> Making bundle: binary (in .) 12:06:32 go: finding github.com/docker/libtrust v0.0.0-20160708172513-aabc10ec26b7 12:06:32 go: finding github.com/modern-go/reflect2 v1.0.1 12:06:32 go: finding github.com/docker/go-connections v0.3.0 12:06:32 go: finding github.com/containerd/continuity v0.0.0-20190426062206-aaeac12a7ffc 12:06:32 go: finding github.com/Microsoft/go-winio v0.3.8 12:06:32 go: finding github.com/docker/engine v0.0.0-20190725163905-fa8dd90ceb7b 12:06:32 go: finding github.com/Microsoft/hcsshim v0.8.6 12:06:32 go: finding github.com/docker/go-metrics v0.0.0-20181218153428-b84716841b82 12:06:32 go: finding github.com/Azure/go-ansiterm v0.0.0-20170929234023-d6e3b3328b78 12:06:33 go: finding golang.org/x/net v0.0.0-20190613194153-d28f0bde5980 12:06:33 go: finding github.com/pkg/errors v0.8.0 12:06:33 go: finding github.com/docker/cli v0.0.0-20190711175710-5b38d82aa076 12:06:33 go: finding github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f 12:06:33 go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd 12:06:33 go: finding github.com/docker/distribution v2.7.1+incompatible 12:06:33 go: finding github.com/docker/go-units v0.4.0 12:06:34 go: finding github.com/opencontainers/runc v0.0.0-20161109192122-51371867a01c 12:06:34 go: finding golang.org/x/time v0.0.0-20190308202827-9d24e82272b4 12:06:34 go: finding github.com/flynn/go-shlex v0.0.0-20150515145356-3f9db97f8568 12:06:34 go: finding github.com/opencontainers/go-digest v0.0.0-20170106003457-a6d0ee40d420 12:06:34 go: finding github.com/kr/pty v0.0.0-20150511174710-5cf931ef8f76 12:06:34 go: finding github.com/urfave/cli v1.21.0 12:06:35 go: finding github.com/xeipuuv/gojsonschema v1.1.0 12:06:35 go: finding github.com/gogo/protobuf v1.1.1 12:06:35 go: finding github.com/sirupsen/logrus v1.2.0 12:06:35 go: finding github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415 12:06:35 go: finding golang.org/x/crypto v0.0.0-20190308221718-c2843e01d9a2 12:06:35 go: finding github.com/docker/docker-credential-helpers v0.6.3 12:06:36 go: finding github.com/prometheus/client_golang v1.1.0 12:06:36 go: finding github.com/gorilla/mux v0.0.0-20160317213430-0eeaf8392f5b 12:06:36 go: finding golang.org/x/crypto v0.0.0-20180904163835-0709b304e793 12:06:36 go: finding golang.org/x/sys v0.0.0-20180905080454-ebe1bf3edb33 12:06:37 go: finding github.com/morikuni/aec v0.0.0-20170113033406-39771216ff4c 12:06:37 go: finding github.com/prometheus/common v0.6.0 12:06:37 go: finding github.com/stretchr/testify v1.2.2 12:06:37 go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 12:06:37 go: finding gotest.tools v2.2.0+incompatible 12:06:38 go: finding github.com/davecgh/go-spew v1.1.1 12:06:38 go: finding github.com/beorn7/perks v1.0.1 12:06:38 go: finding github.com/stretchr/testify v1.3.0 12:06:38 go: finding github.com/opencontainers/image-spec v0.0.0-20170515205857-f03dbe35d449 12:06:39 go: finding github.com/go-logfmt/logfmt v0.4.0 12:06:39 go: finding golang.org/x/sys v0.0.0-20190215142949-d0b11bdaac8a 12:06:39 go: finding github.com/BurntSushi/toml v0.3.1 12:06:39 go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 12:06:39 go: finding golang.org/x/sys v0.0.0-20190801041406-cbf593c0f2f3 12:06:40 go: finding google.golang.org/grpc v1.22.1 12:06:40 go: finding gopkg.in/yaml.v2 v2.2.2 12:06:40 go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 12:06:40 go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 12:06:40 go: finding github.com/golang/protobuf v1.3.1 12:06:40 go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 12:06:40 go: finding github.com/golang/protobuf v1.2.0 12:06:40 go: finding gopkg.in/yaml.v2 v2.2.1 12:06:41 go: finding golang.org/x/net v0.0.0-20190311183353-d8887717615a 12:06:41 go: finding github.com/pmezard/go-difflib v1.0.0 12:06:42 go: finding github.com/davecgh/go-spew v1.1.0 12:06:42 go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b 12:06:42 go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 12:06:42 go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 12:06:42 go: finding golang.org/x/text v0.3.0 12:06:42 go: finding github.com/golang/protobuf v1.3.2 12:06:42 go: finding google.golang.org/appengine v1.1.0 12:06:42 go: finding github.com/gorilla/context v1.1.1 12:06:42 go: finding github.com/prometheus/procfs v0.0.3 12:06:42 go: finding github.com/go-kit/kit v0.8.0 12:06:43 go: finding github.com/stretchr/objx v0.1.1 12:06:43 go: finding github.com/client9/misspell v0.3.4 12:06:43 go: finding github.com/google/go-cmp v0.2.0 12:06:43 go: finding github.com/json-iterator/go v1.1.7 12:06:43 go: finding github.com/julienschmidt/httprouter v1.2.0 12:06:43 go: finding cloud.google.com/go v0.26.0 12:06:43 go: finding github.com/stretchr/objx v0.1.0 12:06:44 go: finding golang.org/x/sync v0.0.0-20181221193216-37e7f081c4d4 12:06:44 go: finding golang.org/x/sync v0.0.0-20181108010431-42b317875d0f 12:06:44 go: finding github.com/golang/mock v1.1.1 12:06:44 go: finding google.golang.org/genproto v0.0.0-20180817151627-c66870c02cf8 12:06:44 go: finding github.com/prometheus/client_golang v1.0.0 12:06:44 go: finding golang.org/x/lint v0.0.0-20190313153728-d0100b6bd8b3 12:06:44 go: finding github.com/modern-go/concurrent v0.0.0-20180228061459-e0a39a4cb421 12:06:44 go: finding github.com/google/go-cmp v0.3.0 12:06:44 go: finding github.com/google/gofuzz v1.0.0 12:06:44 go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 12:06:45 go: finding github.com/beorn7/perks v1.0.0 12:06:45 go: finding golang.org/x/tools v0.0.0-20190524140312-2c0ae7006135 12:06:45 go: finding github.com/prometheus/common v0.4.1 12:06:45 go: finding golang.org/x/sys v0.0.0-20181116152217-5ac8a444bdc5 12:06:45 go: finding github.com/prometheus/procfs v0.0.2 12:06:45 go: finding github.com/json-iterator/go v1.1.6 12:06:45 go: finding honnef.co/go/tools v0.0.0-20190523083050-ea95bdfd59fc 12:06:45 go: finding golang.org/x/oauth2 v0.0.0-20180821212333-d2e6202438be 12:06:45 go: finding github.com/prometheus/client_golang v0.9.1 12:06:45 go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 12:06:45 go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc 12:06:45 go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf 12:06:45 go: finding github.com/prometheus/client_model v0.0.0-20180712105110-5c3871d89910 12:06:45 go: finding github.com/prometheus/procfs v0.0.0-20181005140218-185b4288413d 12:06:45 go: finding github.com/go-stack/stack v1.8.0 12:06:46 go: finding golang.org/x/net v0.0.0-20181114220301-adae6a3d119a 12:06:46 go: finding github.com/go-logfmt/logfmt v0.3.0 12:06:46 go: finding golang.org/x/tools v0.0.0-20190311212946-11955173bddd 12:06:54 go: finding golang.org/x/sync v0.0.0-20190423024810-112230192c58 12:06:56 go: downloading golang.org/x/net v0.0.0-20190613194153-d28f0bde5980 12:06:56 go: downloading github.com/docker/go-units v0.4.0 12:06:56 go: downloading github.com/docker/engine v0.0.0-20190725163905-fa8dd90ceb7b 12:06:56 go: downloading github.com/docker/cli v0.0.0-20190711175710-5b38d82aa076 12:06:56 go: downloading github.com/sirupsen/logrus v1.2.0 12:06:56 go: downloading github.com/docker/go-connections v0.3.0 12:06:56 go: extracting github.com/sirupsen/logrus v1.2.0 12:06:56 go: downloading gopkg.in/yaml.v2 v2.2.2 12:06:56 go: extracting github.com/docker/go-units v0.4.0 12:06:56 go: extracting github.com/docker/go-connections v0.3.0 12:06:57 go: extracting gopkg.in/yaml.v2 v2.2.2 12:06:57 go: downloading github.com/urfave/cli v1.21.0 12:06:57 go: extracting github.com/urfave/cli v1.21.0 12:06:58 go: extracting golang.org/x/net v0.0.0-20190613194153-d28f0bde5980 12:06:59 go: extracting github.com/docker/cli v0.0.0-20190711175710-5b38d82aa076 12:06:59 go: downloading github.com/pkg/errors v0.8.0 12:06:59 go: extracting github.com/pkg/errors v0.8.0 12:07:00 go: extracting github.com/docker/engine v0.0.0-20190725163905-fa8dd90ceb7b 12:07:00 go: downloading github.com/opencontainers/runc v0.0.0-20161109192122-51371867a01c 12:07:00 go: downloading github.com/docker/distribution v2.7.1+incompatible 12:07:00 go: downloading github.com/flynn/go-shlex v0.0.0-20150515145356-3f9db97f8568 12:07:00 go: downloading github.com/xeipuuv/gojsonschema v1.1.0 12:07:00 go: downloading github.com/opencontainers/go-digest v0.0.0-20170106003457-a6d0ee40d420 12:07:00 go: downloading github.com/docker/docker-credential-helpers v0.6.3 12:07:00 go: downloading github.com/gogo/protobuf v1.1.1 12:07:00 go: extracting github.com/docker/docker-credential-helpers v0.6.3 12:07:00 go: downloading golang.org/x/sys v0.0.0-20190801041406-cbf593c0f2f3 12:07:00 go: downloading github.com/morikuni/aec v0.0.0-20170113033406-39771216ff4c 12:07:00 go: downloading golang.org/x/crypto v0.0.0-20190308221718-c2843e01d9a2 12:07:00 go: downloading google.golang.org/grpc v1.22.1 12:07:00 go: extracting github.com/flynn/go-shlex v0.0.0-20150515145356-3f9db97f8568 12:07:00 go: extracting github.com/opencontainers/go-digest v0.0.0-20170106003457-a6d0ee40d420 12:07:00 go: extracting github.com/morikuni/aec v0.0.0-20170113033406-39771216ff4c 12:07:00 go: extracting github.com/xeipuuv/gojsonschema v1.1.0 12:07:01 go: extracting github.com/opencontainers/runc v0.0.0-20161109192122-51371867a01c 12:07:01 go: downloading github.com/opencontainers/image-spec v0.0.0-20170515205857-f03dbe35d449 12:07:01 go: extracting github.com/opencontainers/image-spec v0.0.0-20170515205857-f03dbe35d449 12:07:02 go: extracting github.com/docker/distribution v2.7.1+incompatible 12:07:02 go: extracting golang.org/x/crypto v0.0.0-20190308221718-c2843e01d9a2 12:07:02 go: downloading golang.org/x/time v0.0.0-20190308202827-9d24e82272b4 12:07:02 go: downloading github.com/containerd/continuity v0.0.0-20190426062206-aaeac12a7ffc 12:07:02 go: downloading github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415 12:07:02 go: downloading github.com/gorilla/mux v0.0.0-20160317213430-0eeaf8392f5b 12:07:02 go: extracting golang.org/x/time v0.0.0-20190308202827-9d24e82272b4 12:07:02 go: downloading github.com/docker/go-metrics v0.0.0-20181218153428-b84716841b82 12:07:02 go: extracting github.com/docker/go-metrics v0.0.0-20181218153428-b84716841b82 12:07:02 go: downloading github.com/prometheus/client_golang v1.1.0 12:07:02 go: extracting github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415 12:07:02 go: downloading github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f 12:07:02 go: extracting github.com/gorilla/mux v0.0.0-20160317213430-0eeaf8392f5b 12:07:02 go: downloading github.com/gorilla/context v1.1.1 12:07:02 go: extracting google.golang.org/grpc v1.22.1 12:07:02 go: extracting github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f 12:07:02 go: extracting github.com/gorilla/context v1.1.1 12:07:02 go: downloading github.com/golang/protobuf v1.3.2 12:07:02 go: extracting github.com/prometheus/client_golang v1.1.0 12:07:02 go: downloading github.com/prometheus/common v0.6.0 12:07:02 go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 12:07:02 go: downloading github.com/prometheus/procfs v0.0.3 12:07:03 go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 12:07:03 go: downloading google.golang.org/genproto v0.0.0-20180817151627-c66870c02cf8 12:07:03 go: extracting github.com/prometheus/procfs v0.0.3 12:07:03 go: downloading github.com/beorn7/perks v1.0.1 12:07:03 go: extracting github.com/prometheus/common v0.6.0 12:07:03 go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 12:07:03 go: extracting github.com/beorn7/perks v1.0.1 12:07:03 go: extracting github.com/containerd/continuity v0.0.0-20190426062206-aaeac12a7ffc 12:07:03 go: downloading golang.org/x/sync v0.0.0-20190423024810-112230192c58 12:07:03 go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 12:07:03 go: extracting golang.org/x/sync v0.0.0-20190423024810-112230192c58 12:07:03 go: extracting golang.org/x/sys v0.0.0-20190801041406-cbf593c0f2f3 12:07:03 go: extracting github.com/golang/protobuf v1.3.2 12:07:04 go: extracting github.com/gogo/protobuf v1.1.1 12:07:04 go: extracting google.golang.org/genproto v0.0.0-20180817151627-c66870c02cf8 12:07:19 12:07:19 ---> Making bundle: test-unit (in .) 12:07:19 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/cli/docker/app 12:07:41 ok github.com/docker/libcompose/cli/docker/app 1.122s coverage: 88.2% of statements 12:07:41 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/config 12:07:41 go: downloading github.com/stretchr/testify v1.3.0 12:07:41 go: extracting github.com/stretchr/testify v1.3.0 12:07:41 go: downloading github.com/pmezard/go-difflib v1.0.0 12:07:41 go: downloading github.com/davecgh/go-spew v1.1.1 12:07:41 go: extracting github.com/pmezard/go-difflib v1.0.0 12:07:41 go: extracting github.com/davecgh/go-spew v1.1.1 12:07:43 ok github.com/docker/libcompose/config 1.533s coverage: 66.0% of statements 12:07:43 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/docker/builder 12:07:46 ok github.com/docker/libcompose/docker/builder 1.036s coverage: 89.0% of statements 12:07:46 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/docker/client 12:07:49 ok github.com/docker/libcompose/docker/client 1.244s coverage: 100.0% of statements 12:07:49 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/docker/network 12:07:50 ok github.com/docker/libcompose/docker/network 1.027s coverage: 90.9% of statements 12:07:50 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/docker/service 12:07:54 ok github.com/docker/libcompose/docker/service 1.035s coverage: 15.9% of statements 12:07:54 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/docker/volume 12:07:56 ok github.com/docker/libcompose/docker/volume 1.025s coverage: 23.5% of statements 12:07:56 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/labels 12:07:58 ok github.com/docker/libcompose/labels 1.007s coverage: 16.0% of statements 12:07:58 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/lookup 12:08:00 ok github.com/docker/libcompose/lookup 1.016s coverage: 91.9% of statements 12:08:00 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/project 12:08:03 ok github.com/docker/libcompose/project 1.070s coverage: 31.9% of statements 12:08:03 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/project/events 12:08:04 ok github.com/docker/libcompose/project/events 1.006s coverage: 10.2% of statements 12:08:04 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/project/options 12:08:05 ok github.com/docker/libcompose/project/options 1.006s coverage: 100.0% of statements 12:08:05 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/utils 12:08:07 ok github.com/docker/libcompose/utils 1.011s coverage: 41.3% of statements 12:08:07 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/version 12:08:08 ok github.com/docker/libcompose/version 1.009s coverage: 0.0% of statements [no tests to run] 12:08:08 + go test -race -cover -coverprofile=cover.out github.com/docker/libcompose/yaml 12:08:10 ok github.com/docker/libcompose/yaml 1.023s coverage: 72.1% of statements 12:08:10 12:08:10 Run non-race test (if any) 12:08:10 + go test -cover -coverprofile=cover.out github.com/docker/libcompose/utils 12:08:17 ok github.com/docker/libcompose/utils 0.004s coverage: 56.0% of statements 12:08:17 12:08:17 Test success 12:08:17 12:08:17 12:08:17 ---> Making bundle: test-integration (in .) 12:08:17 Running integration test against 1.9.1 12:08:17 ---> Making bundle: .integration-daemon-start (in .) 12:08:17 +++ exec docker daemon --debug --host unix:///go/src/github.com/docker/libcompose/docker.sock --storage-driver vfs --pidfile ./docker.pid --userland-proxy=true --graph=/var/lib/docker/1.9.1 12:08:18 Client: 12:08:18 Version: 1.9.1 12:08:18 API version: 1.21 12:08:18 Go version: go1.4.3 12:08:18 Git commit: a34a1d5 12:08:18 Built: Fri Nov 20 17:56:04 UTC 2015 12:08:18 OS/Arch: linux/amd64 12:08:18 12:08:18 Server: 12:08:18 Version: 1.9.1 12:08:18 API version: 1.21 12:08:18 Go version: go1.4.3 12:08:18 Git commit: a34a1d5 12:08:18 Built: Fri Nov 20 17:56:04 UTC 2015 12:08:18 OS/Arch: linux/amd64 12:08:18 go: downloading gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 12:08:18 go: downloading github.com/kr/pty v0.0.0-20150511174710-5cf931ef8f76 12:08:18 go: extracting github.com/kr/pty v0.0.0-20150511174710-5cf931ef8f76 12:08:19 go: extracting gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 12:08:25 SKIP: api_event_test.go:16: APISuite.TestEvents (Not(Test requires the daemon version to be 1.9)) 12:08:25 time="2021-06-16T12:08:25Z" level=info msg="[0/1] [service]: Starting " 12:08:25 Pulling service (busybox)... 12:08:25 latest: Pulling from library/busybox 12:08:25 0f0fa77a4c1e: Pulling fs layer 12:08:25 4e762df69681: Pulling fs layer 12:08:25 0f0fa77a4c1e: Verifying Checksum 12:08:25 0f0fa77a4c1e: Download complete 12:08:25 4e762df69681: Verifying Checksum 12:08:25 4e762df69681: Download complete 12:08:25 0f0fa77a4c1e: Pull complete 12:08:25 4e762df69681: Pull complete 12:08:25 Digest: sha256:3601b6c6b893707baa92046ce91e643574a7fb9c8fa04536aaa0e8aaf7cc501e 12:08:25 Status: Downloaded newer image for busybox:latest 12:08:26 time="2021-06-16T12:08:25Z" level=info msg="[1/1] [service]: Started " 12:08:26 PASS: api_test.go:20: APISuite.TestVolumeWithoutComposeFile 0.677s 12:08:26 PASS: build_test.go:13: CliSuite.TestBuild 0.525s 12:08:27 PASS: build_test.go:85: CliSuite.TestBuildWithArgs 0.661s 12:08:27 PASS: build_test.go:33: CliSuite.TestBuildWithNoCache1 0.144s 12:08:28 PASS: build_test.go:49: CliSuite.TestBuildWithNoCache2 0.568s 12:08:28 PASS: build_test.go:65: CliSuite.TestBuildWithNoCache3 0.512s 12:08:28 time="2021-06-16T12:08:28Z" level=info msg="Running create [--verbose -p testprojectlckymsr -f - create]" 12:08:28 PASS: create_test.go:58: CliSuite.TestContainerName 0.129s 12:08:28 time="2021-06-16T12:08:28Z" level=info msg="Running create [--verbose -p testprojectpfhufhk -f - create]" 12:08:28 time="2021-06-16T12:08:28Z" level=info msg="Running scale [--verbose -p testprojectpfhufhk -f - scale hello=2]" 12:08:28 PASS: create_test.go:72: CliSuite.TestContainerNameWithScale 0.135s 12:08:29 PASS: env_test.go:12: CliSuite.TestCreateWithEnvInCurrentDir 0.467s 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="Running create [--verbose -p testprojectqjbfolz -f - create]" 12:08:29 PASS: env_test.go:38: CliSuite.TestCreateWithEnvNotInCurrentDir 0.080s 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="[0/3] [yetanother]: Creating " 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="[0/3] [another]: Creating " 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="[0/3] [simple]: Creating " 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="[0/3] [yetanother]: Created " 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="[0/3] [simple]: Created " 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="[0/3] [another]: Created " 12:08:29 PASS: create_test.go:241: CliSuite.TestDefaultMultipleComposeFiles 0.346s 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="Running up [--verbose -p testprojectnzwrnwo -f - up -d]" 12:08:29 time="2021-06-16T12:08:29Z" level=info msg="Running stop [--verbose -p testprojectnzwrnwo -f - stop --timeout 0]" 12:08:42 time="2021-06-16T12:08:39Z" level=info msg="Running rm [--verbose -p testprojectnzwrnwo -f - rm --force]" 12:08:42 PASS: rm_test.go:9: CliSuite.TestDelete 10.370s 12:08:42 time="2021-06-16T12:08:39Z" level=info msg="Running up [--verbose -p testprojectqbrzjwq -f - up -d]" 12:08:42 time="2021-06-16T12:08:40Z" level=info msg="Running stop [--verbose -p testprojectqbrzjwq -f - stop bye --timeout 0]" 12:08:52 time="2021-06-16T12:08:50Z" level=info msg="Running rm [--verbose -p testprojectqbrzjwq -f - rm --force]" 12:08:52 PASS: rm_test.go:25: CliSuite.TestDeleteOnlyRemovesStopped 10.537s 12:08:52 time="2021-06-16T12:08:50Z" level=info msg="Running up [--verbose -p testprojectcurehqp -f - up -d]" 12:08:52 time="2021-06-16T12:08:50Z" level=info msg="Running stop [--verbose -p testprojectcurehqp -f - stop --timeout 0]" 12:09:01 time="2021-06-16T12:09:00Z" level=info msg="Running rm [--verbose -p testprojectcurehqp -f - rm --force -v]" 12:09:01 PASS: rm_test.go:65: CliSuite.TestDeleteWithVol 10.367s 12:09:01 time="2021-06-16T12:09:01Z" level=info msg="Running up [--verbose -p testprojectlqnyomd -f - up -d]" 12:09:01 time="2021-06-16T12:09:01Z" level=info msg="Running down [--verbose -p testprojectlqnyomd -f - down]" 12:09:11 PASS: down_test.go:9: CliSuite.TestDown 10.346s 12:09:11 time="2021-06-16T12:09:11Z" level=info msg="Running up [--verbose -p testprojectavemewr -f - up -d]" 12:09:11 time="2021-06-16T12:09:11Z" level=info msg="Running scale [--verbose -p testprojectavemewr -f - scale hello=2]" 12:09:11 time="2021-06-16T12:09:11Z" level=info msg="Running down [--verbose -p testprojectavemewr -f - down]" 12:09:24 PASS: down_test.go:24: CliSuite.TestDownMultiple 10.609s 12:09:24 time="2021-06-16T12:09:21Z" level=info msg="Running create [--verbose -p testprojectkqbwvfg -f - create]" 12:09:24 PASS: create_test.go:31: CliSuite.TestEmptyEntrypoint 0.125s 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojectdpghsgn -f - create]" 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojectqgsduby -f - create]" 12:09:24 PASS: create_test.go:146: CliSuite.TestFieldTypeConversions 0.160s 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojectfwkdnrd -f - create]" 12:09:24 PASS: create_test.go:14: CliSuite.TestFields 0.077s 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojectadskbfa -f - create]" 12:09:24 PASS: create_test.go:45: CliSuite.TestHelloWorld 0.080s 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojectwtqyxti -f - create]" 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojecthngexow -f - create]" 12:09:24 PASS: create_test.go:86: CliSuite.TestInterpolation 0.156s 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojecthisbbpj -f - create]" 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running create [--verbose -p testprojectphzmfao -f - create]" 12:09:24 PASS: create_test.go:112: CliSuite.TestInterpolationWithExtends 0.160s 12:09:24 time="2021-06-16T12:09:22Z" level=info msg="Running up [--verbose -p testprojecttwjgfrv -f - up -d]" 12:09:24 time="2021-06-16T12:09:23Z" level=info msg="Running kill [--verbose -p testprojecttwjgfrv -f - kill]" 12:09:24 PASS: kill_test.go:9: CliSuite.TestKill 0.341s 12:09:24 time="2021-06-16T12:09:23Z" level=info msg="Running up [--verbose -p testprojectvfhfayl -f - up -d]" 12:09:24 PASS: up_test.go:260: CliSuite.TestLink 0.416s 12:09:24 time="2021-06-16T12:09:23Z" level=info msg="[0/4] [another]: Creating " 12:09:24 time="2021-06-16T12:09:23Z" level=info msg="[0/4] [simple]: Creating " 12:09:24 time="2021-06-16T12:09:23Z" level=info msg="[0/4] [multiple]: Creating " 12:09:24 time="2021-06-16T12:09:23Z" level=info msg="[0/4] [yetanother]: Creating " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [simple]: Created " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [multiple]: Created " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [another]: Created " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [yetanother]: Created " 12:09:24 PASS: create_test.go:176: CliSuite.TestMultipleComposeFilesOneTwo 0.399s 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [simple]: Creating " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [yetanother]: Creating " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [another]: Creating " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [multiple]: Creating " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [multiple]: Created " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [simple]: Created " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [yetanother]: Created " 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="[0/4] [another]: Created " 12:09:24 PASS: create_test.go:204: CliSuite.TestMultipleComposeFilesTwoOne 0.357s 12:09:24 time="2021-06-16T12:09:24Z" level=info msg="Running up [--verbose -p testprojectwmmuyte -f - up -d]" 12:09:25 PASS: volume_test.go:62: CliSuite.TestNamedVolume 0.207s 12:09:25 time="2021-06-16T12:09:25Z" level=info msg="Running up [--verbose -p testprojectiafiaaz -f - up -d]" 12:09:25 time="2021-06-16T12:09:25Z" level=info msg="Running pause [--verbose -p testprojectiafiaaz -f - pause]" 12:09:25 PASS: pause_unpause_test.go:9: CliSuite.TestPause 0.225s 12:09:25 time="2021-06-16T12:09:25Z" level=info msg="Running up [--verbose -p testprojectkxlyqir -f - up -d]" 12:09:25 time="2021-06-16T12:09:25Z" level=info msg="Running pause [--verbose -p testprojectkxlyqir -f - pause]" 12:09:25 time="2021-06-16T12:09:25Z" level=info msg="Running pause [--verbose -p testprojectkxlyqir -f - pause]" 12:09:25 PASS: pause_unpause_test.go:26: CliSuite.TestPauseAlreadyPausedService 0.229s 12:09:25 time="2021-06-16T12:09:25Z" level=info msg="Running up [--verbose -p testprojectfysxmee -f - up -d]" 12:09:26 time="2021-06-16T12:09:26Z" level=info msg="Running ps [--verbose -p testprojectfysxmee -f - ps]" 12:09:26 PASS: ps_test.go:10: CliSuite.TestPs 0.244s 12:09:26 time="2021-06-16T12:09:26Z" level=info msg="Running up [--verbose -p testprojectsmxvehq -f - up -d]" 12:09:26 time="2021-06-16T12:09:26Z" level=info msg="Running ps [--verbose -p testprojectsmxvehq -f - ps -q]" 12:09:26 PASS: ps_test.go:22: CliSuite.TestPsQuiet 0.226s 12:09:26 time="2021-06-16T12:09:26Z" level=info msg="Running pull [--verbose -p testprojectaxqhnrq -f - pull]" 12:09:26 PASS: pull_test.go:7: CliSuite.TestPull 0.162s 12:09:26 time="2021-06-16T12:09:26Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up -d]" 12:09:27 time="2021-06-16T12:09:26Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up -d]" 12:09:27 time="2021-06-16T12:09:26Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up -d]" 12:09:27 time="2021-06-16T12:09:27Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up -d]" 12:09:27 time="2021-06-16T12:09:27Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up -d]" 12:09:27 time="2021-06-16T12:09:27Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up --force-recreate -d]" 12:09:28 time="2021-06-16T12:09:27Z" level=info msg="Running up [--verbose -p testprojectokiqbim -f - up --force-recreate -d]" 12:09:28 PASS: up_test.go:105: CliSuite.TestRecreate 1.626s 12:09:28 time="2021-06-16T12:09:28Z" level=info msg="Running up [--verbose -p testprojectarrqgdk -f - up -d]" 12:09:28 time="2021-06-16T12:09:28Z" level=info msg="Running up [--verbose -p testprojectarrqgdk -f - up --force-recreate -d]" 12:09:29 PASS: up_test.go:33: CliSuite.TestRecreateForceRecreate 0.553s 12:09:29 time="2021-06-16T12:09:29Z" level=info msg="Running up [--verbose -p testprojectlsjhitm -f - up -d]" 12:09:30 time="2021-06-16T12:09:29Z" level=info msg="Running up [--verbose -p testprojectlsjhitm -f - up --no-recreate -d]" 12:09:30 time="2021-06-16T12:09:29Z" level=info msg="Running up [--verbose -p testprojectlsjhitm -f - up --no-recreate -d]" 12:09:30 time="2021-06-16T12:09:29Z" level=info msg="Running up [--verbose -p testprojectlsjhitm -f - up -d]" 12:09:30 time="2021-06-16T12:09:30Z" level=info msg="Running rm [--verbose -p testprojectlsjhitm -f - rm -f]" 12:09:30 PASS: up_test.go:207: CliSuite.TestRecreateImageChanging 1.156s 12:09:30 time="2021-06-16T12:09:30Z" level=info msg="Running up [--verbose -p testprojectqyqtcft -f - up -d]" 12:09:30 time="2021-06-16T12:09:30Z" level=info msg="Running up [--verbose -p testprojectqyqtcft -f - up --no-recreate -d]" 12:09:30 PASS: up_test.go:84: CliSuite.TestRecreateNoRecreate 0.215s 12:09:30 time="2021-06-16T12:09:30Z" level=info msg="Running up [--verbose -p testprojectghbvtga -f - up -d]" 12:09:31 time="2021-06-16T12:09:30Z" level=info msg="Running up [--verbose -p testprojectghbvtga -f - up --force-recreate -d]" 12:09:31 PASS: up_test.go:53: CliSuite.TestRecreateVols 0.573s 12:09:31 time="2021-06-16T12:09:31Z" level=info msg="Running up [--verbose -p testprojectyqafobl -f - up -d]" 12:09:32 PASS: volume_test.go:43: CliSuite.TestRelativeVolume 0.228s 12:09:32 time="2021-06-16T12:09:31Z" level=info msg="Running up [--verbose -p testprojectkvxgjwr -f - up -d]" 12:09:32 time="2021-06-16T12:09:32Z" level=info msg="Running restart [--verbose -p testprojectkvxgjwr -f - restart --timeout 0]" 12:09:44 PASS: restart_test.go:9: CliSuite.TestRestart 10.429s 12:09:44 PASS: run_test.go:19: CliSuite.TestRun 0.342s 12:09:44 time="2021-06-16T12:09:42Z" level=info msg="Running up [--verbose -p testprojectzwgmlbt -f - up -d]" 12:09:44 time="2021-06-16T12:09:42Z" level=info msg="Running scale [--verbose -p testprojectzwgmlbt -f - scale hello=2]" 12:09:44 time="2021-06-16T12:09:43Z" level=info msg="Running scale [--verbose -p testprojectzwgmlbt -f - scale --timeout 0 hello=1]" 12:09:54 PASS: scale_test.go:10: CliSuite.TestScale 10.586s 12:09:54 time="2021-06-16T12:09:53Z" level=info msg="Running up [--verbose -p testprojectjkkmrpo -f - up -d]" 12:09:54 time="2021-06-16T12:09:53Z" level=info msg="Running scale [--verbose -p testprojectjkkmrpo -f - scale test=2]" 12:09:54 PASS: scale_test.go:46: CliSuite.TestScaleWithHostPortWarning 0.578s 12:09:54 time="2021-06-16T12:09:54Z" level=info msg="Running create [--verbose -p testprojectwjlowuh -f - create]" 12:09:54 time="2021-06-16T12:09:54Z" level=info msg="Running start [--verbose -p testprojectwjlowuh -f - start]" 12:09:54 PASS: start_test.go:9: CliSuite.TestStart 0.257s 12:09:54 time="2021-06-16T12:09:54Z" level=info msg="Running up [--verbose -p testprojectancpwbn -f - up -d]" 12:09:54 time="2021-06-16T12:09:54Z" level=info msg="Running stop [--verbose -p testprojectancpwbn -f - stop --timeout 0]" 12:10:07 PASS: stop_test.go:9: CliSuite.TestStop 10.339s 12:10:07 time="2021-06-16T12:10:04Z" level=info msg="Running up [--verbose -p testprojectgnnahsp -f - up -d]" 12:10:07 time="2021-06-16T12:10:05Z" level=info msg="Running pause [--verbose -p testprojectgnnahsp -f - pause]" 12:10:07 time="2021-06-16T12:10:05Z" level=info msg="Running unpause [--verbose -p testprojectgnnahsp -f - unpause]" 12:10:07 PASS: pause_unpause_test.go:51: CliSuite.TestUnpause 0.251s 12:10:07 time="2021-06-16T12:10:05Z" level=info msg="Running up [--verbose -p testprojectmgiyere -f - up -d]" 12:10:07 time="2021-06-16T12:10:05Z" level=info msg="Running unpause [--verbose -p testprojectmgiyere -f - unpause]" 12:10:07 PASS: pause_unpause_test.go:76: CliSuite.TestUnpauseNotPausedService 0.230s 12:10:07 time="2021-06-16T12:10:05Z" level=info msg="Running up [--verbose -p testprojectjrodddr -f - up -d]" 12:10:07 PASS: up_test.go:15: CliSuite.TestUp 0.218s 12:10:07 time="2021-06-16T12:10:06Z" level=info msg="Running up [--verbose -p testprojectrjyyexr -f - up -d]" 12:10:07 time="2021-06-16T12:10:06Z" level=info msg="Running up [--verbose -p testprojectrjyyexr -f - up --no-recreate -d]" 12:10:07 PASS: up_test.go:177: CliSuite.TestUpAfterImageTagDeleted 0.248s 12:10:07 PASS: up_test.go:291: CliSuite.TestUpNoBuildFailIfImageNotPresent 0.011s 12:10:07 PASS: up_test.go:299: CliSuite.TestUpNoBuildShouldWorkIfImageIsPresent 0.381s 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running up [--verbose -p testprojecttjgnise -f - up -d]" 12:10:07 PASS: up_test.go:25: CliSuite.TestUpNotExistService 0.215s 12:10:07 SKIP: volume_test.go:79: CliSuite.TestV2Volume (Not(Test requires the daemon version to be 1.9)) 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectwczhgbu -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectwczhgbu -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectkuyxetp -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectkuyxetp -f - create]\n with input:\n\n test:\n image: busybox\n build: .\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectexipmah -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectexipmah -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:10:07 PASS: create_test.go:262: CliSuite.TestValidation 0.025s 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojecttkndhun -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecttkndhun -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectkjczjqr -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectkjczjqr -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:10:07 PASS: create_test.go:297: CliSuite.TestValidationV2 0.014s 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectnhonmgf -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectnhonmgf -f - create]\n with input:\n\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectlbrmpco -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectlbrmpco -f - create]\n with input:\n\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectqlknvzm -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectqlknvzm -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v1.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectwiwocwe -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectwiwocwe -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v1.yml\n service: base\n\t" 12:10:07 PASS: create_test.go:327: CliSuite.TestValidationWithExtends 0.028s 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojecthpxtmbs -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecthpxtmbs -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectenciszb -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectenciszb -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectbepevno -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectbepevno -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v2.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:10:07 time="2021-06-16T12:10:07Z" level=info msg="Running create [--verbose -p testprojectmsxbqvt -f - create]" 12:10:07 time="2021-06-16T12:10:07Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectmsxbqvt -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v2.yml\n service: base\n\t" 12:10:07 PASS: create_test.go:380: CliSuite.TestValidationWithExtendsV2 0.034s 12:10:07 PASS: volume_test.go:11: CliSuite.TestVolumeFromService 0.245s 12:10:08 PASS: volume_test.go:27: CliSuite.TestVolumeFromServiceWithContainerName 0.237s 12:10:08 OK: 57 passed, 2 skipped 12:10:08 PASS 12:10:08 coverage: 38.9% of statements 12:10:08 ok github.com/docker/libcompose/integration 102.766s 12:10:08 ---> Making bundle: .integration-daemon-stop (in .) 12:10:08 ++++ cat ./docker.pid 12:10:08 +++ kill 6629 12:10:08 12:10:08 Running integration test against 1.10.3 12:10:08 ---> Making bundle: .integration-daemon-start (in .) 12:10:08 +++ exec docker daemon --debug --host unix:///go/src/github.com/docker/libcompose/docker.sock --storage-driver vfs --pidfile ./docker.pid --userland-proxy=true --graph=/var/lib/docker/1.10.3 12:10:08 Client: 12:10:08 Version: 1.10.3 12:10:08 API version: 1.22 12:10:08 Go version: go1.5.3 12:10:08 Git commit: 20f81dd 12:10:08 Built: Thu Mar 10 21:49:11 2016 12:10:08 OS/Arch: linux/amd64 12:10:08 12:10:08 Server: 12:10:08 Version: 1.10.3 12:10:08 API version: 1.22 12:10:08 Go version: go1.5.3 12:10:08 Git commit: 20f81dd 12:10:08 Built: Thu Mar 10 21:49:11 2016 12:10:08 OS/Arch: linux/amd64 12:10:10 time="2021-06-16T12:10:09Z" level=info msg="[0/2] [another]: Starting " 12:10:10 time="2021-06-16T12:10:09Z" level=info msg="[0/2] [simple]: Starting " 12:10:10 Pulling simple (busybox:latest)... 12:10:10 Pulling another (busybox:latest)... 12:10:10 latest: Pulling from library/busybox 12:10:10 latest: Pulling from library/busybox 12:10:10 b71f96345d44: Pulling fs layer 12:10:10 b71f96345d44: Pulling fs layer 12:10:10 b71f96345d44: Verifying Checksum 12:10:10 b71f96345d44: Download complete 12:10:10 b71f96345d44: Verifying Checksum 12:10:10 b71f96345d44: Download complete 12:10:10 b71f96345d44: Pull complete 12:10:10 b71f96345d44: Pull complete 12:10:10 b71f96345d44: Pull complete 12:10:10 b71f96345d44: Pull complete 12:10:10 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:10:10 Status: Downloaded newer image for busybox:latest 12:10:10 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:10:10 Status: Image is up to date for busybox:latest 12:10:10 time="2021-06-16T12:10:10Z" level=info msg="[1/2] [simple]: Started " 12:10:11 time="2021-06-16T12:10:10Z" level=info msg="[2/2] [another]: Started " 12:10:11 PASS: api_event_test.go:16: APISuite.TestEvents 1.095s 12:10:11 time="2021-06-16T12:10:11Z" level=info msg="[0/1] [service]: Starting " 12:10:11 time="2021-06-16T12:10:11Z" level=info msg="[1/1] [service]: Started " 12:10:11 PASS: api_test.go:20: APISuite.TestVolumeWithoutComposeFile 0.256s 12:10:12 PASS: build_test.go:13: CliSuite.TestBuild 0.543s 12:10:13 PASS: build_test.go:85: CliSuite.TestBuildWithArgs 0.772s 12:10:13 PASS: build_test.go:33: CliSuite.TestBuildWithNoCache1 0.162s 12:10:13 PASS: build_test.go:49: CliSuite.TestBuildWithNoCache2 0.618s 12:10:14 PASS: build_test.go:65: CliSuite.TestBuildWithNoCache3 0.536s 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="Running create [--verbose -p testprojectfeqyknw -f - create]" 12:10:14 PASS: create_test.go:58: CliSuite.TestContainerName 0.121s 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="Running create [--verbose -p testprojectbisubnu -f - create]" 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="Running scale [--verbose -p testprojectbisubnu -f - scale hello=2]" 12:10:14 PASS: create_test.go:72: CliSuite.TestContainerNameWithScale 0.139s 12:10:14 PASS: env_test.go:12: CliSuite.TestCreateWithEnvInCurrentDir 0.279s 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="Running create [--verbose -p testprojectmvclqbz -f - create]" 12:10:14 PASS: env_test.go:38: CliSuite.TestCreateWithEnvNotInCurrentDir 0.079s 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="[0/3] [yetanother]: Creating " 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="[0/3] [simple]: Creating " 12:10:14 time="2021-06-16T12:10:14Z" level=info msg="[0/3] [another]: Creating " 12:10:15 time="2021-06-16T12:10:15Z" level=info msg="[0/3] [yetanother]: Created " 12:10:15 time="2021-06-16T12:10:15Z" level=info msg="[0/3] [simple]: Created " 12:10:15 time="2021-06-16T12:10:15Z" level=info msg="[0/3] [another]: Created " 12:10:15 PASS: create_test.go:241: CliSuite.TestDefaultMultipleComposeFiles 0.346s 12:10:15 time="2021-06-16T12:10:15Z" level=info msg="Running up [--verbose -p testprojectdypanfq -f - up -d]" 12:10:15 time="2021-06-16T12:10:15Z" level=info msg="Running stop [--verbose -p testprojectdypanfq -f - stop --timeout 0]" 12:10:27 time="2021-06-16T12:10:25Z" level=info msg="Running rm [--verbose -p testprojectdypanfq -f - rm --force]" 12:10:27 PASS: rm_test.go:9: CliSuite.TestDelete 10.383s 12:10:27 time="2021-06-16T12:10:25Z" level=info msg="Running up [--verbose -p testprojecthwhkqiq -f - up -d]" 12:10:27 time="2021-06-16T12:10:26Z" level=info msg="Running stop [--verbose -p testprojecthwhkqiq -f - stop bye --timeout 0]" 12:10:37 time="2021-06-16T12:10:36Z" level=info msg="Running rm [--verbose -p testprojecthwhkqiq -f - rm --force]" 12:10:37 PASS: rm_test.go:25: CliSuite.TestDeleteOnlyRemovesStopped 10.595s 12:10:37 time="2021-06-16T12:10:36Z" level=info msg="Running up [--verbose -p testprojectlnccaxj -f - up -d]" 12:10:37 time="2021-06-16T12:10:36Z" level=info msg="Running stop [--verbose -p testprojectlnccaxj -f - stop --timeout 0]" 12:10:47 time="2021-06-16T12:10:46Z" level=info msg="Running rm [--verbose -p testprojectlnccaxj -f - rm --force -v]" 12:10:47 PASS: rm_test.go:65: CliSuite.TestDeleteWithVol 10.380s 12:10:47 time="2021-06-16T12:10:46Z" level=info msg="Running up [--verbose -p testprojectwfuxkyb -f - up -d]" 12:10:47 time="2021-06-16T12:10:46Z" level=info msg="Running down [--verbose -p testprojectwfuxkyb -f - down]" 12:10:57 PASS: down_test.go:9: CliSuite.TestDown 10.398s 12:10:57 time="2021-06-16T12:10:57Z" level=info msg="Running up [--verbose -p testprojecthdflybx -f - up -d]" 12:10:57 time="2021-06-16T12:10:57Z" level=info msg="Running scale [--verbose -p testprojecthdflybx -f - scale hello=2]" 12:10:57 time="2021-06-16T12:10:57Z" level=info msg="Running down [--verbose -p testprojecthdflybx -f - down]" 12:11:09 PASS: down_test.go:24: CliSuite.TestDownMultiple 10.667s 12:11:09 time="2021-06-16T12:11:07Z" level=info msg="Running create [--verbose -p testprojectrzylhtv -f - create]" 12:11:09 PASS: create_test.go:31: CliSuite.TestEmptyEntrypoint 0.125s 12:11:09 time="2021-06-16T12:11:07Z" level=info msg="Running create [--verbose -p testprojectyqnnfrj -f - create]" 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectdincsng -f - create]" 12:11:09 PASS: create_test.go:146: CliSuite.TestFieldTypeConversions 0.165s 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectszvwtal -f - create]" 12:11:09 PASS: create_test.go:14: CliSuite.TestFields 0.084s 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectlnyfnvc -f - create]" 12:11:09 PASS: create_test.go:45: CliSuite.TestHelloWorld 0.082s 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectlfuqlij -f - create]" 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectrygyyhb -f - create]" 12:11:09 PASS: create_test.go:86: CliSuite.TestInterpolation 0.161s 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectqotrzeg -f - create]" 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running create [--verbose -p testprojectuvccywx -f - create]" 12:11:09 PASS: create_test.go:112: CliSuite.TestInterpolationWithExtends 0.189s 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running up [--verbose -p testprojectlkhqukf -f - up -d]" 12:11:09 time="2021-06-16T12:11:08Z" level=info msg="Running kill [--verbose -p testprojectlkhqukf -f - kill]" 12:11:09 PASS: kill_test.go:9: CliSuite.TestKill 0.367s 12:11:09 time="2021-06-16T12:11:09Z" level=info msg="Running up [--verbose -p testprojectafmdfkc -f - up -d]" 12:11:09 PASS: up_test.go:260: CliSuite.TestLink 0.467s 12:11:09 time="2021-06-16T12:11:09Z" level=info msg="[0/4] [multiple]: Creating " 12:11:09 time="2021-06-16T12:11:09Z" level=info msg="[0/4] [another]: Creating " 12:11:09 time="2021-06-16T12:11:09Z" level=info msg="[0/4] [simple]: Creating " 12:11:09 time="2021-06-16T12:11:09Z" level=info msg="[0/4] [yetanother]: Creating " 12:11:10 time="2021-06-16T12:11:09Z" level=info msg="[0/4] [multiple]: Created " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [yetanother]: Created " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [simple]: Created " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [another]: Created " 12:11:10 PASS: create_test.go:176: CliSuite.TestMultipleComposeFilesOneTwo 0.485s 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [yetanother]: Creating " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [another]: Creating " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [simple]: Creating " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [multiple]: Creating " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [multiple]: Created " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [simple]: Created " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [yetanother]: Created " 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="[0/4] [another]: Created " 12:11:10 PASS: create_test.go:204: CliSuite.TestMultipleComposeFilesTwoOne 0.433s 12:11:10 time="2021-06-16T12:11:10Z" level=info msg="Running up [--verbose -p testprojectxuhxbef -f - up -d]" 12:11:11 PASS: volume_test.go:62: CliSuite.TestNamedVolume 0.219s 12:11:11 time="2021-06-16T12:11:11Z" level=info msg="Running up [--verbose -p testprojectoncrmvy -f - up -d]" 12:11:11 time="2021-06-16T12:11:11Z" level=info msg="Running pause [--verbose -p testprojectoncrmvy -f - pause]" 12:11:11 PASS: pause_unpause_test.go:9: CliSuite.TestPause 0.266s 12:11:11 time="2021-06-16T12:11:11Z" level=info msg="Running up [--verbose -p testprojectfqvwyse -f - up -d]" 12:11:11 time="2021-06-16T12:11:11Z" level=info msg="Running pause [--verbose -p testprojectfqvwyse -f - pause]" 12:11:11 time="2021-06-16T12:11:11Z" level=info msg="Running pause [--verbose -p testprojectfqvwyse -f - pause]" 12:11:12 PASS: pause_unpause_test.go:26: CliSuite.TestPauseAlreadyPausedService 0.258s 12:11:12 time="2021-06-16T12:11:11Z" level=info msg="Running up [--verbose -p testprojectwbunpif -f - up -d]" 12:11:12 time="2021-06-16T12:11:12Z" level=info msg="Running ps [--verbose -p testprojectwbunpif -f - ps]" 12:11:12 PASS: ps_test.go:10: CliSuite.TestPs 0.287s 12:11:12 time="2021-06-16T12:11:12Z" level=info msg="Running up [--verbose -p testprojectaolysjx -f - up -d]" 12:11:12 time="2021-06-16T12:11:12Z" level=info msg="Running ps [--verbose -p testprojectaolysjx -f - ps -q]" 12:11:12 PASS: ps_test.go:22: CliSuite.TestPsQuiet 0.246s 12:11:12 time="2021-06-16T12:11:12Z" level=info msg="Running pull [--verbose -p testprojectglsijkj -f - pull]" 12:11:12 PASS: pull_test.go:7: CliSuite.TestPull 0.102s 12:11:12 time="2021-06-16T12:11:12Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up -d]" 12:11:13 time="2021-06-16T12:11:13Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up -d]" 12:11:13 time="2021-06-16T12:11:13Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up -d]" 12:11:13 time="2021-06-16T12:11:13Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up -d]" 12:11:14 time="2021-06-16T12:11:13Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up -d]" 12:11:14 time="2021-06-16T12:11:13Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up --force-recreate -d]" 12:11:14 time="2021-06-16T12:11:14Z" level=info msg="Running up [--verbose -p testprojectuckvxou -f - up --force-recreate -d]" 12:11:15 PASS: up_test.go:105: CliSuite.TestRecreate 1.826s 12:11:15 time="2021-06-16T12:11:14Z" level=info msg="Running up [--verbose -p testprojectgclluna -f - up -d]" 12:11:15 time="2021-06-16T12:11:15Z" level=info msg="Running up [--verbose -p testprojectgclluna -f - up --force-recreate -d]" 12:11:15 PASS: up_test.go:33: CliSuite.TestRecreateForceRecreate 0.660s 12:11:15 time="2021-06-16T12:11:15Z" level=info msg="Running up [--verbose -p testprojectrxqyukd -f - up -d]" 12:11:16 time="2021-06-16T12:11:16Z" level=info msg="Running up [--verbose -p testprojectrxqyukd -f - up --no-recreate -d]" 12:11:16 time="2021-06-16T12:11:16Z" level=info msg="Running up [--verbose -p testprojectrxqyukd -f - up --no-recreate -d]" 12:11:16 time="2021-06-16T12:11:16Z" level=info msg="Running up [--verbose -p testprojectrxqyukd -f - up -d]" 12:11:17 time="2021-06-16T12:11:16Z" level=info msg="Running rm [--verbose -p testprojectrxqyukd -f - rm -f]" 12:11:17 PASS: up_test.go:207: CliSuite.TestRecreateImageChanging 1.227s 12:11:17 time="2021-06-16T12:11:17Z" level=info msg="Running up [--verbose -p testprojectjmkhvbj -f - up -d]" 12:11:17 time="2021-06-16T12:11:17Z" level=info msg="Running up [--verbose -p testprojectjmkhvbj -f - up --no-recreate -d]" 12:11:17 PASS: up_test.go:84: CliSuite.TestRecreateNoRecreate 0.248s 12:11:17 time="2021-06-16T12:11:17Z" level=info msg="Running up [--verbose -p testprojectrivbiup -f - up -d]" 12:11:17 time="2021-06-16T12:11:17Z" level=info msg="Running up [--verbose -p testprojectrivbiup -f - up --force-recreate -d]" 12:11:18 PASS: up_test.go:53: CliSuite.TestRecreateVols 0.655s 12:11:18 time="2021-06-16T12:11:18Z" level=info msg="Running up [--verbose -p testprojectajpciej -f - up -d]" 12:11:18 PASS: volume_test.go:43: CliSuite.TestRelativeVolume 0.252s 12:11:18 time="2021-06-16T12:11:18Z" level=info msg="Running up [--verbose -p testprojectliaqjiu -f - up -d]" 12:11:18 time="2021-06-16T12:11:18Z" level=info msg="Running restart [--verbose -p testprojectliaqjiu -f - restart --timeout 0]" 12:11:31 PASS: restart_test.go:9: CliSuite.TestRestart 10.492s 12:11:31 PASS: run_test.go:19: CliSuite.TestRun 0.366s 12:11:31 time="2021-06-16T12:11:29Z" level=info msg="Running up [--verbose -p testprojectapyislr -f - up -d]" 12:11:31 time="2021-06-16T12:11:29Z" level=info msg="Running scale [--verbose -p testprojectapyislr -f - scale hello=2]" 12:11:31 time="2021-06-16T12:11:30Z" level=info msg="Running scale [--verbose -p testprojectapyislr -f - scale --timeout 0 hello=1]" 12:11:41 PASS: scale_test.go:10: CliSuite.TestScale 10.666s 12:11:41 time="2021-06-16T12:11:40Z" level=info msg="Running up [--verbose -p testprojectrxxxswe -f - up -d]" 12:11:41 time="2021-06-16T12:11:40Z" level=info msg="Running scale [--verbose -p testprojectrxxxswe -f - scale test=2]" 12:11:41 PASS: scale_test.go:46: CliSuite.TestScaleWithHostPortWarning 0.597s 12:11:41 time="2021-06-16T12:11:41Z" level=info msg="Running create [--verbose -p testprojectxgijqll -f - create]" 12:11:41 time="2021-06-16T12:11:41Z" level=info msg="Running start [--verbose -p testprojectxgijqll -f - start]" 12:11:41 PASS: start_test.go:9: CliSuite.TestStart 0.278s 12:11:41 time="2021-06-16T12:11:41Z" level=info msg="Running up [--verbose -p testprojectkmftamo -f - up -d]" 12:11:42 time="2021-06-16T12:11:41Z" level=info msg="Running stop [--verbose -p testprojectkmftamo -f - stop --timeout 0]" 12:11:52 PASS: stop_test.go:9: CliSuite.TestStop 10.349s 12:11:52 time="2021-06-16T12:11:52Z" level=info msg="Running up [--verbose -p testprojectezejkps -f - up -d]" 12:11:52 time="2021-06-16T12:11:52Z" level=info msg="Running pause [--verbose -p testprojectezejkps -f - pause]" 12:11:52 time="2021-06-16T12:11:52Z" level=info msg="Running unpause [--verbose -p testprojectezejkps -f - unpause]" 12:11:52 PASS: pause_unpause_test.go:51: CliSuite.TestUnpause 0.277s 12:11:52 time="2021-06-16T12:11:52Z" level=info msg="Running up [--verbose -p testprojectfcvwuup -f - up -d]" 12:11:52 time="2021-06-16T12:11:52Z" level=info msg="Running unpause [--verbose -p testprojectfcvwuup -f - unpause]" 12:11:53 PASS: pause_unpause_test.go:76: CliSuite.TestUnpauseNotPausedService 0.261s 12:11:53 time="2021-06-16T12:11:52Z" level=info msg="Running up [--verbose -p testprojectimylekf -f - up -d]" 12:11:53 PASS: up_test.go:15: CliSuite.TestUp 0.229s 12:11:53 time="2021-06-16T12:11:53Z" level=info msg="Running up [--verbose -p testprojectblshsqh -f - up -d]" 12:11:53 time="2021-06-16T12:11:53Z" level=info msg="Running up [--verbose -p testprojectblshsqh -f - up --no-recreate -d]" 12:11:53 PASS: up_test.go:177: CliSuite.TestUpAfterImageTagDeleted 0.279s 12:11:53 PASS: up_test.go:291: CliSuite.TestUpNoBuildFailIfImageNotPresent 0.010s 12:11:54 PASS: up_test.go:299: CliSuite.TestUpNoBuildShouldWorkIfImageIsPresent 0.386s 12:11:54 time="2021-06-16T12:11:54Z" level=info msg="Running up [--verbose -p testprojectejkvkrz -f - up -d]" 12:11:54 PASS: up_test.go:25: CliSuite.TestUpNotExistService 0.238s 12:11:54 time="2021-06-16T12:11:54Z" level=info msg="Running up [--verbose -p testprojectrxevglv -f - up -d]" 12:11:55 PASS: volume_test.go:79: CliSuite.TestV2Volume 0.331s 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectwftmeci -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectwftmeci -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectmzxgmez -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectmzxgmez -f - create]\n with input:\n\n test:\n image: busybox\n build: .\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectixegiek -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectixegiek -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:11:55 PASS: create_test.go:262: CliSuite.TestValidation 0.024s 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojecttyhltei -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecttyhltei -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectnyeldnn -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectnyeldnn -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:11:55 PASS: create_test.go:297: CliSuite.TestValidationV2 0.015s 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectcxeuwov -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectcxeuwov -f - create]\n with input:\n\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectszyyzil -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectszyyzil -f - create]\n with input:\n\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectnyvykpw -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectnyvykpw -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v1.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectahuhhhf -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectahuhhhf -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v1.yml\n service: base\n\t" 12:11:55 PASS: create_test.go:327: CliSuite.TestValidationWithExtends 0.028s 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectzvcyhgr -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectzvcyhgr -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectrsqfysf -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectrsqfysf -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectmdxgrdt -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectmdxgrdt -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v2.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:11:55 time="2021-06-16T12:11:55Z" level=info msg="Running create [--verbose -p testprojectesjpbou -f - create]" 12:11:55 time="2021-06-16T12:11:55Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectesjpbou -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v2.yml\n service: base\n\t" 12:11:55 PASS: create_test.go:380: CliSuite.TestValidationWithExtendsV2 0.029s 12:11:55 PASS: volume_test.go:11: CliSuite.TestVolumeFromService 0.251s 12:11:55 PASS: volume_test.go:27: CliSuite.TestVolumeFromServiceWithContainerName 0.242s 12:11:55 OK: 59 passed 12:11:55 PASS 12:11:55 coverage: 33.3% of statements 12:11:55 ok github.com/docker/libcompose/integration 105.705s 12:11:55 ---> Making bundle: .integration-daemon-stop (in .) 12:11:55 ++++ cat ./docker.pid 12:11:55 +++ kill 9501 12:11:55 12:11:55 Running integration test against 1.13.1 12:11:55 ---> Making bundle: .integration-daemon-start (in .) 12:11:55 /usr/local/bin/docker-1.13.1/dockerd 12:11:55 +++ exec dockerd --debug --host unix:///go/src/github.com/docker/libcompose/docker.sock --storage-driver vfs --pidfile ./docker.pid --userland-proxy=true --graph=/var/lib/docker/1.13.1 12:11:58 Client: 12:11:58 Version: 1.13.1 12:11:58 API version: 1.26 12:11:58 Go version: go1.7.5 12:11:58 Git commit: 092cba3 12:11:58 Built: Wed Feb 8 08:47:51 2017 12:11:58 OS/Arch: linux/amd64 12:11:58 12:11:58 Server: 12:11:58 Version: 1.13.1 12:11:58 API version: 1.26 (minimum version 1.12) 12:11:58 Go version: go1.7.5 12:11:58 Git commit: 092cba3 12:11:58 Built: Wed Feb 8 08:47:51 2017 12:11:58 OS/Arch: linux/amd64 12:11:58 Experimental: false 12:11:59 time="2021-06-16T12:11:59Z" level=info msg="[0/2] [another]: Starting " 12:11:59 time="2021-06-16T12:11:59Z" level=info msg="[0/2] [simple]: Starting " 12:11:59 Pulling simple (busybox:latest)... 12:11:59 Pulling another (busybox:latest)... 12:11:59 latest: Pulling from library/busybox 12:11:59 latest: Pulling from library/busybox 12:11:59 b71f96345d44: Pulling fs layer 12:11:59 b71f96345d44: Pulling fs layer 12:11:59 b71f96345d44: Download complete 12:11:59 b71f96345d44: Download complete 12:11:59 b71f96345d44: Pull complete 12:11:59 b71f96345d44: Pull complete 12:11:59 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:11:59 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:11:59 Status: Downloaded newer image for busybox:latest 12:11:59 Status: Image is up to date for busybox:latest 12:12:00 time="2021-06-16T12:11:59Z" level=info msg="[1/2] [simple]: Started " 12:12:00 time="2021-06-16T12:12:00Z" level=info msg="[2/2] [another]: Started " 12:12:00 PASS: api_event_test.go:16: APISuite.TestEvents 0.932s 12:12:00 time="2021-06-16T12:12:00Z" level=info msg="[0/1] [service]: Starting " 12:12:00 time="2021-06-16T12:12:00Z" level=info msg="[1/1] [service]: Started " 12:12:00 PASS: api_test.go:20: APISuite.TestVolumeWithoutComposeFile 0.265s 12:12:02 PASS: build_test.go:13: CliSuite.TestBuild 1.487s 12:12:04 PASS: build_test.go:85: CliSuite.TestBuildWithArgs 1.747s 12:12:04 PASS: build_test.go:33: CliSuite.TestBuildWithNoCache1 0.100s 12:12:06 PASS: build_test.go:49: CliSuite.TestBuildWithNoCache2 1.898s 12:12:08 PASS: build_test.go:65: CliSuite.TestBuildWithNoCache3 2.000s 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="Running create [--verbose -p testprojectftxgwtp -f - create]" 12:12:08 PASS: create_test.go:58: CliSuite.TestContainerName 0.099s 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="Running create [--verbose -p testprojectlqcmviy -f - create]" 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="Running scale [--verbose -p testprojectlqcmviy -f - scale hello=2]" 12:12:08 PASS: create_test.go:72: CliSuite.TestContainerNameWithScale 0.120s 12:12:08 PASS: env_test.go:12: CliSuite.TestCreateWithEnvInCurrentDir 0.244s 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="Running create [--verbose -p testprojectqjpctpp -f - create]" 12:12:08 PASS: env_test.go:38: CliSuite.TestCreateWithEnvNotInCurrentDir 0.053s 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="[0/3] [yetanother]: Creating " 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="[0/3] [another]: Creating " 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="[0/3] [simple]: Creating " 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="[0/3] [another]: Created " 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="[0/3] [simple]: Created " 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="[0/3] [yetanother]: Created " 12:12:08 PASS: create_test.go:241: CliSuite.TestDefaultMultipleComposeFiles 0.249s 12:12:08 time="2021-06-16T12:12:08Z" level=info msg="Running up [--verbose -p testprojectmoasuje -f - up -d]" 12:12:09 time="2021-06-16T12:12:09Z" level=info msg="Running stop [--verbose -p testprojectmoasuje -f - stop --timeout 0]" 12:12:21 time="2021-06-16T12:12:19Z" level=info msg="Running rm [--verbose -p testprojectmoasuje -f - rm --force]" 12:12:21 PASS: rm_test.go:9: CliSuite.TestDelete 10.402s 12:12:21 time="2021-06-16T12:12:19Z" level=info msg="Running up [--verbose -p testprojectuytfstg -f - up -d]" 12:12:21 time="2021-06-16T12:12:19Z" level=info msg="Running stop [--verbose -p testprojectuytfstg -f - stop bye --timeout 0]" 12:12:31 time="2021-06-16T12:12:29Z" level=info msg="Running rm [--verbose -p testprojectuytfstg -f - rm --force]" 12:12:31 PASS: rm_test.go:25: CliSuite.TestDeleteOnlyRemovesStopped 10.541s 12:12:31 time="2021-06-16T12:12:29Z" level=info msg="Running up [--verbose -p testprojectdgxlnoa -f - up -d]" 12:12:31 time="2021-06-16T12:12:30Z" level=info msg="Running stop [--verbose -p testprojectdgxlnoa -f - stop --timeout 0]" 12:12:41 time="2021-06-16T12:12:40Z" level=info msg="Running rm [--verbose -p testprojectdgxlnoa -f - rm --force -v]" 12:12:41 PASS: rm_test.go:65: CliSuite.TestDeleteWithVol 10.414s 12:12:41 time="2021-06-16T12:12:40Z" level=info msg="Running up [--verbose -p testprojectvnuwsbz -f - up -d]" 12:12:41 time="2021-06-16T12:12:40Z" level=info msg="Running down [--verbose -p testprojectvnuwsbz -f - down]" 12:12:51 PASS: down_test.go:9: CliSuite.TestDown 10.399s 12:12:51 time="2021-06-16T12:12:50Z" level=info msg="Running up [--verbose -p testprojectrloiojd -f - up -d]" 12:12:51 time="2021-06-16T12:12:50Z" level=info msg="Running scale [--verbose -p testprojectrloiojd -f - scale hello=2]" 12:12:51 time="2021-06-16T12:12:51Z" level=info msg="Running down [--verbose -p testprojectrloiojd -f - down]" 12:13:03 PASS: down_test.go:24: CliSuite.TestDownMultiple 10.749s 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectvuirgqw -f - create]" 12:13:03 PASS: create_test.go:31: CliSuite.TestEmptyEntrypoint 0.095s 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectovgkjox -f - create]" 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectwrdbppb -f - create]" 12:13:03 PASS: create_test.go:146: CliSuite.TestFieldTypeConversions 0.113s 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectxfmxxtv -f - create]" 12:13:03 PASS: create_test.go:14: CliSuite.TestFields 0.056s 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectvkbzdbp -f - create]" 12:13:03 PASS: create_test.go:45: CliSuite.TestHelloWorld 0.058s 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectmgkhnzp -f - create]" 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectugjbkrt -f - create]" 12:13:03 PASS: create_test.go:86: CliSuite.TestInterpolation 0.112s 12:13:03 time="2021-06-16T12:13:01Z" level=info msg="Running create [--verbose -p testprojectehpywjp -f - create]" 12:13:03 time="2021-06-16T12:13:02Z" level=info msg="Running create [--verbose -p testprojectmuclinv -f - create]" 12:13:03 PASS: create_test.go:112: CliSuite.TestInterpolationWithExtends 0.118s 12:13:03 time="2021-06-16T12:13:02Z" level=info msg="Running up [--verbose -p testprojecttgcocfj -f - up -d]" 12:13:03 time="2021-06-16T12:13:02Z" level=info msg="Running kill [--verbose -p testprojecttgcocfj -f - kill]" 12:13:03 PASS: kill_test.go:9: CliSuite.TestKill 0.400s 12:13:03 time="2021-06-16T12:13:02Z" level=info msg="Running up [--verbose -p testprojectdqahvjk -f - up -d]" 12:13:03 PASS: up_test.go:260: CliSuite.TestLink 0.511s 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [yetanother]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [multiple]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [another]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [simple]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [yetanother]: Created " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [another]: Created " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [simple]: Created " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [multiple]: Created " 12:13:03 PASS: create_test.go:176: CliSuite.TestMultipleComposeFilesOneTwo 0.327s 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [another]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [simple]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [multiple]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [yetanother]: Creating " 12:13:03 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [multiple]: Created " 12:13:04 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [yetanother]: Created " 12:13:04 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [another]: Created " 12:13:04 time="2021-06-16T12:13:03Z" level=info msg="[0/4] [simple]: Created " 12:13:04 PASS: create_test.go:204: CliSuite.TestMultipleComposeFilesTwoOne 0.287s 12:13:04 time="2021-06-16T12:13:03Z" level=info msg="Running up [--verbose -p testprojectuheejub -f - up -d]" 12:13:04 PASS: volume_test.go:62: CliSuite.TestNamedVolume 0.263s 12:13:04 time="2021-06-16T12:13:04Z" level=info msg="Running up [--verbose -p testprojectpqgcter -f - up -d]" 12:13:04 time="2021-06-16T12:13:04Z" level=info msg="Running pause [--verbose -p testprojectpqgcter -f - pause]" 12:13:04 PASS: pause_unpause_test.go:9: CliSuite.TestPause 0.254s 12:13:04 time="2021-06-16T12:13:04Z" level=info msg="Running up [--verbose -p testprojectuyzbies -f - up -d]" 12:13:05 time="2021-06-16T12:13:05Z" level=info msg="Running pause [--verbose -p testprojectuyzbies -f - pause]" 12:13:05 time="2021-06-16T12:13:05Z" level=info msg="Running pause [--verbose -p testprojectuyzbies -f - pause]" 12:13:05 PASS: pause_unpause_test.go:26: CliSuite.TestPauseAlreadyPausedService 0.263s 12:13:05 time="2021-06-16T12:13:05Z" level=info msg="Running up [--verbose -p testprojectdzvwmgq -f - up -d]" 12:13:05 time="2021-06-16T12:13:05Z" level=info msg="Running ps [--verbose -p testprojectdzvwmgq -f - ps]" 12:13:05 PASS: ps_test.go:10: CliSuite.TestPs 0.266s 12:13:05 time="2021-06-16T12:13:05Z" level=info msg="Running up [--verbose -p testprojectvogamar -f - up -d]" 12:13:05 time="2021-06-16T12:13:05Z" level=info msg="Running ps [--verbose -p testprojectvogamar -f - ps -q]" 12:13:06 PASS: ps_test.go:22: CliSuite.TestPsQuiet 0.257s 12:13:06 time="2021-06-16T12:13:05Z" level=info msg="Running pull [--verbose -p testprojectkbesxjd -f - pull]" 12:13:06 PASS: pull_test.go:7: CliSuite.TestPull 0.085s 12:13:06 time="2021-06-16T12:13:06Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up -d]" 12:13:06 time="2021-06-16T12:13:06Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up -d]" 12:13:06 time="2021-06-16T12:13:06Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up -d]" 12:13:06 time="2021-06-16T12:13:06Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up -d]" 12:13:07 time="2021-06-16T12:13:07Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up -d]" 12:13:07 time="2021-06-16T12:13:07Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up --force-recreate -d]" 12:13:07 time="2021-06-16T12:13:07Z" level=info msg="Running up [--verbose -p testprojectaqeglwz -f - up --force-recreate -d]" 12:13:08 PASS: up_test.go:105: CliSuite.TestRecreate 1.804s 12:13:08 time="2021-06-16T12:13:07Z" level=info msg="Running up [--verbose -p testprojectloynwfk -f - up -d]" 12:13:08 time="2021-06-16T12:13:08Z" level=info msg="Running up [--verbose -p testprojectloynwfk -f - up --force-recreate -d]" 12:13:08 PASS: up_test.go:33: CliSuite.TestRecreateForceRecreate 0.707s 12:13:08 time="2021-06-16T12:13:08Z" level=info msg="Running up [--verbose -p testprojectaofhyqt -f - up -d]" 12:13:09 time="2021-06-16T12:13:09Z" level=info msg="Running up [--verbose -p testprojectaofhyqt -f - up --no-recreate -d]" 12:13:09 time="2021-06-16T12:13:09Z" level=info msg="Running up [--verbose -p testprojectaofhyqt -f - up --no-recreate -d]" 12:13:09 time="2021-06-16T12:13:09Z" level=info msg="Running up [--verbose -p testprojectaofhyqt -f - up -d]" 12:13:10 time="2021-06-16T12:13:09Z" level=info msg="Running rm [--verbose -p testprojectaofhyqt -f - rm -f]" 12:13:10 PASS: up_test.go:207: CliSuite.TestRecreateImageChanging 1.163s 12:13:10 time="2021-06-16T12:13:10Z" level=info msg="Running up [--verbose -p testprojectwrewzfb -f - up -d]" 12:13:10 time="2021-06-16T12:13:10Z" level=info msg="Running up [--verbose -p testprojectwrewzfb -f - up --no-recreate -d]" 12:13:10 PASS: up_test.go:84: CliSuite.TestRecreateNoRecreate 0.288s 12:13:10 time="2021-06-16T12:13:10Z" level=info msg="Running up [--verbose -p testprojectanbhlma -f - up -d]" 12:13:10 time="2021-06-16T12:13:10Z" level=info msg="Running up [--verbose -p testprojectanbhlma -f - up --force-recreate -d]" 12:13:11 PASS: up_test.go:53: CliSuite.TestRecreateVols 0.609s 12:13:11 time="2021-06-16T12:13:11Z" level=info msg="Running up [--verbose -p testprojectpireviz -f - up -d]" 12:13:11 PASS: volume_test.go:43: CliSuite.TestRelativeVolume 0.265s 12:13:11 time="2021-06-16T12:13:11Z" level=info msg="Running up [--verbose -p testprojectwooxmoz -f - up -d]" 12:13:11 time="2021-06-16T12:13:11Z" level=info msg="Running restart [--verbose -p testprojectwooxmoz -f - restart --timeout 0]" 12:13:22 PASS: restart_test.go:9: CliSuite.TestRestart 10.541s 12:13:22 PASS: run_test.go:19: CliSuite.TestRun 0.367s 12:13:22 time="2021-06-16T12:13:22Z" level=info msg="Running up [--verbose -p testprojectnkfsrvq -f - up -d]" 12:13:23 time="2021-06-16T12:13:22Z" level=info msg="Running scale [--verbose -p testprojectnkfsrvq -f - scale hello=2]" 12:13:23 time="2021-06-16T12:13:23Z" level=info msg="Running scale [--verbose -p testprojectnkfsrvq -f - scale --timeout 0 hello=1]" 12:13:35 PASS: scale_test.go:10: CliSuite.TestScale 10.653s 12:13:35 time="2021-06-16T12:13:33Z" level=info msg="Running up [--verbose -p testprojectanjfkod -f - up -d]" 12:13:35 time="2021-06-16T12:13:33Z" level=info msg="Running scale [--verbose -p testprojectanjfkod -f - scale test=2]" 12:13:35 PASS: scale_test.go:46: CliSuite.TestScaleWithHostPortWarning 0.701s 12:13:35 time="2021-06-16T12:13:34Z" level=info msg="Running create [--verbose -p testprojectvxukykg -f - create]" 12:13:35 time="2021-06-16T12:13:34Z" level=info msg="Running start [--verbose -p testprojectvxukykg -f - start]" 12:13:35 PASS: start_test.go:9: CliSuite.TestStart 0.255s 12:13:35 time="2021-06-16T12:13:34Z" level=info msg="Running up [--verbose -p testprojecthjmjnoc -f - up -d]" 12:13:35 time="2021-06-16T12:13:34Z" level=info msg="Running stop [--verbose -p testprojecthjmjnoc -f - stop --timeout 0]" 12:13:45 PASS: stop_test.go:9: CliSuite.TestStop 10.387s 12:13:45 time="2021-06-16T12:13:45Z" level=info msg="Running up [--verbose -p testprojectflkruio -f - up -d]" 12:13:45 time="2021-06-16T12:13:45Z" level=info msg="Running pause [--verbose -p testprojectflkruio -f - pause]" 12:13:45 time="2021-06-16T12:13:45Z" level=info msg="Running unpause [--verbose -p testprojectflkruio -f - unpause]" 12:13:45 PASS: pause_unpause_test.go:51: CliSuite.TestUnpause 0.286s 12:13:45 time="2021-06-16T12:13:45Z" level=info msg="Running up [--verbose -p testprojectmdpdmlg -f - up -d]" 12:13:45 time="2021-06-16T12:13:45Z" level=info msg="Running unpause [--verbose -p testprojectmdpdmlg -f - unpause]" 12:13:46 PASS: pause_unpause_test.go:76: CliSuite.TestUnpauseNotPausedService 0.254s 12:13:46 time="2021-06-16T12:13:45Z" level=info msg="Running up [--verbose -p testprojectvolpasu -f - up -d]" 12:13:46 PASS: up_test.go:15: CliSuite.TestUp 0.259s 12:13:46 time="2021-06-16T12:13:46Z" level=info msg="Running up [--verbose -p testprojecteczchwt -f - up -d]" 12:13:46 time="2021-06-16T12:13:46Z" level=info msg="Running up [--verbose -p testprojecteczchwt -f - up --no-recreate -d]" 12:13:46 PASS: up_test.go:177: CliSuite.TestUpAfterImageTagDeleted 0.265s 12:13:46 PASS: up_test.go:291: CliSuite.TestUpNoBuildFailIfImageNotPresent 0.010s 12:13:47 PASS: up_test.go:299: CliSuite.TestUpNoBuildShouldWorkIfImageIsPresent 0.384s 12:13:47 time="2021-06-16T12:13:47Z" level=info msg="Running up [--verbose -p testprojectrsnyofy -f - up -d]" 12:13:47 PASS: up_test.go:25: CliSuite.TestUpNotExistService 0.249s 12:13:47 time="2021-06-16T12:13:47Z" level=info msg="Running up [--verbose -p testprojectbtsfsmc -f - up -d]" 12:14:05 PASS: volume_test.go:79: CliSuite.TestV2Volume 15.346s 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectoiinbeh -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectoiinbeh -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojecteoqxjos -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecteoqxjos -f - create]\n with input:\n\n test:\n image: busybox\n build: .\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectlxszksi -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectlxszksi -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:14:05 PASS: create_test.go:262: CliSuite.TestValidation 0.023s 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojecttndhgsj -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecttndhgsj -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectzalvbqn -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectzalvbqn -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:14:05 PASS: create_test.go:297: CliSuite.TestValidationV2 0.014s 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectfqgcihv -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectfqgcihv -f - create]\n with input:\n\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectonwgzsd -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectonwgzsd -f - create]\n with input:\n\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojecthvqommf -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecthvqommf -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v1.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectrvxbgzt -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectrvxbgzt -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v1.yml\n service: base\n\t" 12:14:05 PASS: create_test.go:327: CliSuite.TestValidationWithExtends 0.028s 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojecthxmfwzq -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecthxmfwzq -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojecttqdglte -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecttqdglte -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectutffbxx -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectutffbxx -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v2.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:14:05 time="2021-06-16T12:14:03Z" level=info msg="Running create [--verbose -p testprojectcuzfpml -f - create]" 12:14:05 time="2021-06-16T12:14:03Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectcuzfpml -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v2.yml\n service: base\n\t" 12:14:05 PASS: create_test.go:380: CliSuite.TestValidationWithExtendsV2 0.029s 12:14:05 PASS: volume_test.go:11: CliSuite.TestVolumeFromService 0.190s 12:14:05 PASS: volume_test.go:27: CliSuite.TestVolumeFromServiceWithContainerName 0.180s 12:14:05 OK: 59 passed 12:14:05 PASS 12:14:05 coverage: 33.3% of statements 12:14:05 ok github.com/docker/libcompose/integration 124.299s 12:14:05 ---> Making bundle: .integration-daemon-stop (in .) 12:14:05 ++++ cat ./docker.pid 12:14:05 +++ kill 12024 12:14:05 12:14:05 Running integration test against 17.03.2 12:14:05 ---> Making bundle: .integration-daemon-start (in .) 12:14:05 /usr/local/bin/docker-17.03.2/dockerd 12:14:05 +++ exec dockerd --debug --host unix:///go/src/github.com/docker/libcompose/docker.sock --storage-driver vfs --pidfile ./docker.pid --userland-proxy=true --graph=/var/lib/docker/17.03.2 12:14:06 Client: 12:14:06 Version: 17.03.2-ce 12:14:06 API version: 1.27 12:14:06 Go version: go1.7.5 12:14:06 Git commit: f5ec1e2 12:14:06 Built: Tue Jun 27 01:35:00 2017 12:14:06 OS/Arch: linux/amd64 12:14:06 12:14:06 Server: 12:14:06 Version: 17.03.2-ce 12:14:06 API version: 1.27 (minimum version 1.12) 12:14:06 Go version: go1.7.5 12:14:06 Git commit: f5ec1e2 12:14:06 Built: Tue Jun 27 01:35:00 2017 12:14:06 OS/Arch: linux/amd64 12:14:06 Experimental: false 12:14:08 time="2021-06-16T12:14:08Z" level=info msg="[0/2] [simple]: Starting " 12:14:08 time="2021-06-16T12:14:08Z" level=info msg="[0/2] [another]: Starting " 12:14:08 Pulling another (busybox:latest)... 12:14:08 Pulling simple (busybox:latest)... 12:14:08 latest: Pulling from library/busybox 12:14:08 latest: Pulling from library/busybox 12:14:08 b71f96345d44: Pulling fs layer 12:14:08 b71f96345d44: Pulling fs layer 12:14:08 b71f96345d44: Verifying Checksum 12:14:08 b71f96345d44: Verifying Checksum 12:14:08 b71f96345d44: Download complete 12:14:08 b71f96345d44: Download complete 12:14:08 b71f96345d44: Pull complete 12:14:08 b71f96345d44: Pull complete 12:14:08 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:14:08 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:14:08 Status: Downloaded newer image for busybox:latest 12:14:08 Status: Image is up to date for busybox:latest 12:14:09 time="2021-06-16T12:14:08Z" level=info msg="[1/2] [simple]: Started " 12:14:09 time="2021-06-16T12:14:09Z" level=info msg="[2/2] [another]: Started " 12:14:09 PASS: api_event_test.go:16: APISuite.TestEvents 0.935s 12:14:09 time="2021-06-16T12:14:09Z" level=info msg="[0/1] [service]: Starting " 12:14:09 time="2021-06-16T12:14:09Z" level=info msg="[1/1] [service]: Started " 12:14:09 PASS: api_test.go:20: APISuite.TestVolumeWithoutComposeFile 0.261s 12:14:11 PASS: build_test.go:13: CliSuite.TestBuild 1.484s 12:14:13 PASS: build_test.go:85: CliSuite.TestBuildWithArgs 1.707s 12:14:13 PASS: build_test.go:33: CliSuite.TestBuildWithNoCache1 0.091s 12:14:15 PASS: build_test.go:49: CliSuite.TestBuildWithNoCache2 1.908s 12:14:17 PASS: build_test.go:65: CliSuite.TestBuildWithNoCache3 2.000s 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="Running create [--verbose -p testprojectafchxyc -f - create]" 12:14:17 PASS: create_test.go:58: CliSuite.TestContainerName 0.092s 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="Running create [--verbose -p testprojectnurdlso -f - create]" 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="Running scale [--verbose -p testprojectnurdlso -f - scale hello=2]" 12:14:17 PASS: create_test.go:72: CliSuite.TestContainerNameWithScale 0.121s 12:14:17 PASS: env_test.go:12: CliSuite.TestCreateWithEnvInCurrentDir 0.219s 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="Running create [--verbose -p testprojectrkfaeqz -f - create]" 12:14:17 PASS: env_test.go:38: CliSuite.TestCreateWithEnvNotInCurrentDir 0.053s 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="[0/3] [yetanother]: Creating " 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="[0/3] [another]: Creating " 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="[0/3] [simple]: Creating " 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="[0/3] [yetanother]: Created " 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="[0/3] [another]: Created " 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="[0/3] [simple]: Created " 12:14:17 PASS: create_test.go:241: CliSuite.TestDefaultMultipleComposeFiles 0.247s 12:14:17 time="2021-06-16T12:14:17Z" level=info msg="Running up [--verbose -p testprojectowpgujb -f - up -d]" 12:14:18 time="2021-06-16T12:14:18Z" level=info msg="Running stop [--verbose -p testprojectowpgujb -f - stop --timeout 0]" 12:14:30 time="2021-06-16T12:14:28Z" level=info msg="Running rm [--verbose -p testprojectowpgujb -f - rm --force]" 12:14:30 PASS: rm_test.go:9: CliSuite.TestDelete 10.402s 12:14:30 time="2021-06-16T12:14:28Z" level=info msg="Running up [--verbose -p testprojectwhxcszv -f - up -d]" 12:14:30 time="2021-06-16T12:14:28Z" level=info msg="Running stop [--verbose -p testprojectwhxcszv -f - stop bye --timeout 0]" 12:14:40 time="2021-06-16T12:14:38Z" level=info msg="Running rm [--verbose -p testprojectwhxcszv -f - rm --force]" 12:14:40 PASS: rm_test.go:25: CliSuite.TestDeleteOnlyRemovesStopped 10.551s 12:14:40 time="2021-06-16T12:14:38Z" level=info msg="Running up [--verbose -p testprojectjskvzwk -f - up -d]" 12:14:40 time="2021-06-16T12:14:39Z" level=info msg="Running stop [--verbose -p testprojectjskvzwk -f - stop --timeout 0]" 12:14:50 time="2021-06-16T12:14:49Z" level=info msg="Running rm [--verbose -p testprojectjskvzwk -f - rm --force -v]" 12:14:50 PASS: rm_test.go:65: CliSuite.TestDeleteWithVol 10.428s 12:14:50 time="2021-06-16T12:14:49Z" level=info msg="Running up [--verbose -p testprojectuppgepd -f - up -d]" 12:14:50 time="2021-06-16T12:14:49Z" level=info msg="Running down [--verbose -p testprojectuppgepd -f - down]" 12:15:00 PASS: down_test.go:9: CliSuite.TestDown 10.379s 12:15:00 time="2021-06-16T12:14:59Z" level=info msg="Running up [--verbose -p testprojectrltlmks -f - up -d]" 12:15:00 time="2021-06-16T12:14:59Z" level=info msg="Running scale [--verbose -p testprojectrltlmks -f - scale hello=2]" 12:15:00 time="2021-06-16T12:15:00Z" level=info msg="Running down [--verbose -p testprojectrltlmks -f - down]" 12:15:12 PASS: down_test.go:24: CliSuite.TestDownMultiple 10.745s 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectiavdhqi -f - create]" 12:15:12 PASS: create_test.go:31: CliSuite.TestEmptyEntrypoint 0.099s 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojecteggqlzb -f - create]" 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectqframsd -f - create]" 12:15:12 PASS: create_test.go:146: CliSuite.TestFieldTypeConversions 0.110s 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectcgwjwuc -f - create]" 12:15:12 PASS: create_test.go:14: CliSuite.TestFields 0.053s 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectzblqowv -f - create]" 12:15:12 PASS: create_test.go:45: CliSuite.TestHelloWorld 0.053s 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectsozwcck -f - create]" 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectivkixjp -f - create]" 12:15:12 PASS: create_test.go:86: CliSuite.TestInterpolation 0.105s 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectqcuxkpg -f - create]" 12:15:12 time="2021-06-16T12:15:10Z" level=info msg="Running create [--verbose -p testprojectioahkzd -f - create]" 12:15:12 PASS: create_test.go:112: CliSuite.TestInterpolationWithExtends 0.128s 12:15:12 time="2021-06-16T12:15:11Z" level=info msg="Running up [--verbose -p testprojectkuhitld -f - up -d]" 12:15:12 time="2021-06-16T12:15:11Z" level=info msg="Running kill [--verbose -p testprojectkuhitld -f - kill]" 12:15:12 PASS: kill_test.go:9: CliSuite.TestKill 0.398s 12:15:12 time="2021-06-16T12:15:11Z" level=info msg="Running up [--verbose -p testprojectuxvinui -f - up -d]" 12:15:12 PASS: up_test.go:260: CliSuite.TestLink 0.513s 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [simple]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [another]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [yetanother]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [multiple]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [simple]: Created " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [another]: Created " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [yetanother]: Created " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [multiple]: Created " 12:15:12 PASS: create_test.go:176: CliSuite.TestMultipleComposeFilesOneTwo 0.317s 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [simple]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [multiple]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [yetanother]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [another]: Creating " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [yetanother]: Created " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [multiple]: Created " 12:15:12 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [simple]: Created " 12:15:13 time="2021-06-16T12:15:12Z" level=info msg="[0/4] [another]: Created " 12:15:13 PASS: create_test.go:204: CliSuite.TestMultipleComposeFilesTwoOne 0.282s 12:15:13 time="2021-06-16T12:15:12Z" level=info msg="Running up [--verbose -p testprojectmhvpygc -f - up -d]" 12:15:13 PASS: volume_test.go:62: CliSuite.TestNamedVolume 0.281s 12:15:13 time="2021-06-16T12:15:13Z" level=info msg="Running up [--verbose -p testprojectiscjprh -f - up -d]" 12:15:13 time="2021-06-16T12:15:13Z" level=info msg="Running pause [--verbose -p testprojectiscjprh -f - pause]" 12:15:13 PASS: pause_unpause_test.go:9: CliSuite.TestPause 0.311s 12:15:13 time="2021-06-16T12:15:13Z" level=info msg="Running up [--verbose -p testprojectrpmjhyr -f - up -d]" 12:15:14 time="2021-06-16T12:15:14Z" level=info msg="Running pause [--verbose -p testprojectrpmjhyr -f - pause]" 12:15:14 time="2021-06-16T12:15:14Z" level=info msg="Running pause [--verbose -p testprojectrpmjhyr -f - pause]" 12:15:14 PASS: pause_unpause_test.go:26: CliSuite.TestPauseAlreadyPausedService 0.320s 12:15:14 time="2021-06-16T12:15:14Z" level=info msg="Running up [--verbose -p testprojectztgvlhw -f - up -d]" 12:15:14 time="2021-06-16T12:15:14Z" level=info msg="Running ps [--verbose -p testprojectztgvlhw -f - ps]" 12:15:14 PASS: ps_test.go:10: CliSuite.TestPs 0.288s 12:15:14 time="2021-06-16T12:15:14Z" level=info msg="Running up [--verbose -p testprojectemerqem -f - up -d]" 12:15:15 time="2021-06-16T12:15:14Z" level=info msg="Running ps [--verbose -p testprojectemerqem -f - ps -q]" 12:15:15 PASS: ps_test.go:22: CliSuite.TestPsQuiet 0.267s 12:15:15 time="2021-06-16T12:15:15Z" level=info msg="Running pull [--verbose -p testprojectgffxxvc -f - pull]" 12:15:15 PASS: pull_test.go:7: CliSuite.TestPull 0.093s 12:15:15 time="2021-06-16T12:15:15Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up -d]" 12:15:15 time="2021-06-16T12:15:15Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up -d]" 12:15:15 time="2021-06-16T12:15:15Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up -d]" 12:15:15 time="2021-06-16T12:15:15Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up -d]" 12:15:16 time="2021-06-16T12:15:16Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up -d]" 12:15:16 time="2021-06-16T12:15:16Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up --force-recreate -d]" 12:15:16 time="2021-06-16T12:15:16Z" level=info msg="Running up [--verbose -p testprojectrcdxhov -f - up --force-recreate -d]" 12:15:17 PASS: up_test.go:105: CliSuite.TestRecreate 1.865s 12:15:17 time="2021-06-16T12:15:17Z" level=info msg="Running up [--verbose -p testprojectkkotugd -f - up -d]" 12:15:17 time="2021-06-16T12:15:17Z" level=info msg="Running up [--verbose -p testprojectkkotugd -f - up --force-recreate -d]" 12:15:18 PASS: up_test.go:33: CliSuite.TestRecreateForceRecreate 0.683s 12:15:18 time="2021-06-16T12:15:17Z" level=info msg="Running up [--verbose -p testprojectshftaya -f - up -d]" 12:15:18 time="2021-06-16T12:15:18Z" level=info msg="Running up [--verbose -p testprojectshftaya -f - up --no-recreate -d]" 12:15:18 time="2021-06-16T12:15:18Z" level=info msg="Running up [--verbose -p testprojectshftaya -f - up --no-recreate -d]" 12:15:18 time="2021-06-16T12:15:18Z" level=info msg="Running up [--verbose -p testprojectshftaya -f - up -d]" 12:15:19 time="2021-06-16T12:15:19Z" level=info msg="Running rm [--verbose -p testprojectshftaya -f - rm -f]" 12:15:19 PASS: up_test.go:207: CliSuite.TestRecreateImageChanging 1.105s 12:15:19 time="2021-06-16T12:15:19Z" level=info msg="Running up [--verbose -p testprojectyucddmj -f - up -d]" 12:15:19 time="2021-06-16T12:15:19Z" level=info msg="Running up [--verbose -p testprojectyucddmj -f - up --no-recreate -d]" 12:15:19 PASS: up_test.go:84: CliSuite.TestRecreateNoRecreate 0.290s 12:15:19 time="2021-06-16T12:15:19Z" level=info msg="Running up [--verbose -p testprojectftfumzm -f - up -d]" 12:15:20 time="2021-06-16T12:15:19Z" level=info msg="Running up [--verbose -p testprojectftfumzm -f - up --force-recreate -d]" 12:15:20 PASS: up_test.go:53: CliSuite.TestRecreateVols 0.635s 12:15:20 time="2021-06-16T12:15:20Z" level=info msg="Running up [--verbose -p testprojectazrgirp -f - up -d]" 12:15:20 PASS: volume_test.go:43: CliSuite.TestRelativeVolume 0.278s 12:15:20 time="2021-06-16T12:15:20Z" level=info msg="Running up [--verbose -p testprojectezjbooa -f - up -d]" 12:15:21 time="2021-06-16T12:15:21Z" level=info msg="Running restart [--verbose -p testprojectezjbooa -f - restart --timeout 0]" 12:15:33 PASS: restart_test.go:9: CliSuite.TestRestart 10.542s 12:15:33 PASS: run_test.go:19: CliSuite.TestRun 0.386s 12:15:33 time="2021-06-16T12:15:31Z" level=info msg="Running up [--verbose -p testprojectogxsryk -f - up -d]" 12:15:33 time="2021-06-16T12:15:32Z" level=info msg="Running scale [--verbose -p testprojectogxsryk -f - scale hello=2]" 12:15:33 time="2021-06-16T12:15:32Z" level=info msg="Running scale [--verbose -p testprojectogxsryk -f - scale --timeout 0 hello=1]" 12:15:43 PASS: scale_test.go:10: CliSuite.TestScale 10.681s 12:15:43 time="2021-06-16T12:15:42Z" level=info msg="Running up [--verbose -p testprojectbexiirq -f - up -d]" 12:15:43 time="2021-06-16T12:15:42Z" level=info msg="Running scale [--verbose -p testprojectbexiirq -f - scale test=2]" 12:15:43 PASS: scale_test.go:46: CliSuite.TestScaleWithHostPortWarning 0.789s 12:15:43 time="2021-06-16T12:15:43Z" level=info msg="Running create [--verbose -p testprojectxzoagjx -f - create]" 12:15:43 time="2021-06-16T12:15:43Z" level=info msg="Running start [--verbose -p testprojectxzoagjx -f - start]" 12:15:44 PASS: start_test.go:9: CliSuite.TestStart 0.261s 12:15:44 time="2021-06-16T12:15:44Z" level=info msg="Running up [--verbose -p testprojectqrbbfub -f - up -d]" 12:15:44 time="2021-06-16T12:15:44Z" level=info msg="Running stop [--verbose -p testprojectqrbbfub -f - stop --timeout 0]" 12:15:54 PASS: stop_test.go:9: CliSuite.TestStop 10.409s 12:15:54 time="2021-06-16T12:15:54Z" level=info msg="Running up [--verbose -p testprojectjfpcqcd -f - up -d]" 12:15:54 time="2021-06-16T12:15:54Z" level=info msg="Running pause [--verbose -p testprojectjfpcqcd -f - pause]" 12:15:54 time="2021-06-16T12:15:54Z" level=info msg="Running unpause [--verbose -p testprojectjfpcqcd -f - unpause]" 12:15:55 PASS: pause_unpause_test.go:51: CliSuite.TestUnpause 0.302s 12:15:55 time="2021-06-16T12:15:54Z" level=info msg="Running up [--verbose -p testprojectaffdupp -f - up -d]" 12:15:55 time="2021-06-16T12:15:55Z" level=info msg="Running unpause [--verbose -p testprojectaffdupp -f - unpause]" 12:15:55 PASS: pause_unpause_test.go:76: CliSuite.TestUnpauseNotPausedService 0.272s 12:15:55 time="2021-06-16T12:15:55Z" level=info msg="Running up [--verbose -p testprojectgpjspxn -f - up -d]" 12:15:55 PASS: up_test.go:15: CliSuite.TestUp 0.259s 12:15:55 time="2021-06-16T12:15:55Z" level=info msg="Running up [--verbose -p testprojectfkcqakk -f - up -d]" 12:15:56 time="2021-06-16T12:15:55Z" level=info msg="Running up [--verbose -p testprojectfkcqakk -f - up --no-recreate -d]" 12:15:56 PASS: up_test.go:177: CliSuite.TestUpAfterImageTagDeleted 0.266s 12:15:56 PASS: up_test.go:291: CliSuite.TestUpNoBuildFailIfImageNotPresent 0.010s 12:15:56 PASS: up_test.go:299: CliSuite.TestUpNoBuildShouldWorkIfImageIsPresent 0.381s 12:15:56 time="2021-06-16T12:15:56Z" level=info msg="Running up [--verbose -p testprojectsetzieu -f - up -d]" 12:15:57 PASS: up_test.go:25: CliSuite.TestUpNotExistService 0.258s 12:15:57 time="2021-06-16T12:15:56Z" level=info msg="Running up [--verbose -p testprojectsitrmny -f - up -d]" 12:16:15 PASS: volume_test.go:79: CliSuite.TestV2Volume 15.359s 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectnjsljtu -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectnjsljtu -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojecthuvysvt -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojecthuvysvt -f - create]\n with input:\n\n test:\n image: busybox\n build: .\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectwvdrdvv -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectwvdrdvv -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:16:15 PASS: create_test.go:262: CliSuite.TestValidation 0.025s 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectkpsuhlx -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectkpsuhlx -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectezwmcqq -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectezwmcqq -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:16:15 PASS: create_test.go:297: CliSuite.TestValidationV2 0.015s 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectfjvmzdb -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectfjvmzdb -f - create]\n with input:\n\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectzjkhnqg -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectzjkhnqg -f - create]\n with input:\n\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectyujzjwv -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectyujzjwv -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v1.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectdepyiui -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectdepyiui -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v1.yml\n service: base\n\t" 12:16:15 PASS: create_test.go:327: CliSuite.TestValidationWithExtends 0.029s 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectsbtpacg -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectsbtpacg -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectiysudfm -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectiysudfm -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectvdzdbzb -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectvdzdbzb -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v2.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:16:15 time="2021-06-16T12:16:12Z" level=info msg="Running create [--verbose -p testprojectogipljl -f - create]" 12:16:15 time="2021-06-16T12:16:12Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectogipljl -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v2.yml\n service: base\n\t" 12:16:15 PASS: create_test.go:380: CliSuite.TestValidationWithExtendsV2 0.030s 12:16:15 PASS: volume_test.go:11: CliSuite.TestVolumeFromService 0.197s 12:16:15 PASS: volume_test.go:27: CliSuite.TestVolumeFromServiceWithContainerName 0.179s 12:16:15 OK: 59 passed 12:16:15 PASS 12:16:15 coverage: 33.3% of statements 12:16:15 ok github.com/docker/libcompose/integration 124.655s 12:16:15 ---> Making bundle: .integration-daemon-stop (in .) 12:16:15 ++++ cat ./docker.pid 12:16:15 +++ kill 16537 12:16:15 12:16:15 Running integration test against 17.06.0 12:16:15 ---> Making bundle: .integration-daemon-start (in .) 12:16:15 /usr/local/bin/docker-17.06.0/dockerd 12:16:15 +++ exec dockerd --debug --host unix:///go/src/github.com/docker/libcompose/docker.sock --storage-driver vfs --pidfile ./docker.pid --userland-proxy=true --graph=/var/lib/docker/17.06.0 12:16:16 Client: 12:16:16 Version: 17.06.0-ce 12:16:16 API version: 1.30 12:16:16 Go version: go1.8.3 12:16:16 Git commit: 02c1d87 12:16:16 Built: Fri Jun 23 21:15:15 2017 12:16:16 OS/Arch: linux/amd64 12:16:16 12:16:16 Server: 12:16:16 Version: 17.06.0-ce 12:16:16 API version: 1.30 (minimum version 1.12) 12:16:16 Go version: go1.8.3 12:16:16 Git commit: 02c1d87 12:16:16 Built: Fri Jun 23 21:51:55 2017 12:16:16 OS/Arch: linux/amd64 12:16:16 Experimental: false 12:16:17 time="2021-06-16T12:16:17Z" level=info msg="[0/2] [simple]: Starting " 12:16:17 time="2021-06-16T12:16:17Z" level=info msg="[0/2] [another]: Starting " 12:16:17 Pulling simple (busybox:latest)... 12:16:17 Pulling another (busybox:latest)... 12:16:17 latest: Pulling from library/busybox 12:16:17 latest: Pulling from library/busybox 12:16:17 b71f96345d44: Pulling fs layer 12:16:17 b71f96345d44: Pulling fs layer 12:16:18 b71f96345d44: Download complete 12:16:18 b71f96345d44: Download complete 12:16:18 b71f96345d44: Pull complete 12:16:18 b71f96345d44: Pull complete 12:16:18 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:16:18 Digest: sha256:930490f97e5b921535c153e0e7110d251134cc4b72bbb8133c6a5065cc68580d 12:16:18 Status: Image is up to date for busybox:latest 12:16:18 Status: Downloaded newer image for busybox:latest 12:16:18 time="2021-06-16T12:16:18Z" level=info msg="[1/2] [simple]: Started " 12:16:18 time="2021-06-16T12:16:18Z" level=info msg="[2/2] [another]: Started " 12:16:18 PASS: api_event_test.go:16: APISuite.TestEvents 0.955s 12:16:18 time="2021-06-16T12:16:18Z" level=info msg="[0/1] [service]: Starting " 12:16:19 time="2021-06-16T12:16:18Z" level=info msg="[1/1] [service]: Started " 12:16:19 PASS: api_test.go:20: APISuite.TestVolumeWithoutComposeFile 0.279s 12:16:21 PASS: build_test.go:13: CliSuite.TestBuild 2.067s 12:16:23 PASS: build_test.go:85: CliSuite.TestBuildWithArgs 1.723s 12:16:23 PASS: build_test.go:33: CliSuite.TestBuildWithNoCache1 0.116s 12:16:25 PASS: build_test.go:49: CliSuite.TestBuildWithNoCache2 1.883s 12:16:27 PASS: build_test.go:65: CliSuite.TestBuildWithNoCache3 2.000s 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="Running create [--verbose -p testprojectxpujeff -f - create]" 12:16:27 PASS: create_test.go:58: CliSuite.TestContainerName 0.098s 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="Running create [--verbose -p testprojectlazivaa -f - create]" 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="Running scale [--verbose -p testprojectlazivaa -f - scale hello=2]" 12:16:27 PASS: create_test.go:72: CliSuite.TestContainerNameWithScale 0.106s 12:16:27 PASS: env_test.go:12: CliSuite.TestCreateWithEnvInCurrentDir 0.280s 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="Running create [--verbose -p testprojectixamtck -f - create]" 12:16:27 PASS: env_test.go:38: CliSuite.TestCreateWithEnvNotInCurrentDir 0.059s 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="[0/3] [yetanother]: Creating " 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="[0/3] [another]: Creating " 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="[0/3] [simple]: Creating " 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="[0/3] [another]: Created " 12:16:27 time="2021-06-16T12:16:27Z" level=info msg="[0/3] [yetanother]: Created " 12:16:28 time="2021-06-16T12:16:27Z" level=info msg="[0/3] [simple]: Created " 12:16:28 PASS: create_test.go:241: CliSuite.TestDefaultMultipleComposeFiles 0.243s 12:16:28 time="2021-06-16T12:16:27Z" level=info msg="Running up [--verbose -p testprojectwykzzed -f - up -d]" 12:16:28 time="2021-06-16T12:16:28Z" level=info msg="Running stop [--verbose -p testprojectwykzzed -f - stop --timeout 0]" 12:16:38 time="2021-06-16T12:16:38Z" level=info msg="Running rm [--verbose -p testprojectwykzzed -f - rm --force]" 12:16:38 PASS: rm_test.go:9: CliSuite.TestDelete 10.439s 12:16:38 time="2021-06-16T12:16:38Z" level=info msg="Running up [--verbose -p testprojectxzmknsh -f - up -d]" 12:16:38 time="2021-06-16T12:16:38Z" level=info msg="Running stop [--verbose -p testprojectxzmknsh -f - stop bye --timeout 0]" 12:16:50 time="2021-06-16T12:16:48Z" level=info msg="Running rm [--verbose -p testprojectxzmknsh -f - rm --force]" 12:16:50 PASS: rm_test.go:25: CliSuite.TestDeleteOnlyRemovesStopped 10.602s 12:16:50 time="2021-06-16T12:16:49Z" level=info msg="Running up [--verbose -p testprojectanhtreo -f - up -d]" 12:16:50 time="2021-06-16T12:16:49Z" level=info msg="Running stop [--verbose -p testprojectanhtreo -f - stop --timeout 0]" 12:17:00 time="2021-06-16T12:16:59Z" level=info msg="Running rm [--verbose -p testprojectanhtreo -f - rm --force -v]" 12:17:00 PASS: rm_test.go:65: CliSuite.TestDeleteWithVol 10.421s 12:17:00 time="2021-06-16T12:16:59Z" level=info msg="Running up [--verbose -p testprojectomvsrdn -f - up -d]" 12:17:00 time="2021-06-16T12:16:59Z" level=info msg="Running down [--verbose -p testprojectomvsrdn -f - down]" 12:17:10 PASS: down_test.go:9: CliSuite.TestDown 10.407s 12:17:10 time="2021-06-16T12:17:09Z" level=info msg="Running up [--verbose -p testprojectrxbldlb -f - up -d]" 12:17:10 time="2021-06-16T12:17:10Z" level=info msg="Running scale [--verbose -p testprojectrxbldlb -f - scale hello=2]" 12:17:10 time="2021-06-16T12:17:10Z" level=info msg="Running down [--verbose -p testprojectrxbldlb -f - down]" 12:17:20 PASS: down_test.go:24: CliSuite.TestDownMultiple 10.775s 12:17:20 time="2021-06-16T12:17:20Z" level=info msg="Running create [--verbose -p testprojectdoaciwm -f - create]" 12:17:20 PASS: create_test.go:31: CliSuite.TestEmptyEntrypoint 0.097s 12:17:20 time="2021-06-16T12:17:20Z" level=info msg="Running create [--verbose -p testprojectuacoatu -f - create]" 12:17:21 time="2021-06-16T12:17:20Z" level=info msg="Running create [--verbose -p testprojectgkfviuw -f - create]" 12:17:21 PASS: create_test.go:146: CliSuite.TestFieldTypeConversions 0.114s 12:17:21 time="2021-06-16T12:17:20Z" level=info msg="Running create [--verbose -p testprojectrvbkgdk -f - create]" 12:17:21 PASS: create_test.go:14: CliSuite.TestFields 0.065s 12:17:21 time="2021-06-16T12:17:20Z" level=info msg="Running create [--verbose -p testprojectpigaqdy -f - create]" 12:17:21 PASS: create_test.go:45: CliSuite.TestHelloWorld 0.057s 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running create [--verbose -p testprojectdbrsopc -f - create]" 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running create [--verbose -p testprojectwwpnbyo -f - create]" 12:17:21 PASS: create_test.go:86: CliSuite.TestInterpolation 0.115s 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running create [--verbose -p testprojectzclxwjt -f - create]" 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running create [--verbose -p testprojectbkxyszq -f - create]" 12:17:21 PASS: create_test.go:112: CliSuite.TestInterpolationWithExtends 0.128s 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running up [--verbose -p testprojectxtuxdzx -f - up -d]" 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running kill [--verbose -p testprojectxtuxdzx -f - kill]" 12:17:21 PASS: kill_test.go:9: CliSuite.TestKill 0.392s 12:17:21 time="2021-06-16T12:17:21Z" level=info msg="Running up [--verbose -p testprojectzhiwsxt -f - up -d]" 12:17:22 PASS: up_test.go:260: CliSuite.TestLink 0.528s 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [another]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [yetanother]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [multiple]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [simple]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [simple]: Created " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [yetanother]: Created " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [another]: Created " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [multiple]: Created " 12:17:22 PASS: create_test.go:176: CliSuite.TestMultipleComposeFilesOneTwo 0.327s 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [multiple]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [simple]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [yetanother]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [another]: Creating " 12:17:22 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [another]: Created " 12:17:23 time="2021-06-16T12:17:22Z" level=info msg="[0/4] [multiple]: Created " 12:17:23 time="2021-06-16T12:17:23Z" level=info msg="[0/4] [yetanother]: Created " 12:17:23 time="2021-06-16T12:17:23Z" level=info msg="[0/4] [simple]: Created " 12:17:23 PASS: create_test.go:204: CliSuite.TestMultipleComposeFilesTwoOne 0.274s 12:17:23 time="2021-06-16T12:17:23Z" level=info msg="Running up [--verbose -p testprojectwqfgals -f - up -d]" 12:17:23 PASS: volume_test.go:62: CliSuite.TestNamedVolume 0.271s 12:17:23 time="2021-06-16T12:17:23Z" level=info msg="Running up [--verbose -p testprojectwtmhqgp -f - up -d]" 12:17:24 time="2021-06-16T12:17:23Z" level=info msg="Running pause [--verbose -p testprojectwtmhqgp -f - pause]" 12:17:24 PASS: pause_unpause_test.go:9: CliSuite.TestPause 0.300s 12:17:24 time="2021-06-16T12:17:24Z" level=info msg="Running up [--verbose -p testprojectowffigh -f - up -d]" 12:17:24 time="2021-06-16T12:17:24Z" level=info msg="Running pause [--verbose -p testprojectowffigh -f - pause]" 12:17:24 time="2021-06-16T12:17:24Z" level=info msg="Running pause [--verbose -p testprojectowffigh -f - pause]" 12:17:24 PASS: pause_unpause_test.go:26: CliSuite.TestPauseAlreadyPausedService 0.299s 12:17:24 time="2021-06-16T12:17:24Z" level=info msg="Running up [--verbose -p testprojectmvrbhub -f - up -d]" 12:17:24 time="2021-06-16T12:17:24Z" level=info msg="Running ps [--verbose -p testprojectmvrbhub -f - ps]" 12:17:25 PASS: ps_test.go:10: CliSuite.TestPs 0.287s 12:17:25 time="2021-06-16T12:17:24Z" level=info msg="Running up [--verbose -p testprojectwdyhcpc -f - up -d]" 12:17:25 time="2021-06-16T12:17:25Z" level=info msg="Running ps [--verbose -p testprojectwdyhcpc -f - ps -q]" 12:17:25 PASS: ps_test.go:22: CliSuite.TestPsQuiet 0.290s 12:17:25 time="2021-06-16T12:17:25Z" level=info msg="Running pull [--verbose -p testprojectxrntkwx -f - pull]" 12:17:25 PASS: pull_test.go:7: CliSuite.TestPull 0.077s 12:17:25 time="2021-06-16T12:17:25Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up -d]" 12:17:25 time="2021-06-16T12:17:25Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up -d]" 12:17:25 time="2021-06-16T12:17:25Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up -d]" 12:17:26 time="2021-06-16T12:17:26Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up -d]" 12:17:26 time="2021-06-16T12:17:26Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up -d]" 12:17:26 time="2021-06-16T12:17:26Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up --force-recreate -d]" 12:17:27 time="2021-06-16T12:17:26Z" level=info msg="Running up [--verbose -p testprojectqmdfodc -f - up --force-recreate -d]" 12:17:27 PASS: up_test.go:105: CliSuite.TestRecreate 1.969s 12:17:27 time="2021-06-16T12:17:27Z" level=info msg="Running up [--verbose -p testprojectbpqycwq -f - up -d]" 12:17:28 time="2021-06-16T12:17:27Z" level=info msg="Running up [--verbose -p testprojectbpqycwq -f - up --force-recreate -d]" 12:17:28 PASS: up_test.go:33: CliSuite.TestRecreateForceRecreate 0.709s 12:17:28 time="2021-06-16T12:17:28Z" level=info msg="Running up [--verbose -p testprojectwxmmktb -f - up -d]" 12:17:29 time="2021-06-16T12:17:29Z" level=info msg="Running up [--verbose -p testprojectwxmmktb -f - up --no-recreate -d]" 12:17:29 time="2021-06-16T12:17:29Z" level=info msg="Running up [--verbose -p testprojectwxmmktb -f - up --no-recreate -d]" 12:17:29 time="2021-06-16T12:17:29Z" level=info msg="Running up [--verbose -p testprojectwxmmktb -f - up -d]" 12:17:29 time="2021-06-16T12:17:29Z" level=info msg="Running rm [--verbose -p testprojectwxmmktb -f - rm -f]" 12:17:29 PASS: up_test.go:207: CliSuite.TestRecreateImageChanging 1.152s 12:17:29 time="2021-06-16T12:17:29Z" level=info msg="Running up [--verbose -p testprojectyrkrkvr -f - up -d]" 12:17:30 time="2021-06-16T12:17:29Z" level=info msg="Running up [--verbose -p testprojectyrkrkvr -f - up --no-recreate -d]" 12:17:30 PASS: up_test.go:84: CliSuite.TestRecreateNoRecreate 0.280s 12:17:30 time="2021-06-16T12:17:30Z" level=info msg="Running up [--verbose -p testprojectvhvozra -f - up -d]" 12:17:30 time="2021-06-16T12:17:30Z" level=info msg="Running up [--verbose -p testprojectvhvozra -f - up --force-recreate -d]" 12:17:31 PASS: up_test.go:53: CliSuite.TestRecreateVols 0.701s 12:17:31 time="2021-06-16T12:17:30Z" level=info msg="Running up [--verbose -p testprojectbwujjej -f - up -d]" 12:17:31 PASS: volume_test.go:43: CliSuite.TestRelativeVolume 0.290s 12:17:31 time="2021-06-16T12:17:31Z" level=info msg="Running up [--verbose -p testprojectsjtvrua -f - up -d]" 12:17:31 time="2021-06-16T12:17:31Z" level=info msg="Running restart [--verbose -p testprojectsjtvrua -f - restart --timeout 0]" 12:17:43 PASS: restart_test.go:9: CliSuite.TestRestart 10.567s 12:17:43 PASS: run_test.go:19: CliSuite.TestRun 0.387s 12:17:43 time="2021-06-16T12:17:42Z" level=info msg="Running up [--verbose -p testprojectlaezert -f - up -d]" 12:17:43 time="2021-06-16T12:17:42Z" level=info msg="Running scale [--verbose -p testprojectlaezert -f - scale hello=2]" 12:17:43 time="2021-06-16T12:17:42Z" level=info msg="Running scale [--verbose -p testprojectlaezert -f - scale --timeout 0 hello=1]" 12:17:53 PASS: scale_test.go:10: CliSuite.TestScale 10.710s 12:17:53 time="2021-06-16T12:17:53Z" level=info msg="Running up [--verbose -p testprojectyblrqmb -f - up -d]" 12:17:53 time="2021-06-16T12:17:53Z" level=info msg="Running scale [--verbose -p testprojectyblrqmb -f - scale test=2]" 12:17:54 PASS: scale_test.go:46: CliSuite.TestScaleWithHostPortWarning 0.711s 12:17:54 time="2021-06-16T12:17:54Z" level=info msg="Running create [--verbose -p testprojectbzyihif -f - create]" 12:17:54 time="2021-06-16T12:17:54Z" level=info msg="Running start [--verbose -p testprojectbzyihif -f - start]" 12:17:54 PASS: start_test.go:9: CliSuite.TestStart 0.289s 12:17:54 time="2021-06-16T12:17:54Z" level=info msg="Running up [--verbose -p testprojectgxwvypf -f - up -d]" 12:17:54 time="2021-06-16T12:17:54Z" level=info msg="Running stop [--verbose -p testprojectgxwvypf -f - stop --timeout 0]" 12:18:07 PASS: stop_test.go:9: CliSuite.TestStop 10.394s 12:18:07 time="2021-06-16T12:18:04Z" level=info msg="Running up [--verbose -p testprojectooweccr -f - up -d]" 12:18:07 time="2021-06-16T12:18:05Z" level=info msg="Running pause [--verbose -p testprojectooweccr -f - pause]" 12:18:07 time="2021-06-16T12:18:05Z" level=info msg="Running unpause [--verbose -p testprojectooweccr -f - unpause]" 12:18:07 PASS: pause_unpause_test.go:51: CliSuite.TestUnpause 0.322s 12:18:07 time="2021-06-16T12:18:05Z" level=info msg="Running up [--verbose -p testprojectttidwtg -f - up -d]" 12:18:07 time="2021-06-16T12:18:05Z" level=info msg="Running unpause [--verbose -p testprojectttidwtg -f - unpause]" 12:18:07 PASS: pause_unpause_test.go:76: CliSuite.TestUnpauseNotPausedService 0.275s 12:18:07 time="2021-06-16T12:18:05Z" level=info msg="Running up [--verbose -p testprojectcznugmz -f - up -d]" 12:18:07 PASS: up_test.go:15: CliSuite.TestUp 0.266s 12:18:07 time="2021-06-16T12:18:06Z" level=info msg="Running up [--verbose -p testprojectobhrczu -f - up -d]" 12:18:07 time="2021-06-16T12:18:06Z" level=info msg="Running up [--verbose -p testprojectobhrczu -f - up --no-recreate -d]" 12:18:07 PASS: up_test.go:177: CliSuite.TestUpAfterImageTagDeleted 0.297s 12:18:07 PASS: up_test.go:291: CliSuite.TestUpNoBuildFailIfImageNotPresent 0.012s 12:18:07 PASS: up_test.go:299: CliSuite.TestUpNoBuildShouldWorkIfImageIsPresent 0.406s 12:18:07 time="2021-06-16T12:18:07Z" level=info msg="Running up [--verbose -p testprojectxarrmfk -f - up -d]" 12:18:07 PASS: up_test.go:25: CliSuite.TestUpNotExistService 0.270s 12:18:07 time="2021-06-16T12:18:07Z" level=info msg="Running up [--verbose -p testprojectgiuxyeg -f - up -d]" 12:18:25 PASS: volume_test.go:79: CliSuite.TestV2Volume 15.372s 12:18:25 time="2021-06-16T12:18:22Z" level=info msg="Running create [--verbose -p testprojectqaefdwn -f - create]" 12:18:25 time="2021-06-16T12:18:22Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectqaefdwn -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n\t" 12:18:25 time="2021-06-16T12:18:22Z" level=info msg="Running create [--verbose -p testprojectahdznqi -f - create]" 12:18:25 time="2021-06-16T12:18:22Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectahdznqi -f - create]\n with input:\n\n test:\n image: busybox\n build: .\n\t" 12:18:25 time="2021-06-16T12:18:22Z" level=info msg="Running create [--verbose -p testprojectbmjwkjy -f - create]" 12:18:25 time="2021-06-16T12:18:22Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectbmjwkjy -f - create]\n with input:\n\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:18:25 PASS: create_test.go:262: CliSuite.TestValidation 0.026s 12:18:25 time="2021-06-16T12:18:22Z" level=info msg="Running create [--verbose -p testprojectowhhdpu -f - create]" 12:18:25 time="2021-06-16T12:18:22Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectowhhdpu -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n\t" 12:18:25 time="2021-06-16T12:18:22Z" level=info msg="Running create [--verbose -p testprojectyesywbm -f - create]" 12:18:25 time="2021-06-16T12:18:22Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectyesywbm -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n image: busybox\n ports: invalid_type\n links: invalid_type\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n " 12:18:25 PASS: create_test.go:297: CliSuite.TestValidationV2 0.014s 12:18:25 time="2021-06-16T12:18:22Z" level=info msg="Running create [--verbose -p testprojectoawywgc -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectoawywgc -f - create]\n with input:\n\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectlsyxoso -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectlsyxoso -f - create]\n with input:\n\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectlduahfn -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectlduahfn -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v1.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectrpihful -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectrpihful -f - create]\n with input:\n\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v1.yml\n service: base\n\t" 12:18:25 PASS: create_test.go:327: CliSuite.TestValidationWithExtends 0.028s 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectkqekpwy -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectkqekpwy -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n privilege: \"something\"\n test:\n extends:\n service: base\n\t" 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectqsjpuxw -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectqsjpuxw -f - create]\n with input:\n\nversion: '2'\nservices:\n base:\n image: busybox\n test:\n extends:\n service: base\n links: invalid_type\n\t" 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectndfcbas -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectndfcbas -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/valid/docker-compose.v2.yml\n service: base\n devices:\n - /dev/foo:/dev/foo\n - /dev/foo:/dev/foo\n\t" 12:18:25 time="2021-06-16T12:18:23Z" level=info msg="Running create [--verbose -p testprojectfrnjaug -f - create]" 12:18:25 time="2021-06-16T12:18:23Z" level=error msg="Failed to run ../bundles/libcompose-cli exit status 1: [--verbose -p testprojectfrnjaug -f - create]\n with input:\n\nversion: '2'\nservices:\n test:\n extends:\n file: ./assets/validation/invalid/docker-compose.v2.yml\n service: base\n\t" 12:18:25 PASS: create_test.go:380: CliSuite.TestValidationWithExtendsV2 0.030s 12:18:25 PASS: volume_test.go:11: CliSuite.TestVolumeFromService 0.178s 12:18:25 PASS: volume_test.go:27: CliSuite.TestVolumeFromServiceWithContainerName 0.191s 12:18:25 OK: 59 passed 12:18:25 PASS 12:18:25 coverage: 33.3% of statements 12:18:25 ok github.com/docker/libcompose/integration 125.767s 12:18:25 ---> Making bundle: .integration-daemon-stop (in .) 12:18:25 ++++ cat ./docker.pid 12:18:25 +++ kill 21068 12:18:25 12:18:25 12:18:25 ---> Making bundle: test-acceptance (in .) 12:18:25 ---> Making bundle: .integration-daemon-start (in .) 12:18:25 /usr/local/bin/docker-17.03.2/dockerd 12:18:25 +++ exec dockerd --debug --host unix:///go/src/github.com/docker/libcompose/docker.sock --storage-driver vfs --pidfile ./docker.pid --userland-proxy=true --graph=/var/lib/docker/17.03.2 12:18:26 Client: 12:18:26 Version: 17.03.2-ce 12:18:26 API version: 1.27 12:18:26 Go version: go1.7.5 12:18:26 Git commit: f5ec1e2 12:18:26 Built: Tue Jun 27 01:35:00 2017 12:18:26 OS/Arch: linux/amd64 12:18:26 12:18:26 Server: 12:18:26 Version: 17.03.2-ce 12:18:26 API version: 1.27 (minimum version 1.12) 12:18:26 Go version: go1.7.5 12:18:26 Git commit: f5ec1e2 12:18:26 Built: Tue Jun 27 01:35:00 2017 12:18:26 OS/Arch: linux/amd64 12:18:26 Experimental: false 12:18:26 libcompose-cli version 0.4.0 (2a046c0) 12:18:26 Traceback (most recent call last): 12:18:26 File "/go/src/github.com/docker/libcompose/venv/bin/py.test", line 5, in <module> 12:18:26 from pytest import main 12:18:26 File "/go/src/github.com/docker/libcompose/venv/lib/python2.7/site-packages/pytest.py", line 14, in <module> 12:18:26 from _pytest.config import main, UsageError, _preloadplugins, cmdline 12:18:26 File "/go/src/github.com/docker/libcompose/venv/lib/python2.7/site-packages/_pytest/config.py", line 12, in <module> 12:18:26 from _pytest.core import PluginManager 12:18:26 File "/go/src/github.com/docker/libcompose/venv/lib/python2.7/site-packages/_pytest/core.py", line 11, in <module> 12:18:26 "%s is too old, remove or upgrade 'py'" % (py.__version__)) 12:18:26 AssertionError: installation problem: 1.10.0 is too old, remove or upgrade 'py' 12:18:26 /go/src/github.com/docker/libcompose 12:18:26 ---> Making bundle: .integration-daemon-stop (in .) 12:18:26 ++++ cat ./docker.pid 12:18:26 +++ kill 25956 12:18:27 [Pipeline] } [Pipeline] // timeout [Pipeline] echo 12:18:28 chowning workspace [Pipeline] sh 12:18:29 + pwd 12:18:29 + id -u 12:18:29 + id -g 12:18:29 + docker run --rm -v /home/ubuntu/workspace/libcompose_master:/workspace busybox chown -R 1000:1000 /workspace [Pipeline] } [Pipeline] // withEnv [Pipeline] stage 12:18:29 Using the ‘stage’ step without a block argument is deprecated 12:18:29 Entering stage build 12:18:29 Proceeding [Pipeline] withEnv [Pipeline] { [Pipeline] timeout 12:18:29 Timeout set to expire in 1 hr 0 min [Pipeline] { [Pipeline] sh 12:18:30 + make -e cross-binary 12:18:30 docker build -t "dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1" . 12:18:30 Sending build context to Docker daemon 6.576MB 12:18:30 Step 1/14 : FROM golang:1.12.7 12:18:30 ---> be63d15101cb 12:18:30 Step 2/14 : RUN apt-get update && apt-get install -y iptables build-essential --no-install-recommends && apt-get install -y python-pip && pip install virtualenv 12:18:30 ---> Using cache 12:18:30 ---> ac32a6af0bc1 12:18:30 Step 3/14 : RUN GO111MODULE=off go get github.com/aktau/github-release && GO111MODULE=off go get golang.org/x/tools/cmd/cover && GO111MODULE=off go get golang.org/x/lint/golint 12:18:30 ---> Using cache 12:18:30 ---> 0197d7e822eb 12:18:30 Step 4/14 : ENV DOCKER_VERSIONS 1.9.1 1.10.3 1.13.1 17.03.2 17.06.0 12:18:30 ---> Using cache 12:18:30 ---> 0c5c284b6af9 12:18:30 Step 5/14 : ENV DEFAULT_DOCKER_VERSION 17.03.2 12:18:30 ---> Using cache 12:18:30 ---> 0d94c7d587b0 12:18:30 Step 6/14 : RUN set -e; set -x; for v in $(echo ${DOCKER_VERSIONS} | cut -f1); do if test "${v}" = "1.9.1" || test "${v}" = "1.10.3"; then mkdir -p /usr/local/bin/docker-${v}/; curl https://get.docker.com/builds/Linux/x86_64/docker-${v} -o /usr/local/bin/docker-${v}/docker; chmod +x /usr/local/bin/docker-${v}/docker; elif test "${v}" = "1.13.1"; then curl https://get.docker.com/builds/Linux/x86_64/docker-${v}.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; else curl https://download.docker.com/linux/static/stable/x86_64/docker-${v}-ce.tgz -o docker-${v}.tgz; tar xzf docker-${v}.tgz -C /usr/local/bin/; mv /usr/local/bin/docker /usr/local/bin/docker-${v}; rm docker-${v}.tgz; fi done 12:18:30 ---> Using cache 12:18:30 ---> 5de95b94ca80 12:18:30 Step 7/14 : RUN ln -s /usr/local/bin/docker-${DEFAULT_DOCKER_VERSION} /usr/local/bin/docker 12:18:30 ---> Using cache 12:18:30 ---> f3720aca4edd 12:18:30 Step 8/14 : WORKDIR /go/src/github.com/docker/libcompose 12:18:30 ---> Using cache 12:18:30 ---> cc78b7416318 12:18:30 Step 9/14 : ENV COMPOSE_COMMIT 1.9.0 12:18:30 ---> Using cache 12:18:30 ---> 5a43dd4377d3 12:18:30 Step 10/14 : RUN virtualenv venv && git clone https://github.com/docker/compose.git venv/compose && cd venv/compose && git checkout -q "$COMPOSE_COMMIT" && ../bin/pip install -r requirements.txt -r requirements-dev.txt 12:18:30 ---> Using cache 12:18:30 ---> 2e25effa23dd 12:18:30 Step 11/14 : ENV COMPOSE_BINARY /go/src/github.com/docker/libcompose/libcompose-cli 12:18:30 ---> Using cache 12:18:30 ---> 74a69ff8454c 12:18:30 Step 12/14 : ENV USER root 12:18:30 ---> Using cache 12:18:30 ---> aae04cb7e5c1 12:18:30 Step 13/14 : ENTRYPOINT ["hack/dind"] 12:18:30 ---> Using cache 12:18:30 ---> 442fdfff171c 12:18:30 Step 14/14 : COPY . /go/src/github.com/docker/libcompose 12:18:30 ---> Using cache 12:18:30 ---> a855da89c5b5 12:18:30 Successfully built a855da89c5b5 12:18:30 Successfully tagged dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 12:18:30 docker run --rm -i --privileged -e DAEMON_VERSION=""default"" -e OS_PLATFORM_ARG -e OS_ARCH_ARG -e DOCKER_TEST_HOST -e TESTDIRS -e TESTFLAGS -e SHOWWARNING -e TESTVERBOSE -v "/home/ubuntu/workspace/libcompose_master/bundles:/go/src/github.com/docker/libcompose/bundles" "dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1" ./hack/make.sh cross-binary 12:18:30 ---> Making bundle: cross-binary (in .) 12:18:30 Building binary for linux/386... 12:18:30 go: finding github.com/Microsoft/hcsshim v0.8.6 12:18:30 go: finding github.com/Azure/go-ansiterm v0.0.0-20170929234023-d6e3b3328b78 12:18:30 go: finding github.com/flynn/go-shlex v0.0.0-20150515145356-3f9db97f8568 12:18:30 go: finding github.com/docker/docker-credential-helpers v0.6.3 12:18:30 go: finding github.com/modern-go/reflect2 v1.0.1 12:18:30 go: finding github.com/docker/go-metrics v0.0.0-20181218153428-b84716841b82 12:18:30 go: finding github.com/prometheus/client_golang v1.1.0 12:18:30 go: finding github.com/gorilla/mux v0.0.0-20160317213430-0eeaf8392f5b 12:18:30 go: finding github.com/docker/cli v0.0.0-20190711175710-5b38d82aa076 12:18:31 go: finding gotest.tools v2.2.0+incompatible 12:18:31 go: finding github.com/docker/go-units v0.4.0 12:18:31 go: finding github.com/gogo/protobuf v1.1.1 12:18:31 go: finding github.com/opencontainers/go-digest v0.0.0-20170106003457-a6d0ee40d420 12:18:31 go: finding github.com/docker/engine v0.0.0-20190725163905-fa8dd90ceb7b 12:18:31 go: finding github.com/opencontainers/image-spec v0.0.0-20170515205857-f03dbe35d449 12:18:31 go: finding github.com/kr/pty v0.0.0-20150511174710-5cf931ef8f76 12:18:31 go: finding golang.org/x/time v0.0.0-20190308202827-9d24e82272b4 12:18:31 go: finding google.golang.org/grpc v1.22.1 12:18:31 go: finding github.com/prometheus/common v0.6.0 12:18:32 go: finding github.com/gorilla/context v1.1.1 12:18:32 go: finding github.com/json-iterator/go v1.1.7 12:18:32 go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 12:18:32 go: finding github.com/docker/libtrust v0.0.0-20160708172513-aabc10ec26b7 12:18:32 go: finding github.com/docker/distribution v2.7.1+incompatible 12:18:32 go: finding github.com/urfave/cli v1.21.0 12:18:32 go: finding golang.org/x/sys v0.0.0-20190215142949-d0b11bdaac8a 12:18:32 go: finding github.com/golang/protobuf v1.2.0 12:18:33 go: finding gopkg.in/yaml.v2 v2.2.1 12:18:33 go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 12:18:33 go: finding github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f 12:18:33 go: finding github.com/google/gofuzz v1.0.0 12:18:33 go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b 12:18:34 go: finding google.golang.org/appengine v1.1.0 12:18:34 go: finding github.com/client9/misspell v0.3.4 12:18:34 go: finding github.com/modern-go/concurrent v0.0.0-20180228061459-e0a39a4cb421 12:18:34 go: finding golang.org/x/net v0.0.0-20190613194153-d28f0bde5980 12:18:34 go: finding github.com/davecgh/go-spew v1.1.1 12:18:34 go: finding github.com/stretchr/testify v1.3.0 12:18:35 go: finding github.com/morikuni/aec v0.0.0-20170113033406-39771216ff4c 12:18:35 go: finding github.com/Microsoft/go-winio v0.3.8 12:18:35 go: finding github.com/docker/go-connections v0.3.0 12:18:35 go: finding github.com/go-kit/kit v0.8.0 12:18:35 go: finding github.com/google/go-cmp v0.2.0 12:18:35 go: finding github.com/BurntSushi/toml v0.3.1 12:18:35 go: finding golang.org/x/tools v0.0.0-20190524140312-2c0ae7006135 12:18:35 go: finding gopkg.in/yaml.v2 v2.2.2 12:18:35 go: finding github.com/beorn7/perks v1.0.1 12:18:36 go: finding github.com/xeipuuv/gojsonschema v1.1.0 12:18:36 go: finding github.com/sirupsen/logrus v1.2.0 12:18:36 go: finding cloud.google.com/go v0.26.0 12:18:36 go: finding github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415 12:18:36 go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd 12:18:36 go: finding github.com/pkg/errors v0.8.0 12:18:36 go: finding golang.org/x/lint v0.0.0-20190313153728-d0100b6bd8b3 12:18:36 go: finding golang.org/x/oauth2 v0.0.0-20180821212333-d2e6202438be 12:18:37 go: finding github.com/stretchr/testify v1.2.2 12:18:37 go: finding golang.org/x/sys v0.0.0-20180905080454-ebe1bf3edb33 12:18:37 go: finding github.com/golang/mock v1.1.1 12:18:37 go: finding golang.org/x/net v0.0.0-20190311183353-d8887717615a 12:18:37 go: finding honnef.co/go/tools v0.0.0-20190523083050-ea95bdfd59fc 12:18:38 go: finding github.com/stretchr/objx v0.1.0 12:18:38 go: finding github.com/go-logfmt/logfmt v0.4.0 12:18:38 go: finding github.com/containerd/continuity v0.0.0-20190426062206-aaeac12a7ffc 12:18:38 go: finding google.golang.org/genproto v0.0.0-20180817151627-c66870c02cf8 12:18:39 go: finding golang.org/x/sys v0.0.0-20190801041406-cbf593c0f2f3 12:18:39 go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 12:18:39 go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 12:18:39 go: finding golang.org/x/text v0.3.0 12:18:40 go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 12:18:40 go: finding github.com/davecgh/go-spew v1.1.0 12:18:40 go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 12:18:41 go: finding github.com/pmezard/go-difflib v1.0.0 12:18:41 go: finding github.com/prometheus/client_golang v1.0.0 12:18:41 go: finding github.com/golang/protobuf v1.3.1 12:18:41 go: finding golang.org/x/crypto v0.0.0-20190308221718-c2843e01d9a2 12:18:41 go: finding github.com/julienschmidt/httprouter v1.2.0 12:18:41 go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 12:18:41 go: finding github.com/stretchr/objx v0.1.1 12:18:41 go: finding golang.org/x/tools v0.0.0-20190311212946-11955173bddd 12:18:41 go: finding github.com/golang/protobuf v1.3.2 12:18:42 go: finding github.com/prometheus/common v0.4.1 12:18:42 go: finding github.com/json-iterator/go v1.1.6 12:18:42 go: finding github.com/beorn7/perks v1.0.0 12:18:42 go: finding golang.org/x/crypto v0.0.0-20180904163835-0709b304e793 12:18:42 go: finding github.com/prometheus/procfs v0.0.3 12:18:42 go: finding golang.org/x/net v0.0.0-20181114220301-adae6a3d119a 12:18:42 go: finding github.com/prometheus/procfs v0.0.0-20181005140218-185b4288413d 12:18:42 go: finding github.com/prometheus/procfs v0.0.2 12:18:42 go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 12:18:42 go: finding golang.org/x/sync v0.0.0-20181108010431-42b317875d0f 12:18:43 go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 12:18:43 go: finding github.com/opencontainers/runc v0.0.0-20161109192122-51371867a01c 12:18:43 go: finding github.com/prometheus/client_golang v0.9.1 12:18:43 go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf 12:18:44 go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc 12:18:44 go: finding golang.org/x/sync v0.0.0-20181221193216-37e7f081c4d4 12:18:44 go: finding github.com/prometheus/client_model v0.0.0-20180712105110-5c3871d89910 12:18:44 go: finding github.com/google/go-cmp v0.3.0 12:18:44 go: finding github.com/go-logfmt/logfmt v0.3.0 12:18:44 go: finding github.com/go-stack/stack v1.8.0 12:18:45 go: finding golang.org/x/sys v0.0.0-20181116152217-5ac8a444bdc5 12:18:49 go: finding golang.org/x/sync v0.0.0-20190423024810-112230192c58 12:18:53 go: downloading golang.org/x/net v0.0.0-20190613194153-d28f0bde5980 12:18:53 go: downloading gopkg.in/yaml.v2 v2.2.2 12:18:53 go: downloading github.com/urfave/cli v1.21.0 12:18:53 go: downloading github.com/docker/go-connections v0.3.0 12:18:53 go: downloading github.com/docker/engine v0.0.0-20190725163905-fa8dd90ceb7b 12:18:53 go: downloading github.com/docker/cli v0.0.0-20190711175710-5b38d82aa076 12:18:53 go: downloading github.com/docker/go-units v0.4.0 12:18:53 go: downloading github.com/sirupsen/logrus v1.2.0 12:18:53 go: extracting github.com/docker/go-units v0.4.0 12:18:53 go: extracting github.com/docker/go-connections v0.3.0 12:18:53 go: extracting github.com/urfave/cli v1.21.0 12:18:53 go: extracting gopkg.in/yaml.v2 v2.2.2 12:18:53 go: extracting github.com/sirupsen/logrus v1.2.0 12:18:54 go: extracting golang.org/x/net v0.0.0-20190613194153-d28f0bde5980 12:18:55 go: extracting github.com/docker/cli v0.0.0-20190711175710-5b38d82aa076 12:18:55 go: downloading github.com/flynn/go-shlex v0.0.0-20150515145356-3f9db97f8568 12:18:55 go: extracting github.com/flynn/go-shlex v0.0.0-20150515145356-3f9db97f8568 12:18:55 go: downloading golang.org/x/crypto v0.0.0-20190308221718-c2843e01d9a2 12:18:56 go: extracting golang.org/x/crypto v0.0.0-20190308221718-c2843e01d9a2 12:18:56 go: extracting github.com/docker/engine v0.0.0-20190725163905-fa8dd90ceb7b 12:18:56 go: downloading github.com/xeipuuv/gojsonschema v1.1.0 12:18:56 go: downloading github.com/docker/docker-credential-helpers v0.6.3 12:18:56 go: downloading golang.org/x/sys v0.0.0-20190801041406-cbf593c0f2f3 12:18:56 go: downloading github.com/opencontainers/runc v0.0.0-20161109192122-51371867a01c 12:18:56 go: downloading github.com/docker/distribution v2.7.1+incompatible 12:18:56 go: downloading github.com/gogo/protobuf v1.1.1 12:18:56 go: extracting github.com/docker/docker-credential-helpers v0.6.3 12:18:56 go: downloading google.golang.org/grpc v1.22.1 12:18:56 go: downloading github.com/containerd/continuity v0.0.0-20190426062206-aaeac12a7ffc 12:18:57 go: extracting github.com/xeipuuv/gojsonschema v1.1.0 12:18:57 go: downloading github.com/morikuni/aec v0.0.0-20170113033406-39771216ff4c 12:18:57 go: extracting github.com/morikuni/aec v0.0.0-20170113033406-39771216ff4c 12:18:58 go: extracting github.com/containerd/continuity v0.0.0-20190426062206-aaeac12a7ffc 12:18:58 go: downloading github.com/opencontainers/image-spec v0.0.0-20170515205857-f03dbe35d449 12:18:58 go: extracting github.com/opencontainers/runc v0.0.0-20161109192122-51371867a01c 12:18:58 go: extracting github.com/opencontainers/image-spec v0.0.0-20170515205857-f03dbe35d449 12:18:58 go: downloading github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415 12:18:58 go: extracting github.com/xeipuuv/gojsonreference v0.0.0-20180127040603-bd5ef7bd5415 12:18:58 go: downloading github.com/opencontainers/go-digest v0.0.0-20170106003457-a6d0ee40d420 12:18:58 go: downloading golang.org/x/sync v0.0.0-20190423024810-112230192c58 12:18:58 go: downloading github.com/pkg/errors v0.8.0 12:18:58 go: extracting github.com/opencontainers/go-digest v0.0.0-20170106003457-a6d0ee40d420 12:18:58 go: extracting golang.org/x/sync v0.0.0-20190423024810-112230192c58 12:18:58 go: extracting github.com/pkg/errors v0.8.0 12:18:58 go: downloading golang.org/x/time v0.0.0-20190308202827-9d24e82272b4 12:18:58 go: extracting golang.org/x/time v0.0.0-20190308202827-9d24e82272b4 12:18:58 go: downloading github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f 12:18:58 go: extracting google.golang.org/grpc v1.22.1 12:18:58 go: extracting github.com/xeipuuv/gojsonpointer v0.0.0-20180127040702-4e3ac2762d5f 12:18:58 go: extracting github.com/docker/distribution v2.7.1+incompatible 12:18:58 go: downloading github.com/golang/protobuf v1.3.2 12:18:58 go: downloading google.golang.org/genproto v0.0.0-20180817151627-c66870c02cf8 12:18:58 go: downloading github.com/gorilla/mux v0.0.0-20160317213430-0eeaf8392f5b 12:18:58 go: downloading github.com/docker/go-metrics v0.0.0-20181218153428-b84716841b82 12:18:58 go: extracting github.com/gorilla/mux v0.0.0-20160317213430-0eeaf8392f5b 12:18:58 go: downloading github.com/gorilla/context v1.1.1 12:18:58 go: extracting github.com/docker/go-metrics v0.0.0-20181218153428-b84716841b82 12:18:58 go: extracting github.com/gorilla/context v1.1.1 12:18:58 go: downloading github.com/prometheus/client_golang v1.1.0 12:18:59 go: extracting github.com/prometheus/client_golang v1.1.0 12:18:59 go: downloading github.com/prometheus/common v0.6.0 12:18:59 go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 12:18:59 go: downloading github.com/beorn7/perks v1.0.1 12:18:59 go: downloading github.com/prometheus/procfs v0.0.3 12:18:59 go: extracting github.com/golang/protobuf v1.3.2 12:18:59 go: extracting github.com/beorn7/perks v1.0.1 12:18:59 go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 12:18:59 go: extracting github.com/prometheus/common v0.6.0 12:18:59 go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 12:18:59 go: extracting github.com/prometheus/procfs v0.0.3 12:18:59 go: extracting golang.org/x/sys v0.0.0-20190801041406-cbf593c0f2f3 12:18:59 go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 12:19:00 go: extracting github.com/gogo/protobuf v1.1.1 12:19:00 go: extracting google.golang.org/genproto v0.0.0-20180817151627-c66870c02cf8 12:19:27 Building binary for linux/amd64... 12:19:42 Building binary for linux/arm... 12:20:08 Building binary for windows/386... 12:20:08 go: downloading github.com/konsorten/go-windows-terminal-sequences v1.0.1 12:20:08 go: downloading github.com/Microsoft/go-winio v0.3.8 12:20:08 go: downloading github.com/Azure/go-ansiterm v0.0.0-20170929234023-d6e3b3328b78 12:20:08 go: downloading github.com/Microsoft/hcsshim v0.8.6 12:20:08 go: extracting github.com/konsorten/go-windows-terminal-sequences v1.0.1 12:20:08 go: extracting github.com/Azure/go-ansiterm v0.0.0-20170929234023-d6e3b3328b78 12:20:08 go: extracting github.com/Microsoft/go-winio v0.3.8 12:20:08 go: extracting github.com/Microsoft/hcsshim v0.8.6 12:20:35 Building binary for windows/amd64... 12:21:01 Building binary for darwin/386... 12:21:25 Building binary for darwin/amd64... 12:21:47 [Pipeline] } [Pipeline] // timeout [Pipeline] echo 12:21:48 chowning workspace [Pipeline] sh 12:21:48 + pwd 12:21:48 + id -u 12:21:48 + id -g 12:21:48 + docker run --rm -v /home/ubuntu/workspace/libcompose_master:/workspace busybox chown -R 1000:1000 /workspace [Pipeline] } [Pipeline] // withEnv [Pipeline] archive 12:21:49 The archive step is deprecated, please use archiveArtifacts instead. 12:21:49 No files found to archive for pattern "bundles"; continuing. [Pipeline] sh 12:21:49 + docker rmi dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 12:21:50 Untagged: dockerbuildbot/libcompose:2a046c0bdbf256adf1d6fe107f5ab64770368af1 12:21:50 Deleted: sha256:a855da89c5b5163701120d01e351eb372b7b2515cf67743d0f31d7552b4b5cc1 12:21:50 Deleted: sha256:b37bb33f8d81c4382616013ed56ee56e694f600a38552689a2ef01455f4a4509 12:21:50 Deleted: sha256:442fdfff171c2985a7b7c86f12d81f29484df224b40854ae504d239e944ea2a6 12:21:50 Deleted: sha256:aae04cb7e5c107034be035d0c5bd3e955fd0ebfb850e8423c17d9baa8db10930 12:21:50 Deleted: sha256:74a69ff8454c3070e0dc6bf01411549ac1b06018246b89ee802c9d2de33fee16 12:21:50 Deleted: sha256:2e25effa23ddc6971751caaa8ec7f28311fd3bab0b7fe0602bcb68a03005c840 12:21:50 Deleted: sha256:b26b825e5202a4553db61bc4cb655297633ad2b2621e5cadffa23b35b3637af0 12:21:50 Deleted: sha256:5a43dd4377d3aa93dbdc1e0f7b549dfb858b2dad4f163f020b5335d270264b2f 12:21:50 Deleted: sha256:cc78b7416318cf90c7b01769cbd99bfe305f974440d739684b7fedc59d9eb27f 12:21:50 Deleted: sha256:ccae2617a0ea247fcb85a27ff075203fe4c8504374bca7c780433ce86962a1e6 12:21:50 Deleted: sha256:f3720aca4edd5caf9ebf2ae7b6386a73147b44a6e6fa04e060a92cd84347c8b9 12:21:50 Deleted: sha256:9d8d354aa28baa9cb00e9d556d7bd1142dbe66863845abda953116263667c781 12:21:50 Deleted: sha256:5de95b94ca80f0b55c1a7c66323c47fb4fa83a68d105fb6393ef45d0c9fe01fc 12:21:50 Deleted: sha256:9045aebbffa828699a6718f1cdac80f4752cd6bbfc65cddb22d5342b23af3910 12:21:50 Deleted: sha256:0d94c7d587b0ea98b70122f35f91a04491437ac290d1245dbf4e49b937c384ac 12:21:50 Deleted: sha256:0c5c284b6af976db274e9bd398f5b5b3b0cbd472285fe3b12c2c7b67469cc413 12:21:50 Deleted: sha256:0197d7e822eb8969db0cce207e3d90a2115f9dbdf9ffd66d9a6d3e48ded686e3 12:21:50 Deleted: sha256:54f73444e533c473e553ce7f8f447b776523f34b01d7d0967e10ef93fba678fb 12:21:50 Deleted: sha256:ac32a6af0bc107708cfead6117c45136f8d6cc239c7ff844bdfc0155aed8f08e 12:21:50 Deleted: sha256:9f42a0c9181348a8be3875e8ff01a0ad1b2e6a563a5865fea51fc67697a75aa0 [Pipeline] } [Pipeline] // wrap [Pipeline] } [Pipeline] // wrap [Pipeline] } [Pipeline] // withDockerRegistry [Pipeline] } [Pipeline] // node [Pipeline] End of Pipeline Could not update commit status, please check if your scan credentials belong to a member of the organization or a collaborator of the repository and repo:status scope is selected GitHub has been notified of this commit’s build result Finished: SUCCESS