Console Output
21:57:33 [2019-10-15T21:57:33.580Z] + docker run --rm -t --privileged -v /home/jenkins/workspace/moby_PR-40095/bundles:/go/src/github.com/docker/docker/bundles --name docker-pr2 -e DOCKER_EXPERIMENTAL -e DOCKER_GITCOMMIT=af3f576b1bd24f21ba62c6e99030913c67bfefc5 -e DOCKER_GRAPHDRIVER -e TESTDEBUG -e TEST_SKIP_INTEGRATION_CLI -e TIMEOUT -e VALIDATE_REPO=https://github.com/moby/moby.git -e VALIDATE_BRANCH=master docker:af3f576b1bd24f21ba62c6e99030913c67bfefc5 hack/make.sh dynbinary test-integration
21:57:33 [2019-10-15T21:57:33.962Z]
21:57:33 [2019-10-15T21:57:33.962Z] Removing bundles/
21:57:33 [2019-10-15T21:57:33.962Z]
21:57:33 [2019-10-15T21:57:33.962Z] ---> Making bundle: dynbinary (in bundles/dynbinary)
21:57:33 [2019-10-15T21:57:33.962Z] Building: bundles/dynbinary-daemon/dockerd-dev
21:57:33 [2019-10-15T21:57:33.962Z] GOOS="" GOARCH="" GOARM=""
21:58:34 [2019-10-15T21:58:34.745Z] Created binary: bundles/dynbinary-daemon/dockerd-dev
21:58:34 [2019-10-15T21:58:34.745Z]
21:58:34 [2019-10-15T21:58:34.745Z] ---> Making bundle: test-integration (in bundles/test-integration)
21:58:34 [2019-10-15T21:58:34.745Z] PKG_CONFIG=pkg-config
21:58:34 [2019-10-15T21:58:34.745Z] GOLANG_VERSION=1.13.1
21:58:34 [2019-10-15T21:58:34.745Z] HOSTNAME=88697ea775ea
21:58:34 [2019-10-15T21:58:34.745Z] DOCKER_EXPERIMENTAL=1
21:58:34 [2019-10-15T21:58:34.745Z] container=docker
21:58:34 [2019-10-15T21:58:34.745Z] TEST_SKIP_INTEGRATION_CLI=1
21:58:34 [2019-10-15T21:58:34.745Z] GO111MODULE=off
21:58:34 [2019-10-15T21:58:34.745Z] GOPATH=/go
21:58:34 [2019-10-15T21:58:34.745Z] PWD=/go/src/github.com/docker/docker
21:58:34 [2019-10-15T21:58:34.745Z] HOME=/root
21:58:34 [2019-10-15T21:58:34.745Z] VALIDATE_REPO=https://github.com/moby/moby.git
21:58:34 [2019-10-15T21:58:34.745Z] SCRIPTDIR=/go/src/github.com/docker/docker/hack
21:58:34 [2019-10-15T21:58:34.745Z] MAKEDIR=/go/src/github.com/docker/docker/hack/make
21:58:34 [2019-10-15T21:58:34.745Z] DOCKER_GITCOMMIT=af3f576b1bd24f21ba62c6e99030913c67bfefc5
21:58:34 [2019-10-15T21:58:34.745Z] DOCKER_BUILDTAGS=apparmor seccomp selinux journald
21:58:34 [2019-10-15T21:58:34.745Z] DOCKER_GRAPHDRIVER=overlay2
21:58:34 [2019-10-15T21:58:34.745Z] TERM=xterm
21:58:34 [2019-10-15T21:58:34.745Z] VALIDATE_BRANCH=master
21:58:34 [2019-10-15T21:58:34.745Z] DEST=bundles/test-integration
21:58:34 [2019-10-15T21:58:34.745Z] SHLVL=1
21:58:34 [2019-10-15T21:58:34.745Z] TIMEOUT=120m
21:58:34 [2019-10-15T21:58:34.745Z] PATH=/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
21:58:34 [2019-10-15T21:58:34.745Z] DOCKER_PKG=github.com/docker/docker
21:58:34 [2019-10-15T21:58:34.745Z] TESTDEBUG=0
21:58:34 [2019-10-15T21:58:34.745Z] _=/usr/bin/env
21:58:34 [2019-10-15T21:58:34.745Z] Building test suite binary /go/src/github.com/docker/docker/integration/build/test.main
21:59:03 [2019-10-15T21:59:03.601Z] Building test suite binary /go/src/github.com/docker/docker/integration/config/test.main
21:59:03 [2019-10-15T21:59:03.601Z] Building test suite binary /go/src/github.com/docker/docker/integration/container/test.main
21:59:05 [2019-10-15T21:59:05.440Z] Building test suite binary /go/src/github.com/docker/docker/integration/image/test.main
21:59:25 [2019-10-15T21:59:25.524Z] Building test suite binary /go/src/github.com/docker/docker/integration/network/test.main
21:59:25 [2019-10-15T21:59:25.524Z] Building test suite binary /go/src/github.com/docker/docker/integration/network/ipvlan/test.main
21:59:25 [2019-10-15T21:59:25.524Z] Building test suite binary /go/src/github.com/docker/docker/integration/network/macvlan/test.main
21:59:27 [2019-10-15T21:59:27.558Z] Building test suite binary /go/src/github.com/docker/docker/integration/plugin/test.main
21:59:27 [2019-10-15T21:59:27.558Z] Building test suite binary /go/src/github.com/docker/docker/integration/plugin/authz/test.main
21:59:29 [2019-10-15T21:59:29.419Z] Building test suite binary /go/src/github.com/docker/docker/integration/plugin/common/test.main
21:59:30 [2019-10-15T21:59:30.241Z] Building test suite binary /go/src/github.com/docker/docker/integration/plugin/graphdriver/test.main
21:59:32 [2019-10-15T21:59:32.070Z] Building test suite binary /go/src/github.com/docker/docker/integration/plugin/logging/test.main
21:59:33 [2019-10-15T21:59:33.939Z] Building test suite binary /go/src/github.com/docker/docker/integration/plugin/volumes/test.main
21:59:35 [2019-10-15T21:59:35.782Z] Building test suite binary /go/src/github.com/docker/docker/integration/secret/test.main
21:59:36 [2019-10-15T21:59:36.605Z] Building test suite binary /go/src/github.com/docker/docker/integration/service/test.main
21:59:39 [2019-10-15T21:59:39.439Z] Building test suite binary /go/src/github.com/docker/docker/integration/session/test.main
21:59:40 [2019-10-15T21:59:40.244Z] Building test suite binary /go/src/github.com/docker/docker/integration/system/test.main
21:59:41 [2019-10-15T21:59:41.545Z] Building test suite binary /go/src/github.com/docker/docker/integration/volume/test.main
21:59:42 [2019-10-15T21:59:42.819Z] ---> Making bundle: .integration-daemon-start (in bundles/test-integration)
21:59:42 [2019-10-15T21:59:42.819Z] Using test binary docker
21:59:42 [2019-10-15T21:59:42.819Z] # DOCKER_EXPERIMENTAL is set: starting daemon with experimental features enabled!
21:59:42 [2019-10-15T21:59:42.819Z] +++ /etc/init.d/apparmor start
21:59:42 [2019-10-15T21:59:42.819Z] /etc/init.d/apparmor: 130: /etc/init.d/apparmor: systemd-detect-virt: not found
21:59:42 [2019-10-15T21:59:42.819Z] Starting AppArmor profiles:Warning from stdin (line 1): /sbin/apparmor_parser: cannot use or update cache, disable, or force-complain via stdin
21:59:42 [2019-10-15T21:59:42.819Z] Warning failed to create cache: (null)
21:59:42 [2019-10-15T21:59:42.819Z] .
21:59:42 [2019-10-15T21:59:42.819Z] INFO: Waiting for daemon to start...
21:59:42 [2019-10-15T21:59:42.819Z] Starting dockerd
21:59:42 [2019-10-15T21:59:42.819Z] +++ exec dockerd --debug --host unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock --storage-driver overlay2 --pidfile bundles/test-integration/docker.pid --userland-proxy=true --experimental
21:59:45 [2019-10-15T21:59:45.278Z] .
21:59:45 [2019-10-15T21:59:45.278Z] ---> Making bundle: .integration-daemon-setup (in bundles/test-integration)
21:59:45 [2019-10-15T21:59:45.278Z] ---> Making bundle: .ensure-emptyfs (in bundles/test-integration)
21:59:45 [2019-10-15T21:59:45.278Z] Error: No such image: emptyfs
21:59:45 [2019-10-15T21:59:45.278Z] ++++ tar -cC bundles/test-integration/emptyfs .
21:59:45 [2019-10-15T21:59:45.278Z] ++++ docker load
21:59:45 [2019-10-15T21:59:45.278Z] Running integration-test (iteration 1)
21:59:45 [2019-10-15T21:59:45.278Z] Running /go/src/github.com/docker/docker/integration/build (s390x.integration.build) flags=-test.v -test.timeout=120m
21:59:45 [2019-10-15T21:59:45.279Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-build-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-build-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.build -t ./test.main -test.v -test.timeout=120m
21:59:45 [2019-10-15T21:59:45.279Z] ++ set -e
21:59:45 [2019-10-15T21:59:45.279Z] ++ '[' -n 0 ']'
21:59:45 [2019-10-15T21:59:45.279Z] ++ set -x
21:59:45 [2019-10-15T21:59:45.279Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-build-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-build-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.build -t ./test.main -test.v -test.timeout=120m
21:59:54 [2019-10-15T21:59:54.893Z] Loaded image: buildpack-deps:jessie
21:59:54 [2019-10-15T21:59:54.893Z] Loaded image: busybox:latest
21:59:54 [2019-10-15T21:59:54.893Z] Loaded image: busybox:glibc
21:59:54 [2019-10-15T21:59:54.893Z] Loaded image: debian:jessie
21:59:54 [2019-10-15T21:59:54.893Z] Loaded image: hello-world:latest
21:59:54 [2019-10-15T21:59:54.893Z] INFO: Testing against a local daemon
21:59:54 [2019-10-15T21:59:54.893Z] === RUN TestCgroupNamespacesBuild
21:59:56 [2019-10-15T21:59:56.378Z] --- PASS: TestCgroupNamespacesBuild (1.68s)
21:59:56 [2019-10-15T21:59:56.379Z] build_cgroupns_linux_test.go:42: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesBuild"
21:59:56 [2019-10-15T21:59:56.379Z] === RUN TestCgroupNamespacesBuildDaemonHostMode
21:59:58 [2019-10-15T21:59:58.203Z] --- PASS: TestCgroupNamespacesBuildDaemonHostMode (1.66s)
21:59:58 [2019-10-15T21:59:58.203Z] build_cgroupns_linux_test.go:42: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesBuildDaemonHostMode"
21:59:58 [2019-10-15T21:59:58.203Z] === RUN TestBuildWithSession
21:59:58 [2019-10-15T21:59:58.203Z] --- SKIP: TestBuildWithSession (0.00s)
21:59:58 [2019-10-15T21:59:58.203Z] build_session_test.go:25: TODO: BuildKit
21:59:58 [2019-10-15T21:59:58.203Z] === RUN TestBuildSquashParent
22:00:05 [2019-10-15T22:00:05.903Z] --- PASS: TestBuildSquashParent (6.60s)
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove/successful_build_with_no_removal
22:00:05 [2019-10-15T22:00:05.903Z] === PAUSE TestBuildWithRemoveAndForceRemove/successful_build_with_no_removal
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove/successful_build_with_remove
22:00:05 [2019-10-15T22:00:05.903Z] === PAUSE TestBuildWithRemoveAndForceRemove/successful_build_with_remove
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove/successful_build_with_remove_and_force_remove
22:00:05 [2019-10-15T22:00:05.903Z] === PAUSE TestBuildWithRemoveAndForceRemove/successful_build_with_remove_and_force_remove
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove/failed_build_with_no_removal
22:00:05 [2019-10-15T22:00:05.903Z] === PAUSE TestBuildWithRemoveAndForceRemove/failed_build_with_no_removal
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove/failed_build_with_remove
22:00:05 [2019-10-15T22:00:05.903Z] === PAUSE TestBuildWithRemoveAndForceRemove/failed_build_with_remove
22:00:05 [2019-10-15T22:00:05.903Z] === RUN TestBuildWithRemoveAndForceRemove/failed_build_with_remove_and_force_remove
22:00:05 [2019-10-15T22:00:05.903Z] === PAUSE TestBuildWithRemoveAndForceRemove/failed_build_with_remove_and_force_remove
22:00:05 [2019-10-15T22:00:05.903Z] === CONT TestBuildWithRemoveAndForceRemove/successful_build_with_no_removal
22:00:05 [2019-10-15T22:00:05.903Z] === CONT TestBuildWithRemoveAndForceRemove/failed_build_with_no_removal
22:00:07 [2019-10-15T22:00:07.256Z] === CONT TestBuildWithRemoveAndForceRemove/failed_build_with_remove_and_force_remove
22:00:07 [2019-10-15T22:00:07.256Z] === CONT TestBuildWithRemoveAndForceRemove/failed_build_with_remove
22:00:09 [2019-10-15T22:00:09.820Z] === CONT TestBuildWithRemoveAndForceRemove/successful_build_with_remove_and_force_remove
22:00:09 [2019-10-15T22:00:09.820Z] === CONT TestBuildWithRemoveAndForceRemove/successful_build_with_remove
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove (0.01s)
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove/failed_build_with_no_removal (2.34s)
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove/successful_build_with_no_removal (2.42s)
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove/failed_build_with_remove_and_force_remove (2.43s)
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove/failed_build_with_remove (2.35s)
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove/successful_build_with_remove (2.40s)
22:00:12 [2019-10-15T22:00:12.280Z] --- PASS: TestBuildWithRemoveAndForceRemove/successful_build_with_remove_and_force_remove (2.69s)
22:00:12 [2019-10-15T22:00:12.280Z] === RUN TestBuildMultiStageCopy
22:00:12 [2019-10-15T22:00:12.280Z] === RUN TestBuildMultiStageCopy/copy_to_root
22:00:14 [2019-10-15T22:00:14.758Z] === RUN TestBuildMultiStageCopy/copy_to_newdir
22:00:14 [2019-10-15T22:00:14.758Z] === RUN TestBuildMultiStageCopy/copy_to_newdir_nested
22:00:15 [2019-10-15T22:00:15.552Z] === RUN TestBuildMultiStageCopy/copy_to_existingdir
22:00:15 [2019-10-15T22:00:15.935Z] === RUN TestBuildMultiStageCopy/copy_to_newsubdir
22:00:16 [2019-10-15T22:00:16.307Z] --- PASS: TestBuildMultiStageCopy (4.22s)
22:00:16 [2019-10-15T22:00:16.307Z] --- PASS: TestBuildMultiStageCopy/copy_to_root (2.26s)
22:00:16 [2019-10-15T22:00:16.307Z] --- PASS: TestBuildMultiStageCopy/copy_to_newdir (0.42s)
22:00:16 [2019-10-15T22:00:16.307Z] --- PASS: TestBuildMultiStageCopy/copy_to_newdir_nested (0.48s)
22:00:16 [2019-10-15T22:00:16.307Z] --- PASS: TestBuildMultiStageCopy/copy_to_existingdir (0.53s)
22:00:16 [2019-10-15T22:00:16.307Z] --- PASS: TestBuildMultiStageCopy/copy_to_newsubdir (0.53s)
22:00:16 [2019-10-15T22:00:16.307Z] === RUN TestBuildMultiStageParentConfig
22:00:18 [2019-10-15T22:00:18.195Z] --- PASS: TestBuildMultiStageParentConfig (1.51s)
22:00:18 [2019-10-15T22:00:18.196Z] === RUN TestBuildLabelWithTargets
22:00:20 [2019-10-15T22:00:20.014Z] --- PASS: TestBuildLabelWithTargets (2.05s)
22:00:20 [2019-10-15T22:00:20.014Z] === RUN TestBuildWithEmptyLayers
22:00:21 [2019-10-15T22:00:21.288Z] --- PASS: TestBuildWithEmptyLayers (1.01s)
22:00:21 [2019-10-15T22:00:21.288Z] === RUN TestBuildMultiStageOnBuild
22:00:23 [2019-10-15T22:00:23.752Z] --- PASS: TestBuildMultiStageOnBuild (2.86s)
22:00:23 [2019-10-15T22:00:23.752Z] === RUN TestBuildUncleanTarFilenames
22:00:25 [2019-10-15T22:00:25.024Z] --- PASS: TestBuildUncleanTarFilenames (1.13s)
22:00:25 [2019-10-15T22:00:25.024Z] === RUN TestBuildMultiStageLayerLeak
22:00:28 [2019-10-15T22:00:28.232Z] --- PASS: TestBuildMultiStageLayerLeak (3.04s)
22:00:28 [2019-10-15T22:00:28.232Z] === RUN TestBuildWithHugeFile
22:01:02 [2019-10-15T22:01:02.636Z] --- PASS: TestBuildWithHugeFile (30.70s)
22:01:02 [2019-10-15T22:01:02.636Z] === RUN TestBuildWithEmptyDockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === RUN TestBuildWithEmptyDockerfile/empty-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === PAUSE TestBuildWithEmptyDockerfile/empty-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === RUN TestBuildWithEmptyDockerfile/empty-lines-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === PAUSE TestBuildWithEmptyDockerfile/empty-lines-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === RUN TestBuildWithEmptyDockerfile/comment-only-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === PAUSE TestBuildWithEmptyDockerfile/comment-only-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === CONT TestBuildWithEmptyDockerfile/empty-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === CONT TestBuildWithEmptyDockerfile/comment-only-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] === CONT TestBuildWithEmptyDockerfile/empty-lines-dockerfile
22:01:02 [2019-10-15T22:01:02.636Z] --- PASS: TestBuildWithEmptyDockerfile (0.12s)
22:01:02 [2019-10-15T22:01:02.636Z] --- PASS: TestBuildWithEmptyDockerfile/empty-dockerfile (0.18s)
22:01:02 [2019-10-15T22:01:02.636Z] --- PASS: TestBuildWithEmptyDockerfile/comment-only-dockerfile (0.18s)
22:01:02 [2019-10-15T22:01:02.636Z] --- PASS: TestBuildWithEmptyDockerfile/empty-lines-dockerfile (0.08s)
22:01:02 [2019-10-15T22:01:02.636Z] === RUN TestBuildPreserveOwnership
22:01:02 [2019-10-15T22:01:02.636Z] === RUN TestBuildPreserveOwnership/copy_from
22:01:03 [2019-10-15T22:01:03.003Z] === RUN TestBuildPreserveOwnership/copy_from_chowned
22:01:05 [2019-10-15T22:01:05.687Z] --- PASS: TestBuildPreserveOwnership (6.05s)
22:01:05 [2019-10-15T22:01:05.687Z] --- PASS: TestBuildPreserveOwnership/copy_from (3.89s)
22:01:05 [2019-10-15T22:01:05.687Z] --- PASS: TestBuildPreserveOwnership/copy_from_chowned (2.15s)
22:01:05 [2019-10-15T22:01:05.687Z] === RUN TestBuildPlatformInvalid
22:01:05 [2019-10-15T22:01:05.688Z] --- PASS: TestBuildPlatformInvalid (0.17s)
22:01:05 [2019-10-15T22:01:05.688Z] PASS
22:01:05 [2019-10-15T22:01:05.688Z]
22:01:05 [2019-10-15T22:01:05.688Z] === Skipped
22:01:05 [2019-10-15T22:01:05.688Z] === SKIP: s390x.integration.build TestBuildWithSession (0.00s)
22:01:05 [2019-10-15T22:01:05.688Z] build_session_test.go:25: TODO: BuildKit
22:01:05 [2019-10-15T22:01:05.688Z]
22:01:05 [2019-10-15T22:01:05.688Z]
22:01:05 [2019-10-15T22:01:05.688Z] DONE 32 tests, 1 skipped in 80.227s
22:01:05 [2019-10-15T22:01:05.688Z] Running /go/src/github.com/docker/docker/integration/config (s390x.integration.config) flags=-test.v -test.timeout=120m
22:01:05 [2019-10-15T22:01:05.688Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-config-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-config-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.config -t ./test.main -test.v -test.timeout=120m
22:01:05 [2019-10-15T22:01:05.688Z] ++ set -e
22:01:05 [2019-10-15T22:01:05.688Z] ++ '[' -n 0 ']'
22:01:05 [2019-10-15T22:01:05.688Z] ++ set -x
22:01:05 [2019-10-15T22:01:05.688Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-config-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-config-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.config -t ./test.main -test.v -test.timeout=120m
22:01:05 [2019-10-15T22:01:05.688Z] INFO: Testing against a local daemon
22:01:05 [2019-10-15T22:01:05.688Z] === RUN TestConfigList
22:01:09 [2019-10-15T22:01:09.029Z] --- PASS: TestConfigList (2.78s)
22:01:09 [2019-10-15T22:01:09.029Z] config_test.go:30: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigList"
22:01:09 [2019-10-15T22:01:09.029Z] === RUN TestConfigsCreateAndDelete
22:01:11 [2019-10-15T22:01:11.480Z] --- PASS: TestConfigsCreateAndDelete (2.82s)
22:01:11 [2019-10-15T22:01:11.480Z] config_test.go:112: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigsCreateAndDelete"
22:01:11 [2019-10-15T22:01:11.480Z] === RUN TestConfigsUpdate
22:01:13 [2019-10-15T22:01:13.909Z] --- PASS: TestConfigsUpdate (2.72s)
22:01:13 [2019-10-15T22:01:13.909Z] config_test.go:140: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigsUpdate"
22:01:13 [2019-10-15T22:01:13.909Z] === RUN TestTemplatedConfig
22:01:17 [2019-10-15T22:01:17.978Z] --- PASS: TestTemplatedConfig (3.58s)
22:01:17 [2019-10-15T22:01:17.978Z] config_test.go:192: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestTemplatedConfig"
22:01:17 [2019-10-15T22:01:17.978Z] === RUN TestConfigInspect
22:01:20 [2019-10-15T22:01:20.715Z] --- PASS: TestConfigInspect (2.91s)
22:01:20 [2019-10-15T22:01:20.715Z] config_test.go:326: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigInspect"
22:01:20 [2019-10-15T22:01:20.715Z] === RUN TestConfigCreateWithLabels
22:01:23 [2019-10-15T22:01:23.151Z] --- PASS: TestConfigCreateWithLabels (2.73s)
22:01:23 [2019-10-15T22:01:23.151Z] config_test.go:350: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigCreateWithLabels"
22:01:23 [2019-10-15T22:01:23.151Z] === RUN TestConfigCreateResolve
22:01:25 [2019-10-15T22:01:25.608Z] --- PASS: TestConfigCreateResolve (2.31s)
22:01:25 [2019-10-15T22:01:25.608Z] config_test.go:377: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigCreateResolve"
22:01:25 [2019-10-15T22:01:25.608Z] === RUN TestConfigDaemonLibtrustID
22:01:25 [2019-10-15T22:01:25.978Z] --- PASS: TestConfigDaemonLibtrustID (0.54s)
22:01:25 [2019-10-15T22:01:25.978Z] config_test.go:427: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestConfigDaemonLibtrustID"
22:01:25 [2019-10-15T22:01:25.978Z] PASS
22:01:25 [2019-10-15T22:01:25.978Z]
22:01:25 [2019-10-15T22:01:25.978Z] DONE 8 tests in 20.596s
22:01:25 [2019-10-15T22:01:25.978Z] Running /go/src/github.com/docker/docker/integration/container (s390x.integration.container) flags=-test.v -test.timeout=120m
22:01:25 [2019-10-15T22:01:25.978Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-container-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-container-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.container -t ./test.main -test.v -test.timeout=120m
22:01:25 [2019-10-15T22:01:25.978Z] ++ set -e
22:01:25 [2019-10-15T22:01:25.978Z] ++ '[' -n 0 ']'
22:01:25 [2019-10-15T22:01:25.978Z] ++ set -x
22:01:25 [2019-10-15T22:01:25.978Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-container-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-container-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.container -t ./test.main -test.v -test.timeout=120m
22:01:26 [2019-10-15T22:01:26.353Z] INFO: Testing against a local daemon
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestCheckpoint
22:01:26 [2019-10-15T22:01:26.353Z] --- SKIP: TestCheckpoint (0.00s)
22:01:26 [2019-10-15T22:01:26.353Z] checkpoint_test.go:33: TestCheckpoint is broken; see https://github.com/moby/moby/issues/38963
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestContainerInvalidJSON
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestContainerInvalidJSON//containers/foobar/copy
22:01:26 [2019-10-15T22:01:26.353Z] === PAUSE TestContainerInvalidJSON//containers/foobar/copy
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestContainerInvalidJSON//containers/foobar/exec
22:01:26 [2019-10-15T22:01:26.353Z] === PAUSE TestContainerInvalidJSON//containers/foobar/exec
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestContainerInvalidJSON//exec/foobar/start
22:01:26 [2019-10-15T22:01:26.353Z] === PAUSE TestContainerInvalidJSON//exec/foobar/start
22:01:26 [2019-10-15T22:01:26.353Z] === CONT TestContainerInvalidJSON//containers/foobar/copy
22:01:26 [2019-10-15T22:01:26.353Z] === CONT TestContainerInvalidJSON//exec/foobar/start
22:01:26 [2019-10-15T22:01:26.353Z] === CONT TestContainerInvalidJSON//containers/foobar/exec
22:01:26 [2019-10-15T22:01:26.353Z] --- PASS: TestContainerInvalidJSON (0.01s)
22:01:26 [2019-10-15T22:01:26.353Z] --- PASS: TestContainerInvalidJSON//exec/foobar/start (0.00s)
22:01:26 [2019-10-15T22:01:26.353Z] --- PASS: TestContainerInvalidJSON//containers/foobar/copy (0.00s)
22:01:26 [2019-10-15T22:01:26.353Z] --- PASS: TestContainerInvalidJSON//containers/foobar/exec (0.00s)
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestCopyFromContainerPathDoesNotExist
22:01:26 [2019-10-15T22:01:26.353Z] --- PASS: TestCopyFromContainerPathDoesNotExist (0.12s)
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestCopyFromContainerPathIsNotDir
22:01:26 [2019-10-15T22:01:26.353Z] --- PASS: TestCopyFromContainerPathIsNotDir (0.11s)
22:01:26 [2019-10-15T22:01:26.353Z] === RUN TestCopyToContainerPathDoesNotExist
22:01:26 [2019-10-15T22:01:26.716Z] --- PASS: TestCopyToContainerPathDoesNotExist (0.13s)
22:01:26 [2019-10-15T22:01:26.717Z] === RUN TestCopyToContainerPathIsNotDir
22:01:26 [2019-10-15T22:01:26.717Z] --- PASS: TestCopyToContainerPathIsNotDir (0.11s)
22:01:26 [2019-10-15T22:01:26.717Z] === RUN TestCopyFromContainer
22:01:28 [2019-10-15T22:01:28.570Z] === RUN TestCopyFromContainer//
22:01:29 [2019-10-15T22:01:29.063Z] === RUN TestCopyFromContainer//bar/root
22:01:29 [2019-10-15T22:01:29.063Z] === RUN TestCopyFromContainer//bar/root/
22:01:29 [2019-10-15T22:01:29.063Z] === RUN TestCopyFromContainer/bar/quux
22:01:29 [2019-10-15T22:01:29.063Z] === RUN TestCopyFromContainer/bar/quux/
22:01:29 [2019-10-15T22:01:29.063Z] === RUN TestCopyFromContainer/bar/quux/baz
22:01:29 [2019-10-15T22:01:29.525Z] === RUN TestCopyFromContainer/bar/filesymlink
22:01:29 [2019-10-15T22:01:29.525Z] === RUN TestCopyFromContainer/bar/dirsymlink
22:01:29 [2019-10-15T22:01:29.525Z] === RUN TestCopyFromContainer/bar/dirsymlink/
22:01:29 [2019-10-15T22:01:29.525Z] === RUN TestCopyFromContainer/bar/notarget
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer (2.96s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer// (0.10s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer//bar/root (0.13s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer//bar/root/ (0.07s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/quux (0.15s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/quux/ (0.10s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/quux/baz (0.11s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/filesymlink (0.09s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/dirsymlink (0.09s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/dirsymlink/ (0.08s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCopyFromContainer/bar/notarget (0.10s)
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateFailsWhenIdentifierDoesNotExist
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateFailsWhenIdentifierDoesNotExist/image_and_tag
22:01:29 [2019-10-15T22:01:29.996Z] === PAUSE TestCreateFailsWhenIdentifierDoesNotExist/image_and_tag
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateFailsWhenIdentifierDoesNotExist/image_no_tag
22:01:29 [2019-10-15T22:01:29.996Z] === PAUSE TestCreateFailsWhenIdentifierDoesNotExist/image_no_tag
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateFailsWhenIdentifierDoesNotExist/digest
22:01:29 [2019-10-15T22:01:29.996Z] === PAUSE TestCreateFailsWhenIdentifierDoesNotExist/digest
22:01:29 [2019-10-15T22:01:29.996Z] === CONT TestCreateFailsWhenIdentifierDoesNotExist/image_and_tag
22:01:29 [2019-10-15T22:01:29.996Z] === CONT TestCreateFailsWhenIdentifierDoesNotExist/digest
22:01:29 [2019-10-15T22:01:29.996Z] === CONT TestCreateFailsWhenIdentifierDoesNotExist/image_no_tag
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateFailsWhenIdentifierDoesNotExist (0.01s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateFailsWhenIdentifierDoesNotExist/digest (0.00s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateFailsWhenIdentifierDoesNotExist/image_no_tag (0.00s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateFailsWhenIdentifierDoesNotExist/image_and_tag (0.00s)
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateLinkToNonExistingContainer
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateLinkToNonExistingContainer (0.06s)
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateWithInvalidEnv
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateWithInvalidEnv/0
22:01:29 [2019-10-15T22:01:29.996Z] === PAUSE TestCreateWithInvalidEnv/0
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateWithInvalidEnv/1
22:01:29 [2019-10-15T22:01:29.996Z] === PAUSE TestCreateWithInvalidEnv/1
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateWithInvalidEnv/2
22:01:29 [2019-10-15T22:01:29.996Z] === PAUSE TestCreateWithInvalidEnv/2
22:01:29 [2019-10-15T22:01:29.996Z] === CONT TestCreateWithInvalidEnv/0
22:01:29 [2019-10-15T22:01:29.996Z] === CONT TestCreateWithInvalidEnv/2
22:01:29 [2019-10-15T22:01:29.996Z] === CONT TestCreateWithInvalidEnv/1
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateWithInvalidEnv (0.01s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateWithInvalidEnv/2 (0.00s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateWithInvalidEnv/0 (0.00s)
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateWithInvalidEnv/1 (0.00s)
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateTmpfsMountsTarget
22:01:29 [2019-10-15T22:01:29.996Z] --- PASS: TestCreateTmpfsMountsTarget (0.04s)
22:01:29 [2019-10-15T22:01:29.996Z] === RUN TestCreateWithCustomMaskedPaths
22:01:32 [2019-10-15T22:01:32.452Z] --- PASS: TestCreateWithCustomMaskedPaths (2.51s)
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/no_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/no_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/empty_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/empty_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/valid_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/valid_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/invalid_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/invalid_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/duplicate_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/duplicate_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/capabilities_API_v1.39
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/capabilities_API_v1.39
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/empty_capadd
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/empty_capadd
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/empty_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/empty_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/capadd_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/capadd_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/conflict_with_capadd
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/conflict_with_capadd
22:01:32 [2019-10-15T22:01:32.452Z] === RUN TestCreateWithCapabilities/conflict_with_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === PAUSE TestCreateWithCapabilities/conflict_with_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/no_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/conflict_with_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/conflict_with_capadd
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/capadd_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/empty_capdrop
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/empty_capadd
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/capabilities_API_v1.39
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/duplicate_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/invalid_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/valid_capabilities
22:01:32 [2019-10-15T22:01:32.452Z] === CONT TestCreateWithCapabilities/empty_capabilities
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities (0.01s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/conflict_with_capdrop (0.00s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/conflict_with_capadd (0.00s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/no_capabilities (0.08s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/capadd_capdrop (0.07s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/empty_capadd (0.07s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/empty_capdrop (0.07s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/capabilities_API_v1.39 (0.07s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/duplicate_capabilities (0.07s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/invalid_capabilities (0.00s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/empty_capabilities (0.08s)
22:01:32 [2019-10-15T22:01:32.828Z] --- PASS: TestCreateWithCapabilities/valid_capabilities (0.08s)
22:01:32 [2019-10-15T22:01:32.828Z] === RUN TestCreateWithCustomReadonlyPaths
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithCustomReadonlyPaths (2.65s)
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestCreateWithInvalidHealthcheckParams
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_less_than_0s
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_less_than_0s
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_larger_than_0s_but_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_larger_than_0s_but_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestCreateWithInvalidHealthcheckParams/test_invalid_Timeout_in_Healthcheck:_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestCreateWithInvalidHealthcheckParams/test_invalid_Timeout_in_Healthcheck:_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestCreateWithInvalidHealthcheckParams/test_invalid_Retries_in_Healthcheck:_less_than_0
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestCreateWithInvalidHealthcheckParams/test_invalid_Retries_in_Healthcheck:_less_than_0
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestCreateWithInvalidHealthcheckParams/test_invalid_StartPeriod_in_Healthcheck:_not_0_and_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestCreateWithInvalidHealthcheckParams/test_invalid_StartPeriod_in_Healthcheck:_not_0_and_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === CONT TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_less_than_0s
22:01:35 [2019-10-15T22:01:35.274Z] === CONT TestCreateWithInvalidHealthcheckParams/test_invalid_StartPeriod_in_Healthcheck:_not_0_and_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === CONT TestCreateWithInvalidHealthcheckParams/test_invalid_Timeout_in_Healthcheck:_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] === CONT TestCreateWithInvalidHealthcheckParams/test_invalid_Retries_in_Healthcheck:_less_than_0
22:01:35 [2019-10-15T22:01:35.274Z] === CONT TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_larger_than_0s_but_less_than_1ms
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithInvalidHealthcheckParams (0.02s)
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithInvalidHealthcheckParams/test_invalid_StartPeriod_in_Healthcheck:_not_0_and_less_than_1ms (0.01s)
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_less_than_0s (0.01s)
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithInvalidHealthcheckParams/test_invalid_Retries_in_Healthcheck:_less_than_0 (0.01s)
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithInvalidHealthcheckParams/test_invalid_Timeout_in_Healthcheck:_less_than_1ms (0.01s)
22:01:35 [2019-10-15T22:01:35.274Z] --- PASS: TestCreateWithInvalidHealthcheckParams/test_invalid_Interval_in_Healthcheck:_larger_than_0s_but_less_than_1ms (0.01s)
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestContainerStartOnDaemonRestart
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestContainerStartOnDaemonRestart
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestDaemonRestartIpcMode
22:01:35 [2019-10-15T22:01:35.274Z] === PAUSE TestDaemonRestartIpcMode
22:01:35 [2019-10-15T22:01:35.274Z] === RUN TestDiff
22:01:36 [2019-10-15T22:01:36.078Z] --- PASS: TestDiff (0.83s)
22:01:36 [2019-10-15T22:01:36.078Z] === RUN TestExecWithCloseStdin
22:01:37 [2019-10-15T22:01:37.352Z] --- PASS: TestExecWithCloseStdin (1.02s)
22:01:37 [2019-10-15T22:01:37.352Z] === RUN TestExec
22:01:38 [2019-10-15T22:01:38.151Z] --- PASS: TestExec (1.12s)
22:01:38 [2019-10-15T22:01:38.151Z] === RUN TestExecUser
22:01:39 [2019-10-15T22:01:39.454Z] --- PASS: TestExecUser (1.05s)
22:01:39 [2019-10-15T22:01:39.454Z] === RUN TestExportContainerAndImportImage
22:01:40 [2019-10-15T22:01:40.459Z] --- PASS: TestExportContainerAndImportImage (1.25s)
22:01:40 [2019-10-15T22:01:40.459Z] === RUN TestExportContainerAfterDaemonRestart
22:01:42 [2019-10-15T22:01:42.309Z] --- PASS: TestExportContainerAfterDaemonRestart (1.53s)
22:01:42 [2019-10-15T22:01:42.309Z] export_test.go:63: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestExportContainerAfterDaemonRestart"
22:01:42 [2019-10-15T22:01:42.309Z] === RUN TestHealthCheckWorkdir
22:01:43 [2019-10-15T22:01:43.561Z] --- PASS: TestHealthCheckWorkdir (1.17s)
22:01:43 [2019-10-15T22:01:43.561Z] === RUN TestHealthKillContainer
22:01:46 [2019-10-15T22:01:46.720Z] --- PASS: TestHealthKillContainer (2.98s)
22:01:46 [2019-10-15T22:01:46.720Z] === RUN TestInspectCpusetInConfigPre120
22:01:47 [2019-10-15T22:01:47.091Z] --- PASS: TestInspectCpusetInConfigPre120 (0.77s)
22:01:47 [2019-10-15T22:01:47.091Z] === RUN TestIpcModeNone
22:01:48 [2019-10-15T22:01:48.373Z] --- PASS: TestIpcModeNone (1.05s)
22:01:48 [2019-10-15T22:01:48.373Z] === RUN TestIpcModePrivate
22:01:49 [2019-10-15T22:01:49.162Z] --- PASS: TestIpcModePrivate (1.06s)
22:01:49 [2019-10-15T22:01:49.162Z] ipcmode_linux_test.go:90: [testIpcPrivateShareable] ipcmode: private, ipcdev: 0:64, shared: false, mustBeShared: false
22:01:49 [2019-10-15T22:01:49.162Z] === RUN TestIpcModeShareable
22:01:50 [2019-10-15T22:01:50.515Z] --- PASS: TestIpcModeShareable (1.11s)
22:01:50 [2019-10-15T22:01:50.515Z] ipcmode_linux_test.go:90: [testIpcPrivateShareable] ipcmode: shareable, ipcdev: 0:58, shared: true, mustBeShared: true
22:01:50 [2019-10-15T22:01:50.515Z] === RUN TestAPIIpcModeShareableAndContainer
22:01:53 [2019-10-15T22:01:53.832Z] --- PASS: TestAPIIpcModeShareableAndContainer (3.21s)
22:01:53 [2019-10-15T22:01:53.832Z] === RUN TestAPIIpcModeHost
22:01:54 [2019-10-15T22:01:54.627Z] --- PASS: TestAPIIpcModeHost (0.91s)
22:01:54 [2019-10-15T22:01:54.627Z] === RUN TestDaemonIpcModeShareable
22:01:56 [2019-10-15T22:01:56.424Z] --- PASS: TestDaemonIpcModeShareable (1.85s)
22:01:56 [2019-10-15T22:01:56.424Z] ipcmode_linux_test.go:230: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonIpcModeShareable"
22:01:56 [2019-10-15T22:01:56.424Z] ipcmode_linux_test.go:258: [testDaemonIpcPrivateShareable] ipcdev: 0:66, shared: true, mustBeShared: true
22:01:56 [2019-10-15T22:01:56.424Z] === RUN TestDaemonIpcModePrivate
22:01:58 [2019-10-15T22:01:58.221Z] --- PASS: TestDaemonIpcModePrivate (1.70s)
22:01:58 [2019-10-15T22:01:58.221Z] ipcmode_linux_test.go:230: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonIpcModePrivate"
22:01:58 [2019-10-15T22:01:58.221Z] ipcmode_linux_test.go:258: [testDaemonIpcPrivateShareable] ipcdev: 0:72, shared: false, mustBeShared: false
22:01:58 [2019-10-15T22:01:58.221Z] === RUN TestDaemonIpcModePrivateFromConfig
22:02:00 [2019-10-15T22:02:00.055Z] --- PASS: TestDaemonIpcModePrivateFromConfig (1.76s)
22:02:00 [2019-10-15T22:02:00.055Z] ipcmode_linux_test.go:230: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonIpcModePrivateFromConfig"
22:02:00 [2019-10-15T22:02:00.055Z] ipcmode_linux_test.go:258: [testDaemonIpcPrivateShareable] ipcdev: 0:72, shared: false, mustBeShared: false
22:02:00 [2019-10-15T22:02:00.055Z] === RUN TestDaemonIpcModeShareableFromConfig
22:02:01 [2019-10-15T22:02:01.442Z] --- PASS: TestDaemonIpcModeShareableFromConfig (1.86s)
22:02:01 [2019-10-15T22:02:01.442Z] ipcmode_linux_test.go:230: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonIpcModeShareableFromConfig"
22:02:01 [2019-10-15T22:02:01.442Z] ipcmode_linux_test.go:258: [testDaemonIpcPrivateShareable] ipcdev: 0:66, shared: true, mustBeShared: true
22:02:01 [2019-10-15T22:02:01.442Z] === RUN TestIpcModeOlderClient
22:02:01 [2019-10-15T22:02:01.442Z] === PAUSE TestIpcModeOlderClient
22:02:01 [2019-10-15T22:02:01.442Z] === RUN TestKillContainerInvalidSignal
22:02:02 [2019-10-15T22:02:02.306Z] --- PASS: TestKillContainerInvalidSignal (0.67s)
22:02:02 [2019-10-15T22:02:02.306Z] === RUN TestKillContainer
22:02:02 [2019-10-15T22:02:02.306Z] === RUN TestKillContainer/no_signal
22:02:03 [2019-10-15T22:02:03.103Z] === RUN TestKillContainer/non_killing_signal
22:02:03 [2019-10-15T22:02:03.909Z] === RUN TestKillContainer/killing_signal
22:02:05 [2019-10-15T22:02:05.180Z] --- PASS: TestKillContainer (2.81s)
22:02:05 [2019-10-15T22:02:05.180Z] --- PASS: TestKillContainer/no_signal (0.96s)
22:02:05 [2019-10-15T22:02:05.180Z] --- PASS: TestKillContainer/non_killing_signal (0.63s)
22:02:05 [2019-10-15T22:02:05.180Z] --- PASS: TestKillContainer/killing_signal (0.87s)
22:02:05 [2019-10-15T22:02:05.180Z] === RUN TestKillWithStopSignalAndRestartPolicies
22:02:05 [2019-10-15T22:02:05.180Z] === RUN TestKillWithStopSignalAndRestartPolicies/same-signal-disables-restart-policy
22:02:05 [2019-10-15T22:02:05.979Z] === RUN TestKillWithStopSignalAndRestartPolicies/different-signal-keep-restart-policy
22:02:06 [2019-10-15T22:02:06.766Z] --- PASS: TestKillWithStopSignalAndRestartPolicies (1.77s)
22:02:06 [2019-10-15T22:02:06.766Z] --- PASS: TestKillWithStopSignalAndRestartPolicies/same-signal-disables-restart-policy (0.88s)
22:02:06 [2019-10-15T22:02:06.766Z] --- PASS: TestKillWithStopSignalAndRestartPolicies/different-signal-keep-restart-policy (0.55s)
22:02:06 [2019-10-15T22:02:06.766Z] === RUN TestKillStoppedContainer
22:02:06 [2019-10-15T22:02:06.766Z] --- PASS: TestKillStoppedContainer (0.11s)
22:02:06 [2019-10-15T22:02:06.766Z] === RUN TestKillStoppedContainerAPIPre120
22:02:07 [2019-10-15T22:02:07.140Z] --- PASS: TestKillStoppedContainerAPIPre120 (0.10s)
22:02:07 [2019-10-15T22:02:07.140Z] === RUN TestKillDifferentUserContainer
22:02:07 [2019-10-15T22:02:07.917Z] --- PASS: TestKillDifferentUserContainer (0.83s)
22:02:07 [2019-10-15T22:02:07.917Z] === RUN TestInspectOomKilledTrue
22:02:07 [2019-10-15T22:02:07.917Z] --- SKIP: TestInspectOomKilledTrue (0.00s)
22:02:07 [2019-10-15T22:02:07.917Z] kill_test.go:151: testEnv.DaemonInfo.OSType == "windows" || !testEnv.DaemonInfo.MemoryLimit || !testEnv.DaemonInfo.SwapLimit
22:02:07 [2019-10-15T22:02:07.917Z] === RUN TestInspectOomKilledFalse
22:02:07 [2019-10-15T22:02:07.917Z] --- SKIP: TestInspectOomKilledFalse (0.00s)
22:02:07 [2019-10-15T22:02:07.917Z] kill_test.go:169: testEnv.DaemonInfo.OSType == "windows" || !testEnv.DaemonInfo.MemoryLimit || !testEnv.DaemonInfo.SwapLimit
22:02:07 [2019-10-15T22:02:07.917Z] === RUN TestLinksEtcHostsContentMatch
22:02:08 [2019-10-15T22:02:08.720Z] --- PASS: TestLinksEtcHostsContentMatch (0.60s)
22:02:08 [2019-10-15T22:02:08.720Z] === RUN TestLinksContainerNames
22:02:10 [2019-10-15T22:02:10.146Z] --- PASS: TestLinksContainerNames (1.47s)
22:02:10 [2019-10-15T22:02:10.146Z] === RUN TestLogsFollowTailEmpty
22:02:11 [2019-10-15T22:02:11.053Z] --- PASS: TestLogsFollowTailEmpty (0.94s)
22:02:11 [2019-10-15T22:02:11.053Z] === RUN TestContainerNetworkMountsNoChown
22:02:11 [2019-10-15T22:02:11.894Z] --- PASS: TestContainerNetworkMountsNoChown (0.92s)
22:02:11 [2019-10-15T22:02:11.894Z] === RUN TestMountDaemonRoot
22:02:11 [2019-10-15T22:02:11.894Z] === RUN TestMountDaemonRoot/default
22:02:11 [2019-10-15T22:02:11.894Z] === PAUSE TestMountDaemonRoot/default
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/private
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/private
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rprivate
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rprivate
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/slave
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/slave
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rslave
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rslave
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/shared
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/shared
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rshared
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rshared
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/default
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rslave
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/default/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/default/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/default/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/default/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/default/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/default/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/default/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/default/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rslave/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rslave/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rslave/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rslave/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rslave/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rshared
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rslave/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rslave/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rslave/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/shared
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rshared/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/shared/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rshared/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/shared/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rshared/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/shared/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rshared/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/shared/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/shared/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rshared/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/shared/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rshared/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/shared/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rshared/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rshared/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/shared/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rprivate
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rprivate/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rprivate/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rprivate/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rprivate/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rprivate/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rprivate/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/rprivate/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/rprivate/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/slave
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/slave/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/slave/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/slave/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/slave/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/slave/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/slave/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/slave/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/slave/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/private
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/private/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/private/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/private/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/private/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/private/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/private/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === RUN TestMountDaemonRoot/private/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === PAUSE TestMountDaemonRoot/private/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/default/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/default/mount_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rslave/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/default/bind_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/default/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rslave/bind_root
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rslave/mount_subpath
22:02:11 [2019-10-15T22:02:11.895Z] === CONT TestMountDaemonRoot/rslave/mount_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rshared/mount_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/shared/mount_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rprivate/bind_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rshared/mount_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rshared/bind_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rshared/bind_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/slave/bind_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/shared/mount_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/shared/bind_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/shared/bind_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/private/bind_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rprivate/mount_subpath
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rprivate/mount_root
22:02:12 [2019-10-15T22:02:12.267Z] === CONT TestMountDaemonRoot/rprivate/bind_subpath
22:02:12 [2019-10-15T22:02:12.658Z] === CONT TestMountDaemonRoot/slave/bind_root
22:02:12 [2019-10-15T22:02:12.659Z] === CONT TestMountDaemonRoot/slave/mount_subpath
22:02:12 [2019-10-15T22:02:12.659Z] === CONT TestMountDaemonRoot/slave/mount_root
22:02:12 [2019-10-15T22:02:12.659Z] === CONT TestMountDaemonRoot/private/bind_root
22:02:12 [2019-10-15T22:02:12.659Z] === CONT TestMountDaemonRoot/private/mount_subpath
22:02:12 [2019-10-15T22:02:12.659Z] === CONT TestMountDaemonRoot/private/mount_root
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot (0.04s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/default (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/default/mount_subpath (0.07s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/default/mount_root (0.09s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/default/bind_subpath (0.07s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/default/bind_root (0.07s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rslave (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rslave/bind_subpath (0.08s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rslave/bind_root (0.06s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rslave/mount_root (0.08s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rslave/mount_subpath (0.08s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rshared (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rshared/mount_subpath (0.07s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rshared/mount_root (0.06s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rshared/bind_subpath (0.06s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rshared/bind_root (0.08s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/shared (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/shared/mount_subpath (0.05s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/shared/mount_root (0.02s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/shared/bind_subpath (0.03s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/shared/bind_root (0.03s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rprivate (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rprivate/bind_root (0.03s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rprivate/mount_subpath (0.06s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rprivate/mount_root (0.05s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/rprivate/bind_subpath (0.02s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/slave (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/slave/bind_subpath (0.04s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/slave/bind_root (0.03s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/slave/mount_subpath (0.03s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/slave/mount_root (0.05s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/private (0.00s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/private/bind_subpath (0.04s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/private/bind_root (0.05s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/private/mount_subpath (0.04s)
22:02:12 [2019-10-15T22:02:12.659Z] --- PASS: TestMountDaemonRoot/private/mount_root (0.04s)
22:02:12 [2019-10-15T22:02:12.659Z] === RUN TestContainerBindMountNonRecursive
22:02:14 [2019-10-15T22:02:14.505Z] --- PASS: TestContainerBindMountNonRecursive (1.91s)
22:02:14 [2019-10-15T22:02:14.505Z] === RUN TestNetworkNat
22:02:15 [2019-10-15T22:02:15.296Z] --- PASS: TestNetworkNat (0.90s)
22:02:15 [2019-10-15T22:02:15.296Z] === RUN TestNetworkLocalhostTCPNat
22:02:16 [2019-10-15T22:02:16.562Z] --- PASS: TestNetworkLocalhostTCPNat (0.97s)
22:02:16 [2019-10-15T22:02:16.562Z] === RUN TestNetworkLoopbackNat
22:02:17 [2019-10-15T22:02:17.841Z] --- PASS: TestNetworkLoopbackNat (1.19s)
22:02:17 [2019-10-15T22:02:17.841Z] === RUN TestPause
22:02:18 [2019-10-15T22:02:18.623Z] --- PASS: TestPause (0.97s)
22:02:18 [2019-10-15T22:02:18.623Z] === RUN TestPauseFailsOnWindowsServerContainers
22:02:18 [2019-10-15T22:02:18.623Z] --- SKIP: TestPauseFailsOnWindowsServerContainers (0.00s)
22:02:18 [2019-10-15T22:02:18.623Z] pause_test.go:54: testEnv.DaemonInfo.OSType != "windows" || testEnv.DaemonInfo.Isolation != "process"
22:02:18 [2019-10-15T22:02:18.623Z] === RUN TestPauseStopPausedContainer
22:02:19 [2019-10-15T22:02:19.423Z] --- PASS: TestPauseStopPausedContainer (0.88s)
22:02:19 [2019-10-15T22:02:19.423Z] === RUN TestPsFilter
22:02:19 [2019-10-15T22:02:19.788Z] --- PASS: TestPsFilter (0.27s)
22:02:19 [2019-10-15T22:02:19.788Z] === RUN TestRemoveContainerWithRemovedVolume
22:02:20 [2019-10-15T22:02:20.691Z] --- PASS: TestRemoveContainerWithRemovedVolume (0.79s)
22:02:20 [2019-10-15T22:02:20.691Z] === RUN TestRemoveContainerWithVolume
22:02:21 [2019-10-15T22:02:21.085Z] --- PASS: TestRemoveContainerWithVolume (0.77s)
22:02:21 [2019-10-15T22:02:21.085Z] === RUN TestRemoveContainerRunning
22:02:21 [2019-10-15T22:02:21.971Z] --- PASS: TestRemoveContainerRunning (0.87s)
22:02:21 [2019-10-15T22:02:21.971Z] === RUN TestRemoveContainerForceRemoveRunning
22:02:23 [2019-10-15T22:02:23.257Z] --- PASS: TestRemoveContainerForceRemoveRunning (0.96s)
22:02:23 [2019-10-15T22:02:23.257Z] === RUN TestRemoveInvalidContainer
22:02:23 [2019-10-15T22:02:23.257Z] --- PASS: TestRemoveInvalidContainer (0.01s)
22:02:23 [2019-10-15T22:02:23.257Z] === RUN TestRenameLinkedContainer
22:02:26 [2019-10-15T22:02:26.484Z] --- PASS: TestRenameLinkedContainer (3.47s)
22:02:26 [2019-10-15T22:02:26.484Z] === RUN TestRenameStoppedContainer
22:02:27 [2019-10-15T22:02:27.757Z] --- PASS: TestRenameStoppedContainer (0.93s)
22:02:27 [2019-10-15T22:02:27.757Z] === RUN TestRenameRunningContainerAndReuse
22:02:29 [2019-10-15T22:02:29.013Z] --- PASS: TestRenameRunningContainerAndReuse (1.61s)
22:02:29 [2019-10-15T22:02:29.013Z] === RUN TestRenameInvalidName
22:02:30 [2019-10-15T22:02:30.263Z] --- PASS: TestRenameInvalidName (0.96s)
22:02:30 [2019-10-15T22:02:30.263Z] === RUN TestRenameAnonymousContainer
22:02:33 [2019-10-15T22:02:33.003Z] --- PASS: TestRenameAnonymousContainer (2.91s)
22:02:33 [2019-10-15T22:02:33.003Z] === RUN TestRenameContainerWithSameName
22:02:33 [2019-10-15T22:02:33.818Z] --- PASS: TestRenameContainerWithSameName (0.87s)
22:02:33 [2019-10-15T22:02:33.818Z] === RUN TestRenameContainerWithLinkedContainer
22:02:35 [2019-10-15T22:02:35.645Z] --- PASS: TestRenameContainerWithLinkedContainer (1.68s)
22:02:35 [2019-10-15T22:02:35.646Z] === RUN TestResize
22:02:36 [2019-10-15T22:02:36.430Z] --- PASS: TestResize (0.96s)
22:02:36 [2019-10-15T22:02:36.430Z] === RUN TestResizeWithInvalidSize
22:02:37 [2019-10-15T22:02:37.216Z] --- PASS: TestResizeWithInvalidSize (0.86s)
22:02:37 [2019-10-15T22:02:37.216Z] === RUN TestResizeWhenContainerNotStarted
22:02:38 [2019-10-15T22:02:38.506Z] --- PASS: TestResizeWhenContainerNotStarted (0.89s)
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === RUN TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === PAUSE TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/stop-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === CONT TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/kill-daemon
22:02:38 [2019-10-15T22:02:38.506Z] === CONT TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/kill-daemon
22:02:40 [2019-10-15T22:02:40.353Z] === CONT TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/stop-daemon
22:02:40 [2019-10-15T22:02:40.353Z] === CONT TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/kill-daemon
22:02:41 [2019-10-15T22:02:41.874Z] === CONT TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/stop-daemon
22:02:41 [2019-10-15T22:02:41.874Z] === CONT TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/kill-daemon
22:02:43 [2019-10-15T22:02:43.280Z] === CONT TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/stop-daemon
22:02:43 [2019-10-15T22:02:43.280Z] === CONT TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/stop-daemon
22:02:45 [2019-10-15T22:02:45.751Z] === CONT TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/stop-daemon
22:02:45 [2019-10-15T22:02:45.751Z] === CONT TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/kill-daemon
22:02:49 [2019-10-15T22:02:49.024Z] === CONT TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/kill-daemon
22:02:49 [2019-10-15T22:02:49.024Z] === CONT TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/stop-daemon
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers (0.00s)
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/kill-daemon (1.95s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/kill-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/kill-daemon (2.17s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/kill-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/stop-daemon (1.47s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/stop-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/kill-daemon (1.44s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=true/container_created_should_not_be_restarted/kill-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/stop-daemon (1.51s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/stop-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/kill-daemon (1.55s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=false/container_created_should_not_be_restarted/kill-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/stop-daemon (2.26s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=true/container_with_restart=always/stop-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/stop-daemon (2.37s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/stop-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/stop-daemon (3.09s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/stop-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/kill-daemon (2.86s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=false/container_with_restart=always/kill-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/kill-daemon (2.26s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=true/container_without_restart_policy/kill-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] --- PASS: TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/stop-daemon (2.20s)
22:02:50 [2019-10-15T22:02:50.863Z] restart_test.go:67: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartKillContainers/live-restore=false/container_without_restart_policy/stop-daemon"
22:02:50 [2019-10-15T22:02:50.863Z] === RUN TestCgroupNamespacesRun
22:02:52 [2019-10-15T22:02:52.708Z] --- PASS: TestCgroupNamespacesRun (1.89s)
22:02:52 [2019-10-15T22:02:52.708Z] run_cgroupns_linux_test.go:30: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRun"
22:02:52 [2019-10-15T22:02:52.708Z] === RUN TestCgroupNamespacesRunPrivileged
22:02:54 [2019-10-15T22:02:54.745Z] --- PASS: TestCgroupNamespacesRunPrivileged (1.65s)
22:02:54 [2019-10-15T22:02:54.745Z] run_cgroupns_linux_test.go:30: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunPrivileged"
22:02:54 [2019-10-15T22:02:54.745Z] === RUN TestCgroupNamespacesRunDaemonHostMode
22:02:55 [2019-10-15T22:02:55.987Z] --- PASS: TestCgroupNamespacesRunDaemonHostMode (1.77s)
22:02:55 [2019-10-15T22:02:55.987Z] run_cgroupns_linux_test.go:30: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunDaemonHostMode"
22:02:55 [2019-10-15T22:02:55.987Z] === RUN TestCgroupNamespacesRunHostMode
22:02:58 [2019-10-15T22:02:58.406Z] --- PASS: TestCgroupNamespacesRunHostMode (1.82s)
22:02:58 [2019-10-15T22:02:58.406Z] run_cgroupns_linux_test.go:30: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunHostMode"
22:02:58 [2019-10-15T22:02:58.406Z] === RUN TestCgroupNamespacesRunPrivateMode
22:02:59 [2019-10-15T22:02:59.702Z] --- PASS: TestCgroupNamespacesRunPrivateMode (1.74s)
22:02:59 [2019-10-15T22:02:59.702Z] run_cgroupns_linux_test.go:30: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunPrivateMode"
22:02:59 [2019-10-15T22:02:59.702Z] === RUN TestCgroupNamespacesRunPrivilegedAndPrivate
22:03:00 [2019-10-15T22:03:00.500Z] --- PASS: TestCgroupNamespacesRunPrivilegedAndPrivate (0.85s)
22:03:00 [2019-10-15T22:03:00.501Z] run_cgroupns_linux_test.go:48: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunPrivilegedAndPrivate"
22:03:00 [2019-10-15T22:03:00.501Z] === RUN TestCgroupNamespacesRunInvalidMode
22:03:01 [2019-10-15T22:03:01.291Z] --- PASS: TestCgroupNamespacesRunInvalidMode (0.81s)
22:03:01 [2019-10-15T22:03:01.291Z] run_cgroupns_linux_test.go:48: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunInvalidMode"
22:03:01 [2019-10-15T22:03:01.291Z] === RUN TestCgroupNamespacesRunOlderClient
22:03:03 [2019-10-15T22:03:03.132Z] --- PASS: TestCgroupNamespacesRunOlderClient (1.82s)
22:03:03 [2019-10-15T22:03:03.132Z] run_cgroupns_linux_test.go:139: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCgroupNamespacesRunOlderClient"
22:03:03 [2019-10-15T22:03:03.132Z] === RUN TestKernelTCPMemory
22:03:04 [2019-10-15T22:03:04.587Z] --- PASS: TestKernelTCPMemory (1.17s)
22:03:04 [2019-10-15T22:03:04.587Z] === RUN TestNISDomainname
22:03:05 [2019-10-15T22:03:05.840Z] --- PASS: TestNISDomainname (1.27s)
22:03:05 [2019-10-15T22:03:05.840Z] === RUN TestHostnameDnsResolution
22:03:06 [2019-10-15T22:03:06.624Z] --- PASS: TestHostnameDnsResolution (1.09s)
22:03:06 [2019-10-15T22:03:06.624Z] === RUN TestStats
22:03:09 [2019-10-15T22:03:09.797Z] --- PASS: TestStats (2.69s)
22:03:09 [2019-10-15T22:03:09.797Z] === RUN TestStopContainerWithTimeout
22:03:09 [2019-10-15T22:03:09.797Z] === RUN TestStopContainerWithTimeout/0
22:03:09 [2019-10-15T22:03:09.797Z] === PAUSE TestStopContainerWithTimeout/0
22:03:09 [2019-10-15T22:03:09.797Z] === RUN TestStopContainerWithTimeout/1
22:03:09 [2019-10-15T22:03:09.797Z] === PAUSE TestStopContainerWithTimeout/1
22:03:09 [2019-10-15T22:03:09.797Z] === RUN TestStopContainerWithTimeout/3
22:03:09 [2019-10-15T22:03:09.797Z] === PAUSE TestStopContainerWithTimeout/3
22:03:09 [2019-10-15T22:03:09.797Z] === RUN TestStopContainerWithTimeout/-1
22:03:09 [2019-10-15T22:03:09.797Z] === PAUSE TestStopContainerWithTimeout/-1
22:03:09 [2019-10-15T22:03:09.797Z] === CONT TestStopContainerWithTimeout/0
22:03:09 [2019-10-15T22:03:09.797Z] === CONT TestStopContainerWithTimeout/3
22:03:10 [2019-10-15T22:03:10.581Z] === CONT TestStopContainerWithTimeout/-1
22:03:12 [2019-10-15T22:03:12.381Z] === CONT TestStopContainerWithTimeout/1
22:03:14 [2019-10-15T22:03:14.220Z] --- PASS: TestStopContainerWithTimeout (0.01s)
22:03:14 [2019-10-15T22:03:14.220Z] --- PASS: TestStopContainerWithTimeout/0 (1.12s)
22:03:14 [2019-10-15T22:03:14.220Z] --- PASS: TestStopContainerWithTimeout/3 (3.03s)
22:03:14 [2019-10-15T22:03:14.220Z] --- PASS: TestStopContainerWithTimeout/-1 (2.87s)
22:03:14 [2019-10-15T22:03:14.220Z] --- PASS: TestStopContainerWithTimeout/1 (1.94s)
22:03:14 [2019-10-15T22:03:14.220Z] === RUN TestDeleteDevicemapper
22:03:14 [2019-10-15T22:03:14.220Z] --- SKIP: TestDeleteDevicemapper (0.00s)
22:03:14 [2019-10-15T22:03:14.220Z] stop_linux_test.go:74: testEnv.DaemonInfo.Driver != "devicemapper"
22:03:14 [2019-10-15T22:03:14.220Z] === RUN TestStopContainerWithRestartPolicyAlways
22:03:17 [2019-10-15T22:03:17.722Z] --- PASS: TestStopContainerWithRestartPolicyAlways (3.15s)
22:03:17 [2019-10-15T22:03:17.722Z] === RUN TestUpdateMemory
22:03:17 [2019-10-15T22:03:17.722Z] --- SKIP: TestUpdateMemory (0.00s)
22:03:17 [2019-10-15T22:03:17.722Z] update_linux_test.go:23: !testEnv.DaemonInfo.SwapLimit
22:03:17 [2019-10-15T22:03:17.722Z] === RUN TestUpdateCPUQuota
22:03:19 [2019-10-15T22:03:19.521Z] --- PASS: TestUpdateCPUQuota (1.66s)
22:03:19 [2019-10-15T22:03:19.522Z] === RUN TestUpdatePidsLimit
22:03:19 [2019-10-15T22:03:19.522Z] === RUN TestUpdatePidsLimit/update_from_none
22:03:19 [2019-10-15T22:03:19.522Z] === RUN TestUpdatePidsLimit/no_change
22:03:19 [2019-10-15T22:03:19.903Z] === RUN TestUpdatePidsLimit/update_lower
22:03:20 [2019-10-15T22:03:20.718Z] === RUN TestUpdatePidsLimit/update_on_old_api_ignores_value
22:03:21 [2019-10-15T22:03:21.084Z] === RUN TestUpdatePidsLimit/unset_limit_with_zero
22:03:21 [2019-10-15T22:03:21.456Z] === RUN TestUpdatePidsLimit/unset_limit_with_minus_one
22:03:21 [2019-10-15T22:03:21.926Z] === RUN TestUpdatePidsLimit/unset_limit_with_minus_two
22:03:23 [2019-10-15T22:03:23.193Z] --- PASS: TestUpdatePidsLimit (4.17s)
22:03:23 [2019-10-15T22:03:23.193Z] --- PASS: TestUpdatePidsLimit/update_from_none (0.43s)
22:03:23 [2019-10-15T22:03:23.193Z] --- PASS: TestUpdatePidsLimit/no_change (0.44s)
22:03:23 [2019-10-15T22:03:23.194Z] --- PASS: TestUpdatePidsLimit/update_lower (0.44s)
22:03:23 [2019-10-15T22:03:23.194Z] --- PASS: TestUpdatePidsLimit/update_on_old_api_ignores_value (0.43s)
22:03:23 [2019-10-15T22:03:23.194Z] --- PASS: TestUpdatePidsLimit/unset_limit_with_zero (0.46s)
22:03:23 [2019-10-15T22:03:23.194Z] --- PASS: TestUpdatePidsLimit/unset_limit_with_minus_one (0.39s)
22:03:23 [2019-10-15T22:03:23.194Z] --- PASS: TestUpdatePidsLimit/unset_limit_with_minus_two (0.42s)
22:03:23 [2019-10-15T22:03:23.194Z] === RUN TestUpdateRestartPolicy
22:03:36 [2019-10-15T22:03:36.953Z] --- PASS: TestUpdateRestartPolicy (12.90s)
22:03:36 [2019-10-15T22:03:36.953Z] === RUN TestUpdateRestartWithAutoRemove
22:03:37 [2019-10-15T22:03:37.323Z] --- PASS: TestUpdateRestartWithAutoRemove (1.00s)
22:03:37 [2019-10-15T22:03:37.324Z] === RUN TestWaitNonBlocked
22:03:37 [2019-10-15T22:03:37.324Z] === RUN TestWaitNonBlocked/wait-nonblocking-exit-0
22:03:37 [2019-10-15T22:03:37.324Z] === PAUSE TestWaitNonBlocked/wait-nonblocking-exit-0
22:03:37 [2019-10-15T22:03:37.324Z] === RUN TestWaitNonBlocked/wait-nonblocking-exit-random
22:03:37 [2019-10-15T22:03:37.324Z] === PAUSE TestWaitNonBlocked/wait-nonblocking-exit-random
22:03:37 [2019-10-15T22:03:37.324Z] === CONT TestWaitNonBlocked/wait-nonblocking-exit-0
22:03:37 [2019-10-15T22:03:37.324Z] === CONT TestWaitNonBlocked/wait-nonblocking-exit-random
22:03:38 [2019-10-15T22:03:38.628Z] --- PASS: TestWaitNonBlocked (0.01s)
22:03:38 [2019-10-15T22:03:38.628Z] --- PASS: TestWaitNonBlocked/wait-nonblocking-exit-random (1.01s)
22:03:38 [2019-10-15T22:03:38.628Z] --- PASS: TestWaitNonBlocked/wait-nonblocking-exit-0 (1.10s)
22:03:38 [2019-10-15T22:03:38.628Z] === RUN TestWaitBlocked
22:03:38 [2019-10-15T22:03:38.628Z] === RUN TestWaitBlocked/test-wait-blocked-exit-zero
22:03:38 [2019-10-15T22:03:38.628Z] === PAUSE TestWaitBlocked/test-wait-blocked-exit-zero
22:03:38 [2019-10-15T22:03:38.628Z] === RUN TestWaitBlocked/test-wait-blocked-exit-random
22:03:38 [2019-10-15T22:03:38.628Z] === PAUSE TestWaitBlocked/test-wait-blocked-exit-random
22:03:38 [2019-10-15T22:03:38.628Z] === CONT TestWaitBlocked/test-wait-blocked-exit-zero
22:03:38 [2019-10-15T22:03:38.628Z] === CONT TestWaitBlocked/test-wait-blocked-exit-random
22:03:39 [2019-10-15T22:03:39.896Z] --- PASS: TestWaitBlocked (0.01s)
22:03:39 [2019-10-15T22:03:39.896Z] --- PASS: TestWaitBlocked/test-wait-blocked-exit-random (1.23s)
22:03:39 [2019-10-15T22:03:39.896Z] --- PASS: TestWaitBlocked/test-wait-blocked-exit-zero (1.33s)
22:03:39 [2019-10-15T22:03:39.896Z] === CONT TestContainerStartOnDaemonRestart
22:03:39 [2019-10-15T22:03:39.896Z] === CONT TestIpcModeOlderClient
22:03:39 [2019-10-15T22:03:39.896Z] --- PASS: TestIpcModeOlderClient (0.17s)
22:03:39 [2019-10-15T22:03:39.896Z] === CONT TestDaemonRestartIpcMode
22:03:43 [2019-10-15T22:03:43.151Z] --- PASS: TestContainerStartOnDaemonRestart (3.30s)
22:03:43 [2019-10-15T22:03:43.151Z] daemon_linux_test.go:35: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestContainerStartOnDaemonRestart"
22:03:43 [2019-10-15T22:03:43.538Z] --- PASS: TestDaemonRestartIpcMode (3.76s)
22:03:43 [2019-10-15T22:03:43.538Z] daemon_linux_test.go:89: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartIpcMode"
22:03:43 [2019-10-15T22:03:43.538Z] PASS
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] === Skipped
22:03:43 [2019-10-15T22:03:43.538Z] === SKIP: s390x.integration.container TestCheckpoint (0.00s)
22:03:43 [2019-10-15T22:03:43.538Z] checkpoint_test.go:33: TestCheckpoint is broken; see https://github.com/moby/moby/issues/38963
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] === SKIP: s390x.integration.container TestInspectOomKilledTrue (0.00s)
22:03:43 [2019-10-15T22:03:43.538Z] kill_test.go:151: testEnv.DaemonInfo.OSType == "windows" || !testEnv.DaemonInfo.MemoryLimit || !testEnv.DaemonInfo.SwapLimit
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] === SKIP: s390x.integration.container TestInspectOomKilledFalse (0.00s)
22:03:43 [2019-10-15T22:03:43.538Z] kill_test.go:169: testEnv.DaemonInfo.OSType == "windows" || !testEnv.DaemonInfo.MemoryLimit || !testEnv.DaemonInfo.SwapLimit
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] === SKIP: s390x.integration.container TestPauseFailsOnWindowsServerContainers (0.00s)
22:03:43 [2019-10-15T22:03:43.538Z] pause_test.go:54: testEnv.DaemonInfo.OSType != "windows" || testEnv.DaemonInfo.Isolation != "process"
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] === SKIP: s390x.integration.container TestDeleteDevicemapper (0.00s)
22:03:43 [2019-10-15T22:03:43.538Z] stop_linux_test.go:74: testEnv.DaemonInfo.Driver != "devicemapper"
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] === SKIP: s390x.integration.container TestUpdateMemory (0.00s)
22:03:43 [2019-10-15T22:03:43.538Z] update_linux_test.go:23: !testEnv.DaemonInfo.SwapLimit
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z]
22:03:43 [2019-10-15T22:03:43.538Z] DONE 197 tests, 6 skipped in 137.514s
22:03:43 [2019-10-15T22:03:43.538Z] Running /go/src/github.com/docker/docker/integration/image (s390x.integration.image) flags=-test.v -test.timeout=120m
22:03:43 [2019-10-15T22:03:43.538Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-image-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-image-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.image -t ./test.main -test.v -test.timeout=120m
22:03:43 [2019-10-15T22:03:43.538Z] ++ set -e
22:03:43 [2019-10-15T22:03:43.538Z] ++ '[' -n 0 ']'
22:03:43 [2019-10-15T22:03:43.538Z] ++ set -x
22:03:43 [2019-10-15T22:03:43.538Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-image-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-image-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.image -t ./test.main -test.v -test.timeout=120m
22:03:44 [2019-10-15T22:03:44.052Z] INFO: Testing against a local daemon
22:03:44 [2019-10-15T22:03:44.052Z] === RUN TestCommitInheritsEnv
22:03:44 [2019-10-15T22:03:44.470Z] --- PASS: TestCommitInheritsEnv (0.62s)
22:03:44 [2019-10-15T22:03:44.470Z] === RUN TestImportExtremelyLargeImageWorks
22:03:44 [2019-10-15T22:03:44.470Z] === PAUSE TestImportExtremelyLargeImageWorks
22:03:44 [2019-10-15T22:03:44.470Z] === RUN TestImagesFilterMultiReference
22:03:44 [2019-10-15T22:03:44.470Z] --- PASS: TestImagesFilterMultiReference (0.05s)
22:03:44 [2019-10-15T22:03:44.470Z] === RUN TestImagePullPlatformInvalid
22:03:44 [2019-10-15T22:03:44.470Z] --- PASS: TestImagePullPlatformInvalid (0.02s)
22:03:44 [2019-10-15T22:03:44.470Z] === RUN TestRemoveImageOrphaning
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestRemoveImageOrphaning (0.62s)
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestRemoveImageGarbageCollector
22:03:45 [2019-10-15T22:03:45.317Z] --- SKIP: TestRemoveImageGarbageCollector (0.00s)
22:03:45 [2019-10-15T22:03:45.317Z] remove_unix_test.go:36: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestTagUnprefixedRepoByNameOrName
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestTagUnprefixedRepoByNameOrName (0.03s)
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestTagInvalidReference
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestTagInvalidReference (0.01s)
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestTagValidPrefixedRepo
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestTagValidPrefixedRepo (0.03s)
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestTagExistedNameWithoutForce
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestTagExistedNameWithoutForce (0.02s)
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestTagOfficialNames
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestTagOfficialNames (0.04s)
22:03:45 [2019-10-15T22:03:45.317Z] === RUN TestTagMatchesDigest
22:03:45 [2019-10-15T22:03:45.317Z] --- PASS: TestTagMatchesDigest (0.05s)
22:03:45 [2019-10-15T22:03:45.317Z] === CONT TestImportExtremelyLargeImageWorks
22:05:39 [2019-10-15T22:05:39.990Z] --- PASS: TestImportExtremelyLargeImageWorks (114.30s)
22:05:39 [2019-10-15T22:05:39.990Z] import_test.go:26: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestImportExtremelyLargeImageWorks"
22:05:39 [2019-10-15T22:05:39.990Z] PASS
22:05:39 [2019-10-15T22:05:39.990Z]
22:05:39 [2019-10-15T22:05:39.990Z] === Skipped
22:05:39 [2019-10-15T22:05:39.990Z] === SKIP: s390x.integration.image TestRemoveImageGarbageCollector (0.00s)
22:05:39 [2019-10-15T22:05:39.990Z] remove_unix_test.go:36: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:05:39 [2019-10-15T22:05:39.990Z]
22:05:39 [2019-10-15T22:05:39.990Z]
22:05:39 [2019-10-15T22:05:39.990Z] DONE 12 tests, 1 skipped in 116.095s
22:05:39 [2019-10-15T22:05:39.990Z] Running /go/src/github.com/docker/docker/integration/network (s390x.integration.network) flags=-test.v -test.timeout=120m
22:05:39 [2019-10-15T22:05:39.990Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.network -t ./test.main -test.v -test.timeout=120m
22:05:39 [2019-10-15T22:05:39.990Z] ++ set -e
22:05:39 [2019-10-15T22:05:39.990Z] ++ '[' -n 0 ']'
22:05:39 [2019-10-15T22:05:39.990Z] ++ set -x
22:05:39 [2019-10-15T22:05:39.990Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.network -t ./test.main -test.v -test.timeout=120m
22:05:39 [2019-10-15T22:05:39.990Z] INFO: Testing against a local daemon
22:05:39 [2019-10-15T22:05:39.990Z] === RUN TestNetworkCreateDelete
22:05:39 [2019-10-15T22:05:39.990Z] --- PASS: TestNetworkCreateDelete (0.12s)
22:05:39 [2019-10-15T22:05:39.990Z] === RUN TestDockerNetworkDeletePreferID
22:05:40 [2019-10-15T22:05:40.352Z] --- PASS: TestDockerNetworkDeletePreferID (0.35s)
22:05:40 [2019-10-15T22:05:40.352Z] === RUN TestInspectNetwork
22:05:44 [2019-10-15T22:05:44.392Z] === RUN TestInspectNetwork/full_network_id
22:05:44 [2019-10-15T22:05:44.392Z] === RUN TestInspectNetwork/partial_network_id
22:05:44 [2019-10-15T22:05:44.392Z] === RUN TestInspectNetwork/network_name
22:05:44 [2019-10-15T22:05:44.392Z] === RUN TestInspectNetwork/network_name_and_swarm_scope
22:05:49 [2019-10-15T22:05:49.407Z] --- PASS: TestInspectNetwork (8.84s)
22:05:49 [2019-10-15T22:05:49.407Z] inspect_test.go:18: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestInspectNetwork"
22:05:49 [2019-10-15T22:05:49.407Z] --- PASS: TestInspectNetwork/full_network_id (0.00s)
22:05:49 [2019-10-15T22:05:49.407Z] --- PASS: TestInspectNetwork/partial_network_id (0.00s)
22:05:49 [2019-10-15T22:05:49.407Z] --- PASS: TestInspectNetwork/network_name (0.00s)
22:05:49 [2019-10-15T22:05:49.407Z] --- PASS: TestInspectNetwork/network_name_and_swarm_scope (0.00s)
22:05:49 [2019-10-15T22:05:49.407Z] === RUN TestRunContainerWithBridgeNone
22:05:52 [2019-10-15T22:05:52.140Z] --- PASS: TestRunContainerWithBridgeNone (2.76s)
22:05:52 [2019-10-15T22:05:52.140Z] network_test.go:25: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestRunContainerWithBridgeNone"
22:05:52 [2019-10-15T22:05:52.140Z] === RUN TestNetworkInvalidJSON
22:05:52 [2019-10-15T22:05:52.140Z] === RUN TestNetworkInvalidJSON//networks/create
22:05:52 [2019-10-15T22:05:52.140Z] === PAUSE TestNetworkInvalidJSON//networks/create
22:05:52 [2019-10-15T22:05:52.140Z] === RUN TestNetworkInvalidJSON//networks/bridge/connect
22:05:52 [2019-10-15T22:05:52.140Z] === PAUSE TestNetworkInvalidJSON//networks/bridge/connect
22:05:52 [2019-10-15T22:05:52.140Z] === RUN TestNetworkInvalidJSON//networks/bridge/disconnect
22:05:52 [2019-10-15T22:05:52.140Z] === PAUSE TestNetworkInvalidJSON//networks/bridge/disconnect
22:05:52 [2019-10-15T22:05:52.140Z] === CONT TestNetworkInvalidJSON//networks/create
22:05:52 [2019-10-15T22:05:52.140Z] === CONT TestNetworkInvalidJSON//networks/bridge/disconnect
22:05:52 [2019-10-15T22:05:52.140Z] === CONT TestNetworkInvalidJSON//networks/bridge/connect
22:05:52 [2019-10-15T22:05:52.140Z] --- PASS: TestNetworkInvalidJSON (0.01s)
22:05:52 [2019-10-15T22:05:52.140Z] --- PASS: TestNetworkInvalidJSON//networks/create (0.00s)
22:05:52 [2019-10-15T22:05:52.140Z] --- PASS: TestNetworkInvalidJSON//networks/bridge/disconnect (0.00s)
22:05:52 [2019-10-15T22:05:52.140Z] --- PASS: TestNetworkInvalidJSON//networks/bridge/connect (0.00s)
22:05:52 [2019-10-15T22:05:52.140Z] === RUN TestDaemonRestartWithLiveRestore
22:05:52 [2019-10-15T22:05:52.933Z] --- PASS: TestDaemonRestartWithLiveRestore (1.05s)
22:05:52 [2019-10-15T22:05:52.933Z] service_test.go:32: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartWithLiveRestore"
22:05:52 [2019-10-15T22:05:52.933Z] === RUN TestDaemonDefaultNetworkPools
22:05:53 [2019-10-15T22:05:53.734Z] --- PASS: TestDaemonDefaultNetworkPools (0.77s)
22:05:53 [2019-10-15T22:05:53.734Z] service_test.go:57: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonDefaultNetworkPools"
22:05:53 [2019-10-15T22:05:53.734Z] === RUN TestDaemonRestartWithExistingNetwork
22:05:54 [2019-10-15T22:05:54.992Z] --- PASS: TestDaemonRestartWithExistingNetwork (1.13s)
22:05:54 [2019-10-15T22:05:54.992Z] service_test.go:98: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartWithExistingNetwork"
22:05:54 [2019-10-15T22:05:54.992Z] === RUN TestDaemonRestartWithExistingNetworkWithDefaultPoolRange
22:05:56 [2019-10-15T22:05:56.270Z] --- PASS: TestDaemonRestartWithExistingNetworkWithDefaultPoolRange (1.24s)
22:05:56 [2019-10-15T22:05:56.270Z] service_test.go:131: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonRestartWithExistingNetworkWithDefaultPoolRange"
22:05:56 [2019-10-15T22:05:56.270Z] === RUN TestDaemonWithBipAndDefaultNetworkPool
22:05:56 [2019-10-15T22:05:56.637Z] --- PASS: TestDaemonWithBipAndDefaultNetworkPool (0.58s)
22:05:56 [2019-10-15T22:05:56.637Z] service_test.go:181: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonWithBipAndDefaultNetworkPool"
22:05:56 [2019-10-15T22:05:56.637Z] === RUN TestServiceWithPredefinedNetwork
22:05:59 [2019-10-15T22:05:59.845Z] --- PASS: TestServiceWithPredefinedNetwork (2.49s)
22:05:59 [2019-10-15T22:05:59.845Z] service_test.go:203: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceWithPredefinedNetwork"
22:05:59 [2019-10-15T22:05:59.845Z] === RUN TestServiceRemoveKeepsIngressNetwork
22:05:59 [2019-10-15T22:05:59.845Z] --- SKIP: TestServiceRemoveKeepsIngressNetwork (0.00s)
22:05:59 [2019-10-15T22:05:59.845Z] service_test.go:230: FLAKY_TEST
22:05:59 [2019-10-15T22:05:59.845Z] === RUN TestServiceWithDataPathPortInit
22:06:16 [2019-10-15T22:06:16.561Z] --- PASS: TestServiceWithDataPathPortInit (16.82s)
22:06:16 [2019-10-15T22:06:16.562Z] service_test.go:323: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceWithDataPathPortInit"
22:06:16 [2019-10-15T22:06:16.562Z] service_test.go:355: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceWithDataPathPortInit"
22:06:16 [2019-10-15T22:06:16.562Z] === RUN TestServiceWithDefaultAddressPoolInit
22:06:25 [2019-10-15T22:06:25.912Z] --- PASS: TestServiceWithDefaultAddressPoolInit (8.38s)
22:06:25 [2019-10-15T22:06:25.912Z] service_test.go:388: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceWithDefaultAddressPoolInit"
22:06:25 [2019-10-15T22:06:25.912Z] service_test.go:418: TestServiceWithDefaultAddressPoolInit: NetworkInspect: {Name:sthiraTestServiceWithDefaultAddressPoolInit ID:asrl14iqujawmd06h5t0jof9q Created:2019-10-15 22:06:17.564679874 +0000 UTC Scope:swarm Driver:overlay EnableIPv6:false IPAM:{Driver:default Options:map[] Config:[{Subnet:20.20.0.0/24 IPRange: Gateway:20.20.0.1 AuxAddress:map[]}]} Internal:false Attachable:false Ingress:false ConfigFrom:{Network:} ConfigOnly:false Containers:map[95367b6e775bd3b4be75e5996beda791aa6eac978b826b4ed3a7c8443fcba52e:{Name:TestServiceTestServiceWithDefaultAddressPoolInit.1.hwaisx398ckvviwjt1zvxxb3t EndpointID:2a6cab2c022d495713e32ad0a704fa9c91e3459e4b327391e7d9291c004b420c MacAddress:02:42:14:14:00:03 IPv4Address:20.20.0.3/24 IPv6Address:} lb-sthiraTestServiceWithDefaultAddressPoolInit:{Name:sthiraTestServiceWithDefaultAddressPoolInit-endpoint EndpointID:7a1b87d65e0077f5056b7f7fe8ce9c92a18c85622dffc991d75093808207590b MacAddress:02:42:14:14:00:04 IPv4Address:20.20.0.4/24 IPv6Address:}] Options:map[com.docker.network.driver.overlay.vxlanid_list:4097] Labels:map[] Peers:[{Name:d429820460ea IP:127.0.0.1}] Services:map[TestServiceTestServiceWithDefaultAddressPoolInit:{VIP:20.20.0.2 Ports:[] LocalLBIndex:256 Tasks:[{Name:TestServiceTestServiceWithDefaultAddressPoolInit.1.hwaisx398ckvviwjt1zvxxb3t EndpointID:2a6cab2c022d495713e32ad0a704fa9c91e3459e4b327391e7d9291c004b420c EndpointIP:20.20.0.3 Info:map[Host IP:127.0.0.1]}]}]}
22:06:25 [2019-10-15T22:06:25.912Z] PASS
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z] === Skipped
22:06:25 [2019-10-15T22:06:25.912Z] === SKIP: s390x.integration.network TestServiceRemoveKeepsIngressNetwork (0.00s)
22:06:25 [2019-10-15T22:06:25.912Z] service_test.go:230: FLAKY_TEST
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z] DONE 21 tests, 1 skipped in 44.752s
22:06:25 [2019-10-15T22:06:25.912Z] Running /go/src/github.com/docker/docker/integration/network/ipvlan (s390x.integration.network.ipvlan) flags=-test.v -test.timeout=120m
22:06:25 [2019-10-15T22:06:25.912Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-ipvlan-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-ipvlan-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.network.ipvlan -t ./test.main -test.v -test.timeout=120m
22:06:25 [2019-10-15T22:06:25.912Z] ++ set -e
22:06:25 [2019-10-15T22:06:25.912Z] ++ '[' -n 0 ']'
22:06:25 [2019-10-15T22:06:25.912Z] ++ set -x
22:06:25 [2019-10-15T22:06:25.912Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-ipvlan-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-ipvlan-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.network.ipvlan -t ./test.main -test.v -test.timeout=120m
22:06:25 [2019-10-15T22:06:25.912Z] INFO: Testing against a local daemon
22:06:25 [2019-10-15T22:06:25.912Z] === RUN TestDockerNetworkIpvlanPersistance
22:06:25 [2019-10-15T22:06:25.912Z] --- SKIP: TestDockerNetworkIpvlanPersistance (0.01s)
22:06:25 [2019-10-15T22:06:25.912Z] ipvlan_test.go:26: !ipvlanKernelSupport(t): Kernel doesn't support ipvlan
22:06:25 [2019-10-15T22:06:25.912Z] === RUN TestDockerNetworkIpvlan
22:06:25 [2019-10-15T22:06:25.912Z] --- SKIP: TestDockerNetworkIpvlan (0.00s)
22:06:25 [2019-10-15T22:06:25.912Z] ipvlan_test.go:53: !ipvlanKernelSupport(t): Kernel doesn't support ipvlan
22:06:25 [2019-10-15T22:06:25.912Z] PASS
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z] === Skipped
22:06:25 [2019-10-15T22:06:25.912Z] === SKIP: s390x.integration.network.ipvlan TestDockerNetworkIpvlanPersistance (0.01s)
22:06:25 [2019-10-15T22:06:25.912Z] ipvlan_test.go:26: !ipvlanKernelSupport(t): Kernel doesn't support ipvlan
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z] === SKIP: s390x.integration.network.ipvlan TestDockerNetworkIpvlan (0.00s)
22:06:25 [2019-10-15T22:06:25.912Z] ipvlan_test.go:53: !ipvlanKernelSupport(t): Kernel doesn't support ipvlan
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z]
22:06:25 [2019-10-15T22:06:25.912Z] DONE 2 tests, 2 skipped in 0.111s
22:06:25 [2019-10-15T22:06:25.912Z] Running /go/src/github.com/docker/docker/integration/network/macvlan (s390x.integration.network.macvlan) flags=-test.v -test.timeout=120m
22:06:25 [2019-10-15T22:06:25.912Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-macvlan-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-macvlan-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.network.macvlan -t ./test.main -test.v -test.timeout=120m
22:06:25 [2019-10-15T22:06:25.912Z] ++ set -e
22:06:25 [2019-10-15T22:06:25.912Z] ++ '[' -n 0 ']'
22:06:25 [2019-10-15T22:06:25.912Z] ++ set -x
22:06:25 [2019-10-15T22:06:25.912Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-macvlan-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-network-macvlan-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.network.macvlan -t ./test.main -test.v -test.timeout=120m
22:06:25 [2019-10-15T22:06:25.912Z] INFO: Testing against a local daemon
22:06:25 [2019-10-15T22:06:25.912Z] === RUN TestDockerNetworkMacvlanPersistance
22:06:26 [2019-10-15T22:06:26.323Z] --- PASS: TestDockerNetworkMacvlanPersistance (1.37s)
22:06:26 [2019-10-15T22:06:26.323Z] macvlan_test.go:24: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlanPersistance"
22:06:26 [2019-10-15T22:06:26.323Z] === RUN TestDockerNetworkMacvlan
22:06:27 [2019-10-15T22:06:27.277Z] === RUN TestDockerNetworkMacvlan/Subinterface
22:06:28 [2019-10-15T22:06:28.140Z] === RUN TestDockerNetworkMacvlan/OverlapParent
22:06:28 [2019-10-15T22:06:28.944Z] === RUN TestDockerNetworkMacvlan/NilParent
22:06:31 [2019-10-15T22:06:31.380Z] === RUN TestDockerNetworkMacvlan/InternalMode
22:06:35 [2019-10-15T22:06:35.397Z] === RUN TestDockerNetworkMacvlan/MultiSubnet
22:06:40 [2019-10-15T22:06:40.748Z] === RUN TestDockerNetworkMacvlan/Addressing
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan (15.32s)
22:06:41 [2019-10-15T22:06:41.543Z] macvlan_test.go:70: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlan"
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan/Subinterface (0.06s)
22:06:41 [2019-10-15T22:06:41.543Z] macvlan_test.go:70: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlan"
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan/OverlapParent (0.08s)
22:06:41 [2019-10-15T22:06:41.543Z] macvlan_test.go:70: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlan"
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan/NilParent (1.22s)
22:06:41 [2019-10-15T22:06:41.543Z] macvlan_test.go:70: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlan"
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan/InternalMode (2.22s)
22:06:41 [2019-10-15T22:06:41.543Z] macvlan_test.go:70: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlan"
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan/MultiSubnet (3.22s)
22:06:41 [2019-10-15T22:06:41.543Z] macvlan_test.go:70: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkMacvlan"
22:06:41 [2019-10-15T22:06:41.543Z] --- PASS: TestDockerNetworkMacvlan/Addressing (0.96s)
22:06:41 [2019-10-15T22:06:41.543Z] PASS
22:06:41 [2019-10-15T22:06:41.543Z]
22:06:41 [2019-10-15T22:06:41.543Z] DONE 8 tests in 16.805s
22:06:41 [2019-10-15T22:06:41.543Z] Running /go/src/github.com/docker/docker/integration/plugin (s390x.integration.plugin) flags=-test.v -test.timeout=120m
22:06:41 [2019-10-15T22:06:41.543Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin -t ./test.main -test.v -test.timeout=120m
22:06:41 [2019-10-15T22:06:41.543Z] ++ set -e
22:06:41 [2019-10-15T22:06:41.543Z] ++ '[' -n 0 ']'
22:06:41 [2019-10-15T22:06:41.543Z] ++ set -x
22:06:41 [2019-10-15T22:06:41.543Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin -t ./test.main -test.v -test.timeout=120m
22:06:41 [2019-10-15T22:06:41.543Z] testing: warning: no tests to run
22:06:41 [2019-10-15T22:06:41.543Z] PASS
22:06:41 [2019-10-15T22:06:41.543Z]
22:06:41 [2019-10-15T22:06:41.543Z] DONE 0 tests in 0.064s
22:06:41 [2019-10-15T22:06:41.543Z] Running /go/src/github.com/docker/docker/integration/plugin/authz (s390x.integration.plugin.authz) flags=-test.v -test.timeout=120m
22:06:41 [2019-10-15T22:06:41.543Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-authz-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-authz-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.authz -t ./test.main -test.v -test.timeout=120m
22:06:41 [2019-10-15T22:06:41.543Z] ++ set -e
22:06:41 [2019-10-15T22:06:41.543Z] ++ '[' -n 0 ']'
22:06:41 [2019-10-15T22:06:41.543Z] ++ set -x
22:06:41 [2019-10-15T22:06:41.543Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-authz-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-authz-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.authz -t ./test.main -test.v -test.timeout=120m
22:06:41 [2019-10-15T22:06:41.915Z] INFO: Testing against a local daemon
22:06:41 [2019-10-15T22:06:41.915Z] === RUN TestAuthZPluginAllowRequest
22:06:43 [2019-10-15T22:06:43.728Z] --- PASS: TestAuthZPluginAllowRequest (1.69s)
22:06:43 [2019-10-15T22:06:43.728Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginAllowRequest"
22:06:43 [2019-10-15T22:06:43.728Z] === RUN TestAuthZPluginTLS
22:06:44 [2019-10-15T22:06:44.102Z] --- PASS: TestAuthZPluginTLS (0.55s)
22:06:44 [2019-10-15T22:06:44.102Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginTLS"
22:06:44 [2019-10-15T22:06:44.102Z] === RUN TestAuthZPluginDenyRequest
22:06:44 [2019-10-15T22:06:44.488Z] --- PASS: TestAuthZPluginDenyRequest (0.53s)
22:06:44 [2019-10-15T22:06:44.488Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginDenyRequest"
22:06:44 [2019-10-15T22:06:44.488Z] === RUN TestAuthZPluginAPIDenyResponse
22:06:45 [2019-10-15T22:06:45.279Z] --- PASS: TestAuthZPluginAPIDenyResponse (0.53s)
22:06:45 [2019-10-15T22:06:45.279Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginAPIDenyResponse"
22:06:45 [2019-10-15T22:06:45.279Z] === RUN TestAuthZPluginDenyResponse
22:06:45 [2019-10-15T22:06:45.649Z] --- PASS: TestAuthZPluginDenyResponse (0.55s)
22:06:45 [2019-10-15T22:06:45.649Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginDenyResponse"
22:06:45 [2019-10-15T22:06:45.649Z] === RUN TestAuthZPluginAllowEventStream
22:06:47 [2019-10-15T22:06:47.488Z] --- PASS: TestAuthZPluginAllowEventStream (1.69s)
22:06:47 [2019-10-15T22:06:47.488Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginAllowEventStream"
22:06:47 [2019-10-15T22:06:47.488Z] === RUN TestAuthZPluginErrorResponse
22:06:47 [2019-10-15T22:06:47.854Z] --- PASS: TestAuthZPluginErrorResponse (0.55s)
22:06:47 [2019-10-15T22:06:47.854Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginErrorResponse"
22:06:47 [2019-10-15T22:06:47.854Z] === RUN TestAuthZPluginErrorRequest
22:06:48 [2019-10-15T22:06:48.645Z] --- PASS: TestAuthZPluginErrorRequest (0.56s)
22:06:48 [2019-10-15T22:06:48.645Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginErrorRequest"
22:06:48 [2019-10-15T22:06:48.645Z] === RUN TestAuthZPluginEnsureNoDuplicatePluginRegistration
22:06:49 [2019-10-15T22:06:49.049Z] --- PASS: TestAuthZPluginEnsureNoDuplicatePluginRegistration (0.55s)
22:06:49 [2019-10-15T22:06:49.049Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginEnsureNoDuplicatePluginRegistration"
22:06:49 [2019-10-15T22:06:49.049Z] === RUN TestAuthZPluginEnsureLoadImportWorking
22:06:51 [2019-10-15T22:06:51.683Z] --- PASS: TestAuthZPluginEnsureLoadImportWorking (2.22s)
22:06:51 [2019-10-15T22:06:51.684Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginEnsureLoadImportWorking"
22:06:51 [2019-10-15T22:06:51.684Z] === RUN TestAuthzPluginEnsureContainerCopyToFrom
22:06:53 [2019-10-15T22:06:53.502Z] --- PASS: TestAuthzPluginEnsureContainerCopyToFrom (2.24s)
22:06:53 [2019-10-15T22:06:53.502Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthzPluginEnsureContainerCopyToFrom"
22:06:53 [2019-10-15T22:06:53.502Z] === RUN TestAuthZPluginHeader
22:06:54 [2019-10-15T22:06:54.286Z] --- PASS: TestAuthZPluginHeader (0.88s)
22:06:54 [2019-10-15T22:06:54.286Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginHeader"
22:06:54 [2019-10-15T22:06:54.286Z] === RUN TestAuthZPluginV2AllowNonVolumeRequest
22:06:54 [2019-10-15T22:06:54.286Z] --- SKIP: TestAuthZPluginV2AllowNonVolumeRequest (0.01s)
22:06:54 [2019-10-15T22:06:54.286Z] authz_plugin_v2_test.go:43: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:06:54 [2019-10-15T22:06:54.286Z] === RUN TestAuthZPluginV2Disable
22:06:54 [2019-10-15T22:06:54.286Z] --- SKIP: TestAuthZPluginV2Disable (0.00s)
22:06:54 [2019-10-15T22:06:54.286Z] authz_plugin_v2_test.go:65: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:06:54 [2019-10-15T22:06:54.286Z] === RUN TestAuthZPluginV2RejectVolumeRequests
22:06:54 [2019-10-15T22:06:54.286Z] --- SKIP: TestAuthZPluginV2RejectVolumeRequests (0.00s)
22:06:54 [2019-10-15T22:06:54.286Z] authz_plugin_v2_test.go:91: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:06:54 [2019-10-15T22:06:54.286Z] === RUN TestAuthZPluginV2BadManifestFailsDaemonStart
22:06:54 [2019-10-15T22:06:54.286Z] --- SKIP: TestAuthZPluginV2BadManifestFailsDaemonStart (0.00s)
22:06:54 [2019-10-15T22:06:54.286Z] authz_plugin_v2_test.go:126: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:06:54 [2019-10-15T22:06:54.286Z] === RUN TestAuthZPluginV2NonexistentFailsDaemonStart
22:07:14 [2019-10-15T22:07:14.128Z] --- PASS: TestAuthZPluginV2NonexistentFailsDaemonStart (16.86s)
22:07:14 [2019-10-15T22:07:14.128Z] main_test.go:54: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestAuthZPluginV2NonexistentFailsDaemonStart"
22:07:14 [2019-10-15T22:07:14.128Z] PASS
22:07:14 [2019-10-15T22:07:14.128Z]
22:07:14 [2019-10-15T22:07:14.128Z] === Skipped
22:07:14 [2019-10-15T22:07:14.128Z] === SKIP: s390x.integration.plugin.authz TestAuthZPluginV2AllowNonVolumeRequest (0.01s)
22:07:14 [2019-10-15T22:07:14.128Z] authz_plugin_v2_test.go:43: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:07:14 [2019-10-15T22:07:14.128Z]
22:07:14 [2019-10-15T22:07:14.128Z] === SKIP: s390x.integration.plugin.authz TestAuthZPluginV2Disable (0.00s)
22:07:14 [2019-10-15T22:07:14.128Z] authz_plugin_v2_test.go:65: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:07:14 [2019-10-15T22:07:14.128Z]
22:07:14 [2019-10-15T22:07:14.128Z] === SKIP: s390x.integration.plugin.authz TestAuthZPluginV2RejectVolumeRequests (0.00s)
22:07:14 [2019-10-15T22:07:14.128Z] authz_plugin_v2_test.go:91: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:07:14 [2019-10-15T22:07:14.128Z]
22:07:14 [2019-10-15T22:07:14.128Z] === SKIP: s390x.integration.plugin.authz TestAuthZPluginV2BadManifestFailsDaemonStart (0.00s)
22:07:14 [2019-10-15T22:07:14.128Z] authz_plugin_v2_test.go:126: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:07:14 [2019-10-15T22:07:14.128Z]
22:07:14 [2019-10-15T22:07:14.128Z]
22:07:14 [2019-10-15T22:07:14.128Z] DONE 17 tests, 4 skipped in 29.689s
22:07:14 [2019-10-15T22:07:14.128Z] Running /go/src/github.com/docker/docker/integration/plugin/common (s390x.integration.plugin.common) flags=-test.v -test.timeout=120m
22:07:14 [2019-10-15T22:07:14.129Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-common-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-common-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.common -t ./test.main -test.v -test.timeout=120m
22:07:14 [2019-10-15T22:07:14.129Z] ++ set -e
22:07:14 [2019-10-15T22:07:14.129Z] ++ '[' -n 0 ']'
22:07:14 [2019-10-15T22:07:14.129Z] ++ set -x
22:07:14 [2019-10-15T22:07:14.129Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-common-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-common-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.common -t ./test.main -test.v -test.timeout=120m
22:07:14 [2019-10-15T22:07:14.129Z] INFO: Testing against a local daemon
22:07:14 [2019-10-15T22:07:14.129Z] === RUN TestPluginInvalidJSON
22:07:14 [2019-10-15T22:07:14.129Z] === RUN TestPluginInvalidJSON//plugins/foobar/set
22:07:14 [2019-10-15T22:07:14.129Z] === PAUSE TestPluginInvalidJSON//plugins/foobar/set
22:07:14 [2019-10-15T22:07:14.129Z] === CONT TestPluginInvalidJSON//plugins/foobar/set
22:07:14 [2019-10-15T22:07:14.129Z] --- PASS: TestPluginInvalidJSON (0.02s)
22:07:14 [2019-10-15T22:07:14.129Z] --- PASS: TestPluginInvalidJSON//plugins/foobar/set (0.00s)
22:07:14 [2019-10-15T22:07:14.129Z] PASS
22:07:14 [2019-10-15T22:07:14.129Z]
22:07:14 [2019-10-15T22:07:14.129Z] DONE 2 tests in 0.141s
22:07:14 [2019-10-15T22:07:14.129Z] Running /go/src/github.com/docker/docker/integration/plugin/graphdriver (s390x.integration.plugin.graphdriver) flags=-test.v -test.timeout=120m
22:07:14 [2019-10-15T22:07:14.129Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-graphdriver-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-graphdriver-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.graphdriver -t ./test.main -test.v -test.timeout=120m
22:07:14 [2019-10-15T22:07:14.129Z] ++ set -e
22:07:14 [2019-10-15T22:07:14.129Z] ++ '[' -n 0 ']'
22:07:14 [2019-10-15T22:07:14.129Z] ++ set -x
22:07:14 [2019-10-15T22:07:14.129Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-graphdriver-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-graphdriver-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.graphdriver -t ./test.main -test.v -test.timeout=120m
22:07:14 [2019-10-15T22:07:14.129Z] INFO: Testing against a local daemon
22:07:14 [2019-10-15T22:07:14.129Z] === RUN TestExternalGraphDriver
22:07:14 [2019-10-15T22:07:14.129Z] === RUN TestExternalGraphDriver/json
22:07:14 [2019-10-15T22:07:14.129Z] === RUN TestExternalGraphDriver/spec
22:07:15 [2019-10-15T22:07:15.945Z] === RUN TestExternalGraphDriver/pull
22:07:19 [2019-10-15T22:07:19.981Z] --- PASS: TestExternalGraphDriver (8.32s)
22:07:19 [2019-10-15T22:07:19.981Z] external_test.go:57: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestExternalGraphDriver"
22:07:19 [2019-10-15T22:07:19.981Z] --- PASS: TestExternalGraphDriver/json (2.04s)
22:07:19 [2019-10-15T22:07:19.981Z] --- PASS: TestExternalGraphDriver/spec (2.07s)
22:07:19 [2019-10-15T22:07:19.981Z] --- PASS: TestExternalGraphDriver/pull (4.04s)
22:07:19 [2019-10-15T22:07:19.981Z] === RUN TestGraphdriverPluginV2
22:07:19 [2019-10-15T22:07:19.981Z] --- SKIP: TestGraphdriverPluginV2 (0.06s)
22:07:19 [2019-10-15T22:07:19.981Z] external_test.go:410: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:07:19 [2019-10-15T22:07:19.981Z] PASS
22:07:19 [2019-10-15T22:07:19.981Z]
22:07:19 [2019-10-15T22:07:19.981Z] === Skipped
22:07:19 [2019-10-15T22:07:19.981Z] === SKIP: s390x.integration.plugin.graphdriver TestGraphdriverPluginV2 (0.06s)
22:07:19 [2019-10-15T22:07:19.981Z] external_test.go:410: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:07:19 [2019-10-15T22:07:19.981Z]
22:07:19 [2019-10-15T22:07:19.981Z]
22:07:19 [2019-10-15T22:07:19.981Z] DONE 5 tests, 1 skipped in 8.544s
22:07:19 [2019-10-15T22:07:19.981Z] Running /go/src/github.com/docker/docker/integration/plugin/logging (s390x.integration.plugin.logging) flags=-test.v -test.timeout=120m
22:07:19 [2019-10-15T22:07:19.981Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-logging-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-logging-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.logging -t ./test.main -test.v -test.timeout=120m
22:07:19 [2019-10-15T22:07:19.981Z] ++ set -e
22:07:19 [2019-10-15T22:07:19.981Z] ++ '[' -n 0 ']'
22:07:19 [2019-10-15T22:07:19.981Z] ++ set -x
22:07:19 [2019-10-15T22:07:19.981Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-logging-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-logging-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.logging -t ./test.main -test.v -test.timeout=120m
22:07:19 [2019-10-15T22:07:19.981Z] INFO: Testing against a local daemon
22:07:19 [2019-10-15T22:07:19.981Z] === RUN TestContinueAfterPluginCrash
22:07:19 [2019-10-15T22:07:19.981Z] === PAUSE TestContinueAfterPluginCrash
22:07:19 [2019-10-15T22:07:19.981Z] === RUN TestDaemonStartWithLogOpt
22:07:19 [2019-10-15T22:07:19.981Z] === PAUSE TestDaemonStartWithLogOpt
22:07:19 [2019-10-15T22:07:19.981Z] === CONT TestContinueAfterPluginCrash
22:07:19 [2019-10-15T22:07:19.981Z] === CONT TestDaemonStartWithLogOpt
22:07:31 [2019-10-15T22:07:31.448Z] --- PASS: TestDaemonStartWithLogOpt (11.09s)
22:07:31 [2019-10-15T22:07:31.448Z] validation_test.go:23: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDaemonStartWithLogOpt"
22:07:36 [2019-10-15T22:07:36.569Z] --- PASS: TestContinueAfterPluginCrash (16.00s)
22:07:36 [2019-10-15T22:07:36.569Z] logging_linux_test.go:22: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestContinueAfterPluginCrash"
22:07:36 [2019-10-15T22:07:36.569Z] PASS
22:07:36 [2019-10-15T22:07:36.569Z]
22:07:36 [2019-10-15T22:07:36.569Z] DONE 2 tests in 16.182s
22:07:36 [2019-10-15T22:07:36.569Z] Running /go/src/github.com/docker/docker/integration/plugin/volumes (s390x.integration.plugin.volumes) flags=-test.v -test.timeout=120m
22:07:36 [2019-10-15T22:07:36.569Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-volumes-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-volumes-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.volumes -t ./test.main -test.v -test.timeout=120m
22:07:36 [2019-10-15T22:07:36.569Z] ++ set -e
22:07:36 [2019-10-15T22:07:36.569Z] ++ '[' -n 0 ']'
22:07:36 [2019-10-15T22:07:36.569Z] ++ set -x
22:07:36 [2019-10-15T22:07:36.569Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-volumes-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-plugin-volumes-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.plugin.volumes -t ./test.main -test.v -test.timeout=120m
22:07:36 [2019-10-15T22:07:36.569Z] INFO: Testing against a local daemon
22:07:36 [2019-10-15T22:07:36.569Z] === RUN TestPluginWithDevMounts
22:07:36 [2019-10-15T22:07:36.569Z] === PAUSE TestPluginWithDevMounts
22:07:36 [2019-10-15T22:07:36.569Z] === CONT TestPluginWithDevMounts
22:07:38 [2019-10-15T22:07:38.409Z] --- PASS: TestPluginWithDevMounts (2.05s)
22:07:38 [2019-10-15T22:07:38.409Z] mounts_test.go:23: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestPluginWithDevMounts"
22:07:38 [2019-10-15T22:07:38.409Z] PASS
22:07:38 [2019-10-15T22:07:38.409Z]
22:07:38 [2019-10-15T22:07:38.409Z] DONE 1 tests in 2.191s
22:07:38 [2019-10-15T22:07:38.409Z] Running /go/src/github.com/docker/docker/integration/secret (s390x.integration.secret) flags=-test.v -test.timeout=120m
22:07:38 [2019-10-15T22:07:38.409Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-secret-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-secret-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.secret -t ./test.main -test.v -test.timeout=120m
22:07:38 [2019-10-15T22:07:38.409Z] ++ set -e
22:07:38 [2019-10-15T22:07:38.409Z] ++ '[' -n 0 ']'
22:07:38 [2019-10-15T22:07:38.409Z] ++ set -x
22:07:38 [2019-10-15T22:07:38.409Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-secret-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-secret-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.secret -t ./test.main -test.v -test.timeout=120m
22:07:38 [2019-10-15T22:07:38.409Z] INFO: Testing against a local daemon
22:07:38 [2019-10-15T22:07:38.409Z] === RUN TestSecretInspect
22:07:41 [2019-10-15T22:07:41.586Z] --- PASS: TestSecretInspect (2.77s)
22:07:41 [2019-10-15T22:07:41.586Z] secret_test.go:25: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestSecretInspect"
22:07:41 [2019-10-15T22:07:41.586Z] === RUN TestSecretList
22:07:44 [2019-10-15T22:07:44.173Z] --- PASS: TestSecretList (2.97s)
22:07:44 [2019-10-15T22:07:44.173Z] secret_test.go:48: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestSecretList"
22:07:44 [2019-10-15T22:07:44.173Z] === RUN TestSecretsCreateAndDelete
22:07:47 [2019-10-15T22:07:47.357Z] --- PASS: TestSecretsCreateAndDelete (2.90s)
22:07:47 [2019-10-15T22:07:47.357Z] secret_test.go:125: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestSecretsCreateAndDelete"
22:07:47 [2019-10-15T22:07:47.357Z] === RUN TestSecretsUpdate
22:07:49 [2019-10-15T22:07:49.793Z] --- PASS: TestSecretsUpdate (2.65s)
22:07:49 [2019-10-15T22:07:49.793Z] secret_test.go:172: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestSecretsUpdate"
22:07:49 [2019-10-15T22:07:49.793Z] === RUN TestTemplatedSecret
22:07:53 [2019-10-15T22:07:53.838Z] --- PASS: TestTemplatedSecret (3.53s)
22:07:53 [2019-10-15T22:07:53.838Z] secret_test.go:221: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestTemplatedSecret"
22:07:53 [2019-10-15T22:07:53.838Z] === RUN TestSecretCreateResolve
22:07:56 [2019-10-15T22:07:56.454Z] --- PASS: TestSecretCreateResolve (2.89s)
22:07:56 [2019-10-15T22:07:56.454Z] secret_test.go:342: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestSecretCreateResolve"
22:07:56 [2019-10-15T22:07:56.454Z] PASS
22:07:56 [2019-10-15T22:07:56.454Z]
22:07:56 [2019-10-15T22:07:56.454Z] DONE 6 tests in 17.818s
22:07:56 [2019-10-15T22:07:56.454Z] Running /go/src/github.com/docker/docker/integration/service (s390x.integration.service) flags=-test.v -test.timeout=120m
22:07:56 [2019-10-15T22:07:56.454Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-service-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-service-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.service -t ./test.main -test.v -test.timeout=120m
22:07:56 [2019-10-15T22:07:56.454Z] ++ set -e
22:07:56 [2019-10-15T22:07:56.454Z] ++ '[' -n 0 ']'
22:07:56 [2019-10-15T22:07:56.454Z] ++ set -x
22:07:56 [2019-10-15T22:07:56.454Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-service-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-service-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.service -t ./test.main -test.v -test.timeout=120m
22:07:56 [2019-10-15T22:07:56.454Z] INFO: Testing against a local daemon
22:07:56 [2019-10-15T22:07:56.454Z] === RUN TestServiceCreateInit
22:07:56 [2019-10-15T22:07:56.454Z] === RUN TestServiceCreateInit/daemonInitDisabled
22:08:01 [2019-10-15T22:08:01.570Z] === RUN TestServiceCreateInit/daemonInitEnabled
22:08:06 [2019-10-15T22:08:06.643Z] --- PASS: TestServiceCreateInit (9.94s)
22:08:06 [2019-10-15T22:08:06.643Z] --- PASS: TestServiceCreateInit/daemonInitDisabled (5.06s)
22:08:06 [2019-10-15T22:08:06.643Z] create_test.go:38: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceCreateInit/daemonInitDisabled"
22:08:06 [2019-10-15T22:08:06.643Z] --- PASS: TestServiceCreateInit/daemonInitEnabled (4.86s)
22:08:06 [2019-10-15T22:08:06.643Z] create_test.go:38: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceCreateInit/daemonInitEnabled"
22:08:06 [2019-10-15T22:08:06.643Z] === RUN TestCreateServiceMultipleTimes
22:08:26 [2019-10-15T22:08:26.426Z] --- PASS: TestCreateServiceMultipleTimes (17.03s)
22:08:26 [2019-10-15T22:08:26.426Z] create_test.go:80: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceMultipleTimes"
22:08:26 [2019-10-15T22:08:26.426Z] === RUN TestCreateServiceConflict
22:08:26 [2019-10-15T22:08:26.426Z] --- PASS: TestCreateServiceConflict (2.30s)
22:08:26 [2019-10-15T22:08:26.426Z] create_test.go:129: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceConflict"
22:08:26 [2019-10-15T22:08:26.426Z] === RUN TestCreateServiceMaxReplicas
22:08:29 [2019-10-15T22:08:29.657Z] --- PASS: TestCreateServiceMaxReplicas (3.66s)
22:08:29 [2019-10-15T22:08:29.657Z] create_test.go:150: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceMaxReplicas"
22:08:29 [2019-10-15T22:08:29.657Z] === RUN TestCreateWithDuplicateNetworkNames
22:08:43 [2019-10-15T22:08:43.442Z] --- PASS: TestCreateWithDuplicateNetworkNames (13.91s)
22:08:43 [2019-10-15T22:08:43.442Z] create_test.go:171: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateWithDuplicateNetworkNames"
22:08:43 [2019-10-15T22:08:43.442Z] === RUN TestCreateServiceSecretFileMode
22:08:46 [2019-10-15T22:08:46.639Z] --- PASS: TestCreateServiceSecretFileMode (3.25s)
22:08:46 [2019-10-15T22:08:46.639Z] create_test.go:224: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceSecretFileMode"
22:08:46 [2019-10-15T22:08:46.639Z] === RUN TestCreateServiceConfigFileMode
22:08:49 [2019-10-15T22:08:49.811Z] --- PASS: TestCreateServiceConfigFileMode (3.13s)
22:08:49 [2019-10-15T22:08:49.811Z] create_test.go:288: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceConfigFileMode"
22:08:49 [2019-10-15T22:08:49.811Z] === RUN TestCreateServiceSysctls
22:08:54 [2019-10-15T22:08:54.002Z] --- PASS: TestCreateServiceSysctls (4.18s)
22:08:54 [2019-10-15T22:08:54.002Z] create_test.go:378: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceSysctls"
22:08:54 [2019-10-15T22:08:54.002Z] === RUN TestCreateServiceCapabilities
22:08:57 [2019-10-15T22:08:57.286Z] --- PASS: TestCreateServiceCapabilities (3.22s)
22:08:57 [2019-10-15T22:08:57.286Z] create_test.go:462: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestCreateServiceCapabilities"
22:08:57 [2019-10-15T22:08:57.286Z] === RUN TestInspect
22:09:00 [2019-10-15T22:09:00.493Z] --- PASS: TestInspect (3.60s)
22:09:00 [2019-10-15T22:09:00.493Z] inspect_test.go:23: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestInspect"
22:09:00 [2019-10-15T22:09:00.493Z] === RUN TestServiceListWithStatuses
22:09:00 [2019-10-15T22:09:00.493Z] --- SKIP: TestServiceListWithStatuses (0.01s)
22:09:00 [2019-10-15T22:09:00.493Z] list_test.go:34: versions.LessThan(testEnv.DaemonInfo.ServerVersion, "1.41")
22:09:00 [2019-10-15T22:09:00.493Z] === RUN TestDockerNetworkConnectAlias
22:09:06 [2019-10-15T22:09:06.769Z] --- PASS: TestDockerNetworkConnectAlias (5.89s)
22:09:06 [2019-10-15T22:09:06.769Z] network_test.go:20: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkConnectAlias"
22:09:06 [2019-10-15T22:09:06.769Z] === RUN TestDockerNetworkReConnect
22:09:11 [2019-10-15T22:09:11.975Z] --- PASS: TestDockerNetworkReConnect (4.83s)
22:09:11 [2019-10-15T22:09:11.975Z] network_test.go:82: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestDockerNetworkReConnect"
22:09:11 [2019-10-15T22:09:11.975Z] === RUN TestServicePlugin
22:09:11 [2019-10-15T22:09:11.975Z] --- SKIP: TestServicePlugin (0.00s)
22:09:11 [2019-10-15T22:09:11.975Z] plugin_test.go:28: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:09:11 [2019-10-15T22:09:11.975Z] === RUN TestServiceUpdateLabel
22:09:14 [2019-10-15T22:09:14.421Z] --- PASS: TestServiceUpdateLabel (2.73s)
22:09:14 [2019-10-15T22:09:14.421Z] update_test.go:21: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceUpdateLabel"
22:09:14 [2019-10-15T22:09:14.421Z] === RUN TestServiceUpdateSecrets
22:09:30 [2019-10-15T22:09:30.822Z] --- PASS: TestServiceUpdateSecrets (15.39s)
22:09:30 [2019-10-15T22:09:30.822Z] update_test.go:77: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceUpdateSecrets"
22:09:30 [2019-10-15T22:09:30.822Z] === RUN TestServiceUpdateConfigs
22:09:47 [2019-10-15T22:09:47.217Z] --- PASS: TestServiceUpdateConfigs (15.52s)
22:09:47 [2019-10-15T22:09:47.217Z] update_test.go:139: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceUpdateConfigs"
22:09:47 [2019-10-15T22:09:47.217Z] === RUN TestServiceUpdateNetwork
22:10:00 [2019-10-15T22:10:00.824Z] --- PASS: TestServiceUpdateNetwork (14.04s)
22:10:00 [2019-10-15T22:10:00.824Z] update_test.go:201: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestServiceUpdateNetwork"
22:10:00 [2019-10-15T22:10:00.824Z] PASS
22:10:00 [2019-10-15T22:10:00.824Z]
22:10:00 [2019-10-15T22:10:00.824Z] === Skipped
22:10:00 [2019-10-15T22:10:00.824Z] === SKIP: s390x.integration.service TestServiceListWithStatuses (0.01s)
22:10:00 [2019-10-15T22:10:00.824Z] list_test.go:34: versions.LessThan(testEnv.DaemonInfo.ServerVersion, "1.41")
22:10:00 [2019-10-15T22:10:00.824Z]
22:10:00 [2019-10-15T22:10:00.824Z] === SKIP: s390x.integration.service TestServicePlugin (0.00s)
22:10:00 [2019-10-15T22:10:00.824Z] plugin_test.go:28: os.Getenv("DOCKER_ENGINE_GOARCH") != "amd64"
22:10:00 [2019-10-15T22:10:00.824Z]
22:10:00 [2019-10-15T22:10:00.824Z]
22:10:00 [2019-10-15T22:10:00.824Z] DONE 20 tests, 2 skipped in 122.822s
22:10:00 [2019-10-15T22:10:00.824Z] Running /go/src/github.com/docker/docker/integration/session (s390x.integration.session) flags=-test.v -test.timeout=120m
22:10:00 [2019-10-15T22:10:00.824Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-session-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-session-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.session -t ./test.main -test.v -test.timeout=120m
22:10:00 [2019-10-15T22:10:00.824Z] ++ set -e
22:10:00 [2019-10-15T22:10:00.824Z] ++ '[' -n 0 ']'
22:10:00 [2019-10-15T22:10:00.824Z] ++ set -x
22:10:00 [2019-10-15T22:10:00.824Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-session-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-session-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.session -t ./test.main -test.v -test.timeout=120m
22:10:00 [2019-10-15T22:10:00.824Z] INFO: Testing against a local daemon
22:10:00 [2019-10-15T22:10:00.824Z] === RUN TestSessionCreate
22:10:00 [2019-10-15T22:10:00.824Z] --- PASS: TestSessionCreate (0.02s)
22:10:00 [2019-10-15T22:10:00.824Z] === RUN TestSessionCreateWithBadUpgrade
22:10:00 [2019-10-15T22:10:00.824Z] --- PASS: TestSessionCreateWithBadUpgrade (0.02s)
22:10:00 [2019-10-15T22:10:00.824Z] PASS
22:10:00 [2019-10-15T22:10:00.824Z]
22:10:00 [2019-10-15T22:10:00.824Z] DONE 2 tests in 0.239s
22:10:00 [2019-10-15T22:10:00.824Z] Running /go/src/github.com/docker/docker/integration/system (s390x.integration.system) flags=-test.v -test.timeout=120m
22:10:00 [2019-10-15T22:10:00.824Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-system-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-system-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.system -t ./test.main -test.v -test.timeout=120m
22:10:00 [2019-10-15T22:10:00.824Z] ++ set -e
22:10:00 [2019-10-15T22:10:00.824Z] ++ '[' -n 0 ']'
22:10:00 [2019-10-15T22:10:00.824Z] ++ set -x
22:10:00 [2019-10-15T22:10:00.824Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-system-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-system-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.system -t ./test.main -test.v -test.timeout=120m
22:10:00 [2019-10-15T22:10:00.824Z] INFO: Testing against a local daemon
22:10:00 [2019-10-15T22:10:00.824Z] === RUN TestCgroupDriverSystemdMemoryLimit
22:10:00 [2019-10-15T22:10:00.824Z] --- SKIP: TestCgroupDriverSystemdMemoryLimit (0.00s)
22:10:00 [2019-10-15T22:10:00.824Z] cgroupdriver_systemd_test.go:32: !hasSystemd()
22:10:00 [2019-10-15T22:10:00.825Z] === RUN TestEventsExecDie
22:10:00 [2019-10-15T22:10:00.825Z] --- PASS: TestEventsExecDie (0.98s)
22:10:00 [2019-10-15T22:10:00.825Z] === RUN TestEventsBackwardsCompatible
22:10:00 [2019-10-15T22:10:00.825Z] --- PASS: TestEventsBackwardsCompatible (0.12s)
22:10:00 [2019-10-15T22:10:00.825Z] === RUN TestInfoBinaryCommits
22:10:00 [2019-10-15T22:10:00.825Z] --- PASS: TestInfoBinaryCommits (0.03s)
22:10:00 [2019-10-15T22:10:00.825Z] === RUN TestInfoAPIVersioned
22:10:00 [2019-10-15T22:10:00.825Z] --- PASS: TestInfoAPIVersioned (0.01s)
22:10:00 [2019-10-15T22:10:00.825Z] === RUN TestInfoAPI
22:10:00 [2019-10-15T22:10:00.825Z] --- PASS: TestInfoAPI (0.02s)
22:10:00 [2019-10-15T22:10:00.825Z] === RUN TestInfoAPIWarnings
22:10:01 [2019-10-15T22:10:01.616Z] --- PASS: TestInfoAPIWarnings (0.82s)
22:10:01 [2019-10-15T22:10:01.616Z] info_test.go:49: Creating a new daemon at: "/go/src/github.com/docker/docker/bundles/test-integration/TestInfoAPIWarnings"
22:10:01 [2019-10-15T22:10:01.616Z] === RUN TestLoginFailsWithBadCredentials
22:10:01 [2019-10-15T22:10:01.991Z] --- PASS: TestLoginFailsWithBadCredentials (0.44s)
22:10:01 [2019-10-15T22:10:01.991Z] === RUN TestPingCacheHeaders
22:10:01 [2019-10-15T22:10:01.991Z] --- PASS: TestPingCacheHeaders (0.02s)
22:10:01 [2019-10-15T22:10:01.991Z] === RUN TestPingGet
22:10:01 [2019-10-15T22:10:01.991Z] --- PASS: TestPingGet (0.03s)
22:10:01 [2019-10-15T22:10:01.991Z] === RUN TestPingHead
22:10:01 [2019-10-15T22:10:01.991Z] --- PASS: TestPingHead (0.02s)
22:10:01 [2019-10-15T22:10:01.991Z] === RUN TestVersion
22:10:01 [2019-10-15T22:10:01.991Z] --- PASS: TestVersion (0.02s)
22:10:01 [2019-10-15T22:10:01.991Z] PASS
22:10:01 [2019-10-15T22:10:01.991Z]
22:10:01 [2019-10-15T22:10:01.991Z] === Skipped
22:10:01 [2019-10-15T22:10:01.991Z] === SKIP: s390x.integration.system TestCgroupDriverSystemdMemoryLimit (0.00s)
22:10:01 [2019-10-15T22:10:01.991Z] cgroupdriver_systemd_test.go:32: !hasSystemd()
22:10:01 [2019-10-15T22:10:01.991Z]
22:10:01 [2019-10-15T22:10:01.991Z]
22:10:01 [2019-10-15T22:10:01.991Z] DONE 12 tests, 1 skipped in 2.666s
22:10:01 [2019-10-15T22:10:01.991Z] Running /go/src/github.com/docker/docker/integration/volume (s390x.integration.volume) flags=-test.v -test.timeout=120m
22:10:01 [2019-10-15T22:10:01.991Z] ++ test_env gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-volume-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-volume-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.volume -t ./test.main -test.v -test.timeout=120m
22:10:01 [2019-10-15T22:10:01.991Z] ++ set -e
22:10:01 [2019-10-15T22:10:01.991Z] ++ '[' -n 0 ']'
22:10:01 [2019-10-15T22:10:01.991Z] ++ set -x
22:10:01 [2019-10-15T22:10:01.991Z] ++ env -i DEST=/go/src/github.com/docker/docker/bundles/test-integration DOCKER_API_VERSION= DOCKER_BUILDKIT= DOCKER_INTEGRATION_DAEMON_DEST= DOCKER_TLS_VERIFY= DOCKER_CERT_PATH= DOCKER_ENGINE_GOARCH=s390x DOCKER_GRAPHDRIVER=overlay2 DOCKER_USERLANDPROXY=true DOCKER_HOST=unix:///go/src/github.com/docker/docker/bundles/test-integration/docker.sock DOCKER_REMAP_ROOT= DOCKER_REMOTE_DAEMON= DOCKERFILE=Dockerfile GOPATH=/go GOTRACEBACK=all HOME=/go/src/github.com/docker/docker/bundles/test-integration/fake-HOME PATH=/go/src/github.com/docker/docker/bundles/test-integration/../dynbinary-daemon:/go/src/github.com/docker/docker/bundles/test-integration/../binary-daemon:/usr/local/cli:/go/bin:/usr/local/go/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin TEMP= TEST_CLIENT_BINARY=docker gotestsum --format=standard-verbose --jsonfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-volume-go-test-report.json --junitfile=/go/src/github.com/docker/docker/bundles/test-integration/s390x-integration-volume-junit-report.xml --raw-command -- go tool test2json -p s390x.integration.volume -t ./test.main -test.v -test.timeout=120m
22:10:01 [2019-10-15T22:10:01.991Z] INFO: Testing against a local daemon
22:10:01 [2019-10-15T22:10:01.991Z] === RUN TestVolumesCreateAndList
22:10:01 [2019-10-15T22:10:01.991Z] --- PASS: TestVolumesCreateAndList (0.02s)
22:10:01 [2019-10-15T22:10:01.991Z] === RUN TestVolumesRemove
22:10:02 [2019-10-15T22:10:02.391Z] --- PASS: TestVolumesRemove (0.10s)
22:10:02 [2019-10-15T22:10:02.391Z] === RUN TestVolumesInspect
22:10:02 [2019-10-15T22:10:02.391Z] --- PASS: TestVolumesInspect (0.04s)
22:10:02 [2019-10-15T22:10:02.391Z] === RUN TestVolumesInvalidJSON
22:10:02 [2019-10-15T22:10:02.391Z] === RUN TestVolumesInvalidJSON//volumes/create
22:10:02 [2019-10-15T22:10:02.391Z] === PAUSE TestVolumesInvalidJSON//volumes/create
22:10:02 [2019-10-15T22:10:02.391Z] === CONT TestVolumesInvalidJSON//volumes/create
22:10:02 [2019-10-15T22:10:02.391Z] --- PASS: TestVolumesInvalidJSON (0.02s)
22:10:02 [2019-10-15T22:10:02.391Z] --- PASS: TestVolumesInvalidJSON//volumes/create (0.00s)
22:10:02 [2019-10-15T22:10:02.391Z] PASS
22:10:02 [2019-10-15T22:10:02.391Z]
22:10:02 [2019-10-15T22:10:02.391Z] DONE 5 tests in 0.301s
22:10:02 [2019-10-15T22:10:02.391Z] ---> Making bundle: .integration-daemon-stop (in bundles/test-integration)
22:10:02 [2019-10-15T22:10:02.391Z] ++++ cat bundles/test-integration/docker.pid
22:10:02 [2019-10-15T22:10:02.391Z] +++ kill 11566
22:10:04 [2019-10-15T22:10:04.180Z] +++ /etc/init.d/apparmor stop
22:10:04 [2019-10-15T22:10:04.180Z] Clearing AppArmor profiles cache:.
22:10:04 [2019-10-15T22:10:04.180Z] All profile caches have been cleared, but no profiles have been unloaded.
22:10:04 [2019-10-15T22:10:04.180Z] Unloading profiles will leave already running processes permanently
22:10:04 [2019-10-15T22:10:04.180Z] unconfined, which can lead to unexpected situations.
22:10:04 [2019-10-15T22:10:04.180Z]
22:10:04 [2019-10-15T22:10:04.180Z] To set a process to complain mode, use the command line tool
22:10:04 [2019-10-15T22:10:04.180Z] 'aa-complain'. To really tear down all profiles, run the init script
22:10:04 [2019-10-15T22:10:04.180Z] with the 'teardown' option."
22:10:04 [2019-10-15T22:10:04.180Z]