Skip to content

Console Output

Skipping 462 KB.. Full Log
vzkDB8G3tScxILi1BRfsEwJg4BPVmJZon5OYl66vk9+Xrp1RRGDFNSy5Py84vycVD1nCI1qPENFAZCuEtnvtRLoPj6QKXogU/TApnQ/mXCmX/k+EwOjFwNrWWJOaSrQXAGEIr/S3KTUorY1U2W5pzzohpoGBJwVqwFpbTfG8gAAAA==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