Pull request #2730 opened 00:05:45 Connecting to https://api.github.com using docker-jenkins/****** (docker-jenkins GitHub credentials) Obtained Jenkinsfile from 3fa7805771dfef9c11b1d8d4497003575fc2351f+75598aa92a3260f74dbe04b7c3cd307c1781c528 (47402e00ef836ba6eb1b91bc9c1cc5cb88f969e4) Running in Durability level: PERFORMANCE_OPTIMIZED Loading library jps@master Examining docker/jps Attempting to resolve master as a branch Resolved master as branch master at revision 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 using credential jps-reader Cloning the remote Git repository Cloning with configured refspecs honoured and without tags Cloning repository https://github.com/docker/jps.git > git init /var/cloudbees-jenkins-distribution/workspace/cli_PR-2730@libs/jps # timeout=10 Fetching upstream changes from https://github.com/docker/jps.git > git --version # timeout=10 > git --version # 'git version 2.20.1' using GIT_ASKPASS to set credentials GitHub credentials for docker/jps > git fetch --no-tags --force --progress -- https://github.com/docker/jps.git +refs/heads/master:refs/remotes/origin/master # timeout=10 > git config remote.origin.url https://github.com/docker/jps.git # timeout=10 > git config --add remote.origin.fetch +refs/heads/master:refs/remotes/origin/master # timeout=10 > git config remote.origin.url https://github.com/docker/jps.git # timeout=10 Fetching without tags Fetching upstream changes from https://github.com/docker/jps.git using GIT_ASKPASS to set credentials GitHub credentials for docker/jps > git fetch --no-tags --force --progress -- https://github.com/docker/jps.git +refs/heads/master:refs/remotes/origin/master # timeout=10 Checking out Revision 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 (master) > git config core.sparsecheckout # timeout=10 > git checkout -f 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 # timeout=10 Commit message: "Merge pull request #25 from ingshtrom/in-2733-load-from-file" First time build. Skipping changelog. > git --version # timeout=10 > git --version # 'git version 2.20.1' Excluding src/test/ from checkout of git https://github.com/docker/jps.git so that shared library test code cannot be accessed by Pipelines. To remove this log message, move the test code outside of src/. To restore the previous behavior that allowed access to files in src/test/, pass -Dorg.jenkinsci.plugins.workflow.libs.SCMSourceRetriever.INCLUDE_SRC_TEST_IN_LIBRARIES=true to the java command used to start Jenkins. [Pipeline] Start of Pipeline [Pipeline] node Running on EC2 (managed-agents) - ubuntu-1804-overlay2-mem (i-03ae2fb3dffbcf18d) in /home/ubuntu/workspace/cli_PR-2730 [Pipeline] { [Pipeline] stage [Pipeline] { (Declarative: Checkout SCM) [Pipeline] checkout using credential docker-jenkins-github-credentials Cloning the remote Git repository Cloning with configured refspecs honoured and without tags Cloning repository https://github.com/docker/cli.git > git init /home/ubuntu/workspace/cli_PR-2730 # timeout=10 Fetching upstream changes from https://github.com/docker/cli.git > git --version # timeout=10 > git --version # 'git version 2.17.1' using GIT_ASKPASS to set credentials docker-jenkins GitHub credentials > git fetch --no-tags --progress -- https://github.com/docker/cli.git +refs/pull/2730/head:refs/remotes/origin/PR-2730 +refs/heads/master:refs/remotes/origin/master # timeout=10 Fetching without tags > git config remote.origin.url https://github.com/docker/cli.git # timeout=10 > git config --add remote.origin.fetch +refs/pull/2730/head:refs/remotes/origin/PR-2730 # timeout=10 > git config --add remote.origin.fetch +refs/heads/master:refs/remotes/origin/master # timeout=10 > git config remote.origin.url https://github.com/docker/cli.git # timeout=10 Fetching upstream changes from https://github.com/docker/cli.git using GIT_ASKPASS to set credentials docker-jenkins GitHub credentials > git fetch --no-tags --progress -- https://github.com/docker/cli.git +refs/pull/2730/head:refs/remotes/origin/PR-2730 +refs/heads/master:refs/remotes/origin/master # timeout=10 Merging remotes/origin/master commit 75598aa92a3260f74dbe04b7c3cd307c1781c528 into PR head commit 3fa7805771dfef9c11b1d8d4497003575fc2351f Merge succeeded, producing 3fa7805771dfef9c11b1d8d4497003575fc2351f Checking out Revision 3fa7805771dfef9c11b1d8d4497003575fc2351f (PR-2730) Commit message: "[WIP] vendor: containerd/console v1.0.0-12-gf847fbb" First time build. Skipping changelog. > git --version # timeout=10 > git --version # 'git version 2.17.1' fatal: bad object 5d8b74fd03a1c7cc00e1c1f32d100ed97a17f4f0 > git config core.sparsecheckout # timeout=10 > git checkout -f 3fa7805771dfef9c11b1d8d4497003575fc2351f # timeout=10 > git remote # timeout=10 > git config --get remote.origin.url # timeout=10 using GIT_ASKPASS to set credentials docker-jenkins GitHub credentials > git merge 75598aa92a3260f74dbe04b7c3cd307c1781c528 # timeout=10 > git rev-parse HEAD^{commit} # timeout=10 > git config core.sparsecheckout # timeout=10 > git checkout -f 3fa7805771dfef9c11b1d8d4497003575fc2351f # timeout=10 [Pipeline] } [Pipeline] // stage [Pipeline] withEnv [Pipeline] { [Pipeline] timeout Timeout set to expire in 1 hr 0 min [Pipeline] { [Pipeline] stage [Pipeline] { (Docker info) [Pipeline] sh + docker version Client: Docker Engine - Community Version: 19.03.12 API version: 1.40 Go version: go1.13.10 Git commit: 48a66213fe Built: Mon Jun 22 15:45:36 2020 OS/Arch: linux/amd64 Experimental: false Server: Docker Engine - Community Engine: Version: 19.03.12 API version: 1.40 (minimum version 1.12) Go version: go1.13.10 Git commit: 48a66213fe Built: Mon Jun 22 15:44:07 2020 OS/Arch: linux/amd64 Experimental: true containerd: Version: 1.2.13 GitCommit: 7ad184331fa3e55e52b890ea95e65ba581ae3429 runc: Version: 1.0.0-rc10 GitCommit: dc9208a3303feef5b3839f4323d9beb36df0a9dd docker-init: Version: 0.18.0 GitCommit: fec3683 [Pipeline] sh + docker info Client: Debug Mode: false Server: Containers: 0 Running: 0 Paused: 0 Stopped: 0 Images: 2 Server Version: 19.03.12 Storage Driver: overlay2 Backing Filesystem: extfs Supports d_type: true Native Overlay Diff: true Logging Driver: json-file Cgroup Driver: cgroupfs Plugins: Volume: local Network: bridge host ipvlan macvlan null overlay Log: awslogs fluentd gcplogs gelf journald json-file local logentries splunk syslog Swarm: inactive Runtimes: runc Default Runtime: runc Init Binary: docker-init containerd version: 7ad184331fa3e55e52b890ea95e65ba581ae3429 runc version: dc9208a3303feef5b3839f4323d9beb36df0a9dd init version: fec3683 Security Options: apparmor seccomp Profile: default Kernel Version: 5.3.0-1035-aws Operating System: Ubuntu 18.04.5 LTS OSType: linux Architecture: x86_64 CPUs: 2 Total Memory: 15.35GiB Name: ip-10-100-62-48 ID: VHCT:TLUC:R2VO:YIWO:QRPS:LVDE:GNIX:P4Z4:AVYF:EKOW:UMYI:VEID Docker Root Dir: /var/lib/docker Debug Mode: false Registry: https://index.docker.io/v1/ Labels: Experimental: true Insecure Registries: 127.0.0.0/8 Live Restore Enabled: true WARNING: No swap limit support [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (e2e (non-experimental) - stable engine) [Pipeline] sh + E2E_UNIQUE_ID=clie2e1 IMAGE_TAG=clie2e1 DOCKER_BUILDKIT=1 make -f docker.Makefile test-e2e-non-experimental docker build -t docker-cli-e2eclie2e1 --build-arg=GO_VERSION --build-arg VERSION=20.03.0-dev --build-arg GITCOMMIT= -f ./dockerfiles/Dockerfile.e2e . #2 [internal] load .dockerignore #2 transferring context: 117B done #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.e2e #1 transferring dockerfile: 1.65kB done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/golang:1.13.15-buster #3 DONE 0.1s #4 [1/11] FROM docker.io/library/golang:1.13.15-buster@sha256:66a3f6817c129... #4 CACHED #10 [internal] load build context #10 transferring context: 41.37MB 1.5s done #10 DONE 1.5s #5 [2/11] RUN apt-get update && apt-get install -y build-essential ... #5 1.064 Get:1 http://security.debian.org/debian-security buster/updates InRelease [65.4 kB] #5 1.083 Get:2 http://deb.debian.org/debian buster InRelease [122 kB] #5 1.091 Get:3 http://deb.debian.org/debian buster-updates InRelease [51.9 kB] #5 1.325 Get:4 http://security.debian.org/debian-security buster/updates/main amd64 Packages [226 kB] #5 1.518 Get:5 http://deb.debian.org/debian buster/main amd64 Packages [7906 kB] #5 1.840 Get:6 http://deb.debian.org/debian buster-updates/main amd64 Packages [7868 B] #5 3.001 Fetched 8380 kB in 2s (3823 kB/s) #5 3.001 Reading package lists... #5 3.594 Reading package lists... #5 4.170 Building dependency tree... #5 4.279 Reading state information... #5 4.377 curl is already the newest version (7.64.0-4+deb10u1). #5 4.377 openssh-client is already the newest version (1:7.9p1-10+deb10u2). #5 4.377 openssl is already the newest version (1.1.1d-0+deb10u3). #5 4.377 openssl set to manually installed. #5 4.377 The following additional packages will be installed: #5 4.378 bzip2 dpkg-dev fakeroot libalgorithm-diff-perl libalgorithm-diff-xs-perl #5 4.378 libalgorithm-merge-perl libfakeroot patch xz-utils #5 4.379 Suggested packages: #5 4.379 bzip2-doc debian-keyring ed diffutils-doc #5 4.448 The following NEW packages will be installed: #5 4.448 build-essential bzip2 dpkg-dev fakeroot libalgorithm-diff-perl #5 4.449 libalgorithm-diff-xs-perl libalgorithm-merge-perl libfakeroot patch xz-utils #5 4.716 0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded. #5 4.716 Need to get 2342 kB of archives. #5 4.716 After this operation, 3601 kB of additional disk space will be used. #5 4.716 Get:1 http://deb.debian.org/debian buster/main amd64 bzip2 amd64 1.0.6-9.2~deb10u1 [48.4 kB] #5 4.721 Get:2 http://deb.debian.org/debian buster/main amd64 xz-utils amd64 5.2.4-1 [183 kB] #5 4.727 Get:3 http://deb.debian.org/debian buster/main amd64 patch amd64 2.7.6-3+deb10u1 [126 kB] #5 4.730 Get:4 http://deb.debian.org/debian buster/main amd64 dpkg-dev all 1.19.7 [1773 kB] #5 4.757 Get:5 http://deb.debian.org/debian buster/main amd64 build-essential amd64 12.6 [7576 B] #5 4.758 Get:6 http://deb.debian.org/debian buster/main amd64 libfakeroot amd64 1.23-1 [45.9 kB] #5 4.818 Get:7 http://deb.debian.org/debian buster/main amd64 fakeroot amd64 1.23-1 [85.8 kB] #5 4.819 Get:8 http://deb.debian.org/debian buster/main amd64 libalgorithm-diff-perl all 1.19.03-2 [47.9 kB] #5 4.820 Get:9 http://deb.debian.org/debian buster/main amd64 libalgorithm-diff-xs-perl amd64 0.04-5+b1 [11.8 kB] #5 4.820 Get:10 http://deb.debian.org/debian buster/main amd64 libalgorithm-merge-perl all 0.08-3 [12.7 kB] #5 4.948 debconf: delaying package configuration, since apt-utils is not installed #5 4.972 Fetched 2342 kB in 0s (6498 kB/s) #5 4.994 Selecting previously unselected package bzip2. #5 4.994 (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 15410 files and directories currently installed.) #5 5.003 Preparing to unpack .../0-bzip2_1.0.6-9.2~deb10u1_amd64.deb ... #5 5.009 Unpacking bzip2 (1.0.6-9.2~deb10u1) ... #5 5.047 Selecting previously unselected package xz-utils. #5 5.049 Preparing to unpack .../1-xz-utils_5.2.4-1_amd64.deb ... #5 5.053 Unpacking xz-utils (5.2.4-1) ... #5 5.096 Selecting previously unselected package patch. #5 5.098 Preparing to unpack .../2-patch_2.7.6-3+deb10u1_amd64.deb ... #5 5.102 Unpacking patch (2.7.6-3+deb10u1) ... #5 5.141 Selecting previously unselected package dpkg-dev. #5 5.143 Preparing to unpack .../3-dpkg-dev_1.19.7_all.deb ... #5 5.146 Unpacking dpkg-dev (1.19.7) ... #5 5.250 Selecting previously unselected package build-essential. #5 5.252 Preparing to unpack .../4-build-essential_12.6_amd64.deb ... #5 5.256 Unpacking build-essential (12.6) ... #5 5.289 Selecting previously unselected package libfakeroot:amd64. #5 5.291 Preparing to unpack .../5-libfakeroot_1.23-1_amd64.deb ... #5 5.298 Unpacking libfakeroot:amd64 (1.23-1) ... #5 5.334 Selecting previously unselected package fakeroot. #5 5.335 Preparing to unpack .../6-fakeroot_1.23-1_amd64.deb ... #5 5.339 Unpacking fakeroot (1.23-1) ... #5 5.375 Selecting previously unselected package libalgorithm-diff-perl. #5 5.377 Preparing to unpack .../7-libalgorithm-diff-perl_1.19.03-2_all.deb ... #5 5.381 Unpacking libalgorithm-diff-perl (1.19.03-2) ... #5 5.411 Selecting previously unselected package libalgorithm-diff-xs-perl. #5 5.413 Preparing to unpack .../8-libalgorithm-diff-xs-perl_0.04-5+b1_amd64.deb ... #5 5.418 Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... #5 5.447 Selecting previously unselected package libalgorithm-merge-perl. #5 5.449 Preparing to unpack .../9-libalgorithm-merge-perl_0.08-3_all.deb ... #5 5.452 Unpacking libalgorithm-merge-perl (0.08-3) ... #5 5.493 Setting up libalgorithm-diff-perl (1.19.03-2) ... #5 5.505 Setting up bzip2 (1.0.6-9.2~deb10u1) ... #5 5.517 Setting up libfakeroot:amd64 (1.23-1) ... #5 5.533 Setting up fakeroot (1.23-1) ... #5 5.547 update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode #5 5.559 Setting up xz-utils (5.2.4-1) ... #5 5.569 update-alternatives: using /usr/bin/xz to provide /usr/bin/lzma (lzma) in auto mode #5 5.577 Setting up patch (2.7.6-3+deb10u1) ... #5 5.589 Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... #5 5.601 Setting up libalgorithm-merge-perl (0.08-3) ... #5 5.612 Setting up dpkg-dev (1.19.7) ... #5 5.633 Setting up build-essential (12.6) ... #5 5.645 Processing triggers for libc-bin (2.28-10) ... #5 DONE 6.1s #6 [3/11] RUN curl -fsSL https://github.com/docker/compose/releases/downloa... #6 DONE 0.9s #7 [4/11] RUN curl -fsSL https://github.com/theupdateframework/notary/relea... #7 DONE 0.8s #8 [5/11] RUN curl -fsSL https://github.com/gotestyourself/gotestsum/releas... #8 DONE 0.7s #9 [6/11] WORKDIR /go/src/github.com/docker/cli #9 DONE 0.0s #11 [7/11] COPY e2e/testdata/notary/root-ca.cert /usr/share/ca-certificates/... #11 DONE 0.0s #12 [8/11] RUN echo 'notary.cert' >> /etc/ca-certificates.conf && update-ca-... #12 0.404 Updating certificates in /etc/ssl/certs... #12 0.896 1 added, 0 removed; done. #12 0.896 Running hooks in /etc/ca-certificates/update.d... #12 0.897 done. #12 DONE 0.9s #13 [9/11] COPY . . #13 DONE 0.6s #14 [10/11] RUN ./scripts/build/binary #14 0.379 Building statically linked build/docker-linux-amd64 #14 DONE 71.6s #15 [11/11] RUN ./scripts/build/plugins e2e/cli-plugins/plugins/* #15 0.439 Building statically linked build/plugins-linux-amd64/docker-helloworld #15 1.720 Building statically linked build/plugins-linux-amd64/docker-badmeta #15 2.801 Building statically linked build/plugins-linux-amd64/docker-nopersistentprerun #15 DONE 4.1s #16 exporting to image #16 exporting layers #16 exporting layers 2.2s done #16 writing image sha256:11bf6470a145dfd94f65989866ad405d1584301d5dda3d41e7e2583be0188e35 done #16 naming to docker.io/library/docker-cli-e2eclie2e1 done #16 DONE 2.2s docker run --rm --mount type=bind,src=/var/run/docker.sock,dst=/var/run/docker.sock -e VERSION=20.03.0-dev -e GITCOMMIT -e PLATFORM -e TESTFLAGS -e TESTDIRS -e GOOS -e GOARCH -e GOARM -e TEST_ENGINE_VERSION= -e TEST_ENGINE_VERSION= docker-cli-e2eclie2e1 Native build is an experimental feature and could change at any time Creating network "cliendtoendsuite_default" with the default driver Pulling registry (registry:2)... 2: Pulling from library/registry Digest: sha256:8be26f81ffea54106bae012c6f349df70f4d5e7e2ec01b143c46e2c03b9e551d Status: Downloaded newer image for registry:2 Pulling engine (docker:stable-dind)... stable-dind: Pulling from library/docker Digest: sha256:4b3c5014a055156732842f11956b5e239ce6033279a3ca938a18b272f5dd9109 Status: Downloaded newer image for docker:stable-dind Building notary-server #2 [internal] load .dockerignore #2 sha256:f639bd2ab5294f16ead5ce210a3ba951ed9365e6f3895bab550e5c8400338087 #2 transferring context: 2B done #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.notary-server #1 sha256:4b04aa8299cfcf2d70c3a500ac083dd8319b5fab1eb881a5d413ca8cbb59f5f9 #1 transferring dockerfile: 140B done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/notary:server-0.5.0 #3 sha256:a1da53cf05b63d7b254e8b6a3d96b994c4ae5f01cb10e10906db53f729e6903e #3 DONE 0.6s #5 [internal] load build context #5 sha256:9883877492e52c91712e38b0d56215301488e3781d33368f2c4baea57c1343fa #5 transferring context: 5.70kB done #5 DONE 0.0s #4 [1/2] FROM docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 #4 sha256:cd52ec412b0b1cb53bf96e4160cee4046ce1447da482224a0d115d68a2468239 #4 resolve docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 done #4 sha256:176147d1183da92e05482a5560407bfc56af52bc61b1cb5bdb323d4c7d6a8867 0B / 411B 0.0s #4 sha256:9504875b8162730788ff7df926b72bcbb2bae4690f5195b6d62ec961ffc12056 0B / 4.03MB 0.0s #4 sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 434B / 434B done #4 sha256:88bddb53072ae1b018b3b09ac1974be903800e3595ec31401fc3c73ffb66affd 1.36kB / 1.36kB done #4 sha256:16b632e954ca01326ecc36ff0c7bb651baf9dcf92366b322b112f697193eb3d5 3.40kB / 3.40kB done #4 sha256:4fe2ade4980c2dda4fc95858ebb981489baec8c1e4bd282ab1c3560be8ff9bde 0B / 2.21MB 0.0s #4 sha256:176147d1183da92e05482a5560407bfc56af52bc61b1cb5bdb323d4c7d6a8867 411B / 411B 0.1s done #4 sha256:4fe2ade4980c2dda4fc95858ebb981489baec8c1e4bd282ab1c3560be8ff9bde 2.21MB / 2.21MB 0.1s done #4 extracting sha256:4fe2ade4980c2dda4fc95858ebb981489baec8c1e4bd282ab1c3560be8ff9bde #4 sha256:070baad58e0d2d397143ab9f03defb8b016fc8d57eab900d496727c0e2e00292 0B / 377B 0.1s #4 sha256:b9ff31535c882e16ca7c737f9b9852e6b03e10cd1cd3fdf653beecdedf3dffff 0B / 1.19kB 0.1s #4 sha256:9504875b8162730788ff7df926b72bcbb2bae4690f5195b6d62ec961ffc12056 1.71MB / 4.03MB 0.2s #4 extracting sha256:4fe2ade4980c2dda4fc95858ebb981489baec8c1e4bd282ab1c3560be8ff9bde 0.1s done #4 sha256:070baad58e0d2d397143ab9f03defb8b016fc8d57eab900d496727c0e2e00292 377B / 377B 0.2s done #4 sha256:b9ff31535c882e16ca7c737f9b9852e6b03e10cd1cd3fdf653beecdedf3dffff 1.19kB / 1.19kB 0.2s done #4 extracting sha256:176147d1183da92e05482a5560407bfc56af52bc61b1cb5bdb323d4c7d6a8867 done #4 sha256:9504875b8162730788ff7df926b72bcbb2bae4690f5195b6d62ec961ffc12056 3.46MB / 4.03MB 0.3s #4 extracting sha256:9504875b8162730788ff7df926b72bcbb2bae4690f5195b6d62ec961ffc12056 #4 sha256:9504875b8162730788ff7df926b72bcbb2bae4690f5195b6d62ec961ffc12056 4.03MB / 4.03MB 0.4s done #4 extracting sha256:9504875b8162730788ff7df926b72bcbb2bae4690f5195b6d62ec961ffc12056 0.2s done #4 extracting sha256:070baad58e0d2d397143ab9f03defb8b016fc8d57eab900d496727c0e2e00292 done #4 extracting sha256:b9ff31535c882e16ca7c737f9b9852e6b03e10cd1cd3fdf653beecdedf3dffff done #4 DONE 0.8s #6 [2/2] COPY ./notary/ /fixtures/ #6 sha256:537539b81e87e6b5cdc33887aac124087d74c544c8c130eb28923eda8e946f30 #6 DONE 0.8s #7 exporting to image #7 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00 #7 exporting layers 0.0s done #7 writing image sha256:79a84b7cfc8503e4a8452ee7f9db922aff9bf4eb1fb970487afd5986dd5b022d done #7 naming to docker.io/library/cliendtoendsuite_notary-server done #7 DONE 0.0s Successfully built 79a84b7cfc8503e4a8452ee7f9db922aff9bf4eb1fb970487afd5986dd5b022d Building evil-notary-server #2 [internal] load .dockerignore #2 sha256:997476d28cbaa54fc8c81f04a30f45a334adc3f4352bd11ca1a7a21efe070302 #2 transferring context: 2B done #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.evil-notary-server #1 sha256:b9e69d8ae8ff68939957289b5b1a7f2024d28f80b1bd28cebd7ca85e4d718f91 #1 transferring dockerfile: 150B done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/notary:server-0.5.0 #3 sha256:a1da53cf05b63d7b254e8b6a3d96b994c4ae5f01cb10e10906db53f729e6903e #3 DONE 0.1s #4 [1/2] FROM docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 #4 sha256:cd52ec412b0b1cb53bf96e4160cee4046ce1447da482224a0d115d68a2468239 #4 CACHED #5 [internal] load build context #5 sha256:39c35e45c3b5896c66ad68cee244ce175dbd40ee9e133d03cab765ad22172b19 #5 transferring context: 5.73kB done #5 DONE 0.0s #6 [2/2] COPY ./notary-evil/ /fixtures/ #6 sha256:907f7b249061748360a62a4fea0b1cd8d6df2d016eb9014053ced527fd9b578d #6 DONE 0.0s #7 exporting to image #7 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00 #7 exporting layers 0.0s done #7 writing image sha256:076be5ceee8a42397c1f151c32d4e7f923a3c2c61797b797376266ccb80ff51b done #7 naming to docker.io/library/cliendtoendsuite_evil-notary-server done #7 DONE 0.0s Successfully built 076be5ceee8a42397c1f151c32d4e7f923a3c2c61797b797376266ccb80ff51b Creating cliendtoendsuite_notary-server_1 ... Creating cliendtoendsuite_evil-notary-server_1 ... Creating cliendtoendsuite_engine_1 ... Creating cliendtoendsuite_registry_1 ... [2A[2K Creating cliendtoendsuite_engine_1 ... [32mdone[0m [2B[3A[2K Creating cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [3B[1A[2K Creating cliendtoendsuite_registry_1 ... [32mdone[0m [1B[4A[2K Creating cliendtoendsuite_notary-server_1 ... [32mdone[0m [4BWaiting for docker daemon to become available at tcp://172.18.0.5:2375 Client: Version: 20.03.0-dev API version: 1.40 Go version: go1.13.15 Git commit: Built: Fri Sep 18 00:06:06 2020 OS/Arch: linux/amd64 Context: default Experimental: false Server: Docker Engine - Community Engine: Version: 19.03.13 API version: 1.40 (minimum version 1.12) Go version: go1.13.15 Git commit: 4484c46 Built: Wed Sep 16 17:04:43 2020 OS/Arch: linux/amd64 Experimental: false containerd: Version: v1.3.7 GitCommit: 8fba4e9a7d01810a393d5d25a3621dc101981175 runc: Version: 1.0.0-rc10 GitCommit: dc9208a3303feef5b3839f4323d9beb36df0a9dd docker-init: Version: 0.18.0 GitCommit: fec3683 sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d: Pulling from library/alpine 88286f41530e: Pulling fs layer 88286f41530e: Download complete 88286f41530e: Pull complete Digest: sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d Status: Downloaded newer image for alpine@sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d docker.io/library/alpine@sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d The push refers to repository [registry:5000/alpine] 5bef08742407: Preparing 5bef08742407: Pushed 3.6: digest: sha256:641b95ddb2ea9dc2af1a0113b6b348ebc20872ba615204fbe12148e98fd6f23d size: 528 sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7: Pulling from library/busybox 03b1be98f3f9: Pulling fs layer 03b1be98f3f9: Verifying Checksum 03b1be98f3f9: Download complete 03b1be98f3f9: Pull complete Digest: sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 Status: Downloaded newer image for busybox@sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 docker.io/library/busybox@sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 The push refers to repository [registry:5000/busybox] 6a749002dd6a: Preparing 6a749002dd6a: Pushed 1.27.2: digest: sha256:030fcb92e1487b18c974784dcc110a93147c9fc402188370fbfd17efabffc6af size: 527 Swarm initialized: current node (xdeqgwv6v7hiz3sxzvxtu3iam) is now a manager. To add a worker to this swarm, run the following command: docker swarm join --token SWMTKN-1-2pkhyog0lrevnvekenyovs0krd4epkcjj7hys78sfy3h14v22i-769qjkg2ji0332qff3pn36m54 172.18.0.5:2377 To add a manager to this swarm, run 'docker swarm join-token manager' and follow the instructions. [32m✓[0m e2e/cli-plugins (2.778s) [33m∅[0m e2e/cli-plugins/plugins/badmeta [33m∅[0m e2e/cli-plugins/plugins/nopersistentprerun [32m✓[0m e2e/context (49ms) [32m✓[0m e2e/global (114ms) [32m✓[0m e2e/container (9.064s) [32m✓[0m e2e/plugin (3.498s) [32m✓[0m e2e/stack (560ms) [32m✓[0m e2e/system (100ms) [32m✓[0m e2e/image (11.845s) [33m∅[0m e2e/internal/fixtures [33m∅[0m e2e/plugin/basic [32m✓[0m e2e/trust (2.119s) === [33mSkipped[0m === [33mSKIP[0m: e2e/cli-plugins TestCLIPluginDialStdio (0.00s) dial_test.go:16: skipping plugin dial-stdio test since DOCKER_CLI_PLUGIN_USE_DIAL_STDIO is not set === [33mSKIP[0m: e2e/container TestRunWithCgroupNamespace (0.11s) run_test.go:141: !cgroupNsFound: running against a daemon that doesn't support cgroup namespaces (security options: [name=apparmor name=seccomp,profile=default] ) === [33mSKIP[0m: e2e/image TestBuildIidFileSquash (0.05s) build_test.go:112: !experimentalBuild: running against a non-experimental daemon === [33mSKIP[0m: e2e/stack TestDeployWithNamedResources/Kubernetes (0.00s) --- SKIP: TestDeployWithNamedResources/Kubernetes (0.00s) deploy_test.go:22: FIXME(chris-crone): currently does not work with compose for kubernetes. === [33mSKIP[0m: e2e/stack TestRemove/Kubernetes (0.00s) --- SKIP: TestRemove/Kubernetes (0.00s) remove_test.go:22: !environment.KubernetesEnabled() DONE 94 tests, 5 skipped in 32.968s Stopping cliendtoendsuite_registry_1 ... Stopping cliendtoendsuite_notary-server_1 ... Stopping cliendtoendsuite_engine_1 ... Stopping cliendtoendsuite_evil-notary-server_1 ... [3A[2K Stopping cliendtoendsuite_notary-server_1 ... [32mdone[0m [3B[4A[2K Stopping cliendtoendsuite_registry_1 ... [32mdone[0m [4B[1A[2K Stopping cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [1B[2A[2K Stopping cliendtoendsuite_engine_1 ... [32mdone[0m [2BRemoving cliendtoendsuite_registry_1 ... Removing cliendtoendsuite_notary-server_1 ... Removing cliendtoendsuite_engine_1 ... Removing cliendtoendsuite_evil-notary-server_1 ... [1A[2K Removing cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [1B[3A[2K Removing cliendtoendsuite_notary-server_1 ... [32mdone[0m [3B[2A[2K Removing cliendtoendsuite_engine_1 ... [32mdone[0m [2B[4A[2K Removing cliendtoendsuite_registry_1 ... [32mdone[0m [4BRemoving network cliendtoendsuite_default Removing image cliendtoendsuite_notary-server Removing image cliendtoendsuite_evil-notary-server [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (e2e (non-experimental) - 18.09 engine) [Pipeline] sh + E2E_ENGINE_VERSION=18.09-dind E2E_UNIQUE_ID=clie2e1 IMAGE_TAG=clie2e1 DOCKER_BUILDKIT=1 make -f docker.Makefile test-e2e-non-experimental docker build -t docker-cli-e2eclie2e1 --build-arg=GO_VERSION --build-arg VERSION=20.03.0-dev --build-arg GITCOMMIT= -f ./dockerfiles/Dockerfile.e2e . #2 [internal] load .dockerignore #2 transferring context: 34B done #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.e2e #1 transferring dockerfile: 42B done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/golang:1.13.15-buster #3 DONE 0.1s #4 [1/11] FROM docker.io/library/golang:1.13.15-buster@sha256:66a3f6817c129... #4 DONE 0.0s #10 [internal] load build context #10 transferring context: 393.89kB 0.2s done #10 DONE 0.2s #5 [2/11] RUN apt-get update && apt-get install -y build-essential ... #5 CACHED #9 [6/11] WORKDIR /go/src/github.com/docker/cli #9 CACHED #7 [4/11] RUN curl -fsSL https://github.com/theupdateframework/notary/relea... #7 CACHED #8 [5/11] RUN curl -fsSL https://github.com/gotestyourself/gotestsum/releas... #8 CACHED #14 [10/11] RUN ./scripts/build/binary #14 CACHED #11 [7/11] COPY e2e/testdata/notary/root-ca.cert /usr/share/ca-certificates/... #11 CACHED #12 [8/11] RUN echo 'notary.cert' >> /etc/ca-certificates.conf && update-ca-... #12 CACHED #13 [9/11] COPY . . #13 CACHED #6 [3/11] RUN curl -fsSL https://github.com/docker/compose/releases/downloa... #6 CACHED #15 [11/11] RUN ./scripts/build/plugins e2e/cli-plugins/plugins/* #15 CACHED #16 exporting to image #16 exporting layers done #16 writing image sha256:11bf6470a145dfd94f65989866ad405d1584301d5dda3d41e7e2583be0188e35 #16 writing image sha256:11bf6470a145dfd94f65989866ad405d1584301d5dda3d41e7e2583be0188e35 done #16 naming to docker.io/library/docker-cli-e2eclie2e1 done #16 DONE 0.0s docker run --rm --mount type=bind,src=/var/run/docker.sock,dst=/var/run/docker.sock -e VERSION=20.03.0-dev -e GITCOMMIT -e PLATFORM -e TESTFLAGS -e TESTDIRS -e GOOS -e GOARCH -e GOARM -e TEST_ENGINE_VERSION=18.09-dind -e TEST_ENGINE_VERSION=18.09-dind docker-cli-e2eclie2e1 Native build is an experimental feature and could change at any time Creating network "cliendtoendsuite_default" with the default driver Pulling engine (docker:18.09-dind)... 18.09-dind: Pulling from library/docker Digest: sha256:86df3c3573065f2c6f24cd925fd5bc3a0aff899bdf664ff4d2e3ebab26d96bed Status: Downloaded newer image for docker:18.09-dind Building notary-server #2 [internal] load .dockerignore #2 sha256:d6fc5aeb1d2132cd10a5a5512e01943679f21e726e020c7dc3c10bd8b82ff289 #2 transferring context: 2B done #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.notary-server #1 sha256:36f1cf6027ae4daf86ad2105338d8d7f399dbefddc6f056d86280b20cf45cff4 #1 transferring dockerfile: 140B done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/notary:server-0.5.0 #3 sha256:a1da53cf05b63d7b254e8b6a3d96b994c4ae5f01cb10e10906db53f729e6903e #3 DONE 0.2s #4 [1/2] FROM docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 #4 sha256:cd52ec412b0b1cb53bf96e4160cee4046ce1447da482224a0d115d68a2468239 #4 DONE 0.0s #5 [internal] load build context #5 sha256:4fd7c70c408d8692ca5c718af2e8c42b991c8dc6b2f4a592dd2549d4ecd967d0 #5 transferring context: 5.70kB done #5 DONE 0.0s #6 [2/2] COPY ./notary/ /fixtures/ #6 sha256:8891759338ec0e60cb9d81226e53cb6541dab0cb01db0e6a3958970b7efbaaf4 #6 CACHED #7 exporting to image #7 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00 #7 exporting layers done #7 writing image sha256:79a84b7cfc8503e4a8452ee7f9db922aff9bf4eb1fb970487afd5986dd5b022d done #7 naming to docker.io/library/cliendtoendsuite_notary-server done #7 DONE 0.0s Successfully built 79a84b7cfc8503e4a8452ee7f9db922aff9bf4eb1fb970487afd5986dd5b022d Building evil-notary-server #1 [internal] load build definition from Dockerfile.evil-notary-server #1 sha256:c0454b1c99cae4587ca3e779125626809cd63df1779c7c3052d1c609eac5be86 #1 transferring dockerfile: 150B done #1 DONE 0.0s #2 [internal] load .dockerignore #2 sha256:9117ad2401956aab9b1fdf612640212a9e2ec11c170e4e2be12a524ca743e465 #2 transferring context: 2B done #2 DONE 0.0s #3 [internal] load metadata for docker.io/library/notary:server-0.5.0 #3 sha256:a1da53cf05b63d7b254e8b6a3d96b994c4ae5f01cb10e10906db53f729e6903e #3 DONE 0.1s #4 [1/2] FROM docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 #4 sha256:cd52ec412b0b1cb53bf96e4160cee4046ce1447da482224a0d115d68a2468239 #4 DONE 0.0s #5 [internal] load build context #5 sha256:9aea2426d7c9617228b1dedc94fb932b6c625613d9b4971a61ae59fb42e4cc64 #5 transferring context: 5.73kB done #5 DONE 0.0s #6 [2/2] COPY ./notary-evil/ /fixtures/ #6 sha256:0fe759f48734e6f231c3e6683da7c95a4b37c0bbab2a333918484462bfea5a0d #6 CACHED #7 exporting to image #7 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00 #7 exporting layers done #7 writing image sha256:076be5ceee8a42397c1f151c32d4e7f923a3c2c61797b797376266ccb80ff51b done #7 naming to docker.io/library/cliendtoendsuite_evil-notary-server done #7 DONE 0.0s Successfully built 076be5ceee8a42397c1f151c32d4e7f923a3c2c61797b797376266ccb80ff51b Creating cliendtoendsuite_registry_1 ... Creating cliendtoendsuite_engine_1 ... Creating cliendtoendsuite_evil-notary-server_1 ... Creating cliendtoendsuite_notary-server_1 ... [1A[2K Creating cliendtoendsuite_notary-server_1 ... [32mdone[0m [1B[4A[2K Creating cliendtoendsuite_registry_1 ... [32mdone[0m [4B[3A[2K Creating cliendtoendsuite_engine_1 ... [32mdone[0m [3B[2A[2K Creating cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [2BWaiting for docker daemon to become available at tcp://172.19.0.4:2375 Client: Version: 20.03.0-dev API version: 1.39 Go version: go1.13.15 Git commit: Built: Fri Sep 18 00:06:06 2020 OS/Arch: linux/amd64 Context: default Experimental: false Server: Docker Engine - Community Engine: Version: 18.09.9 API version: 1.39 (minimum version 1.12) Go version: go1.11.13 Git commit: 039a7df9ba Built: Wed Sep 4 16:55:50 2019 OS/Arch: linux/amd64 Experimental: false sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d: Pulling from library/alpine 88286f41530e: Pulling fs layer 88286f41530e: Verifying Checksum 88286f41530e: Download complete 88286f41530e: Pull complete Digest: sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d Status: Downloaded newer image for alpine@sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d docker.io/library/alpine@sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d The push refers to repository [registry:5000/alpine] 5bef08742407: Preparing 5bef08742407: Pushed 3.6: digest: sha256:641b95ddb2ea9dc2af1a0113b6b348ebc20872ba615204fbe12148e98fd6f23d size: 528 sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7: Pulling from library/busybox 03b1be98f3f9: Pulling fs layer 03b1be98f3f9: Verifying Checksum 03b1be98f3f9: Download complete 03b1be98f3f9: Pull complete Digest: sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 Status: Downloaded newer image for busybox@sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 docker.io/library/busybox@sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 The push refers to repository [registry:5000/busybox] 6a749002dd6a: Preparing 6a749002dd6a: Pushed 1.27.2: digest: sha256:030fcb92e1487b18c974784dcc110a93147c9fc402188370fbfd17efabffc6af size: 527 Swarm initialized: current node (pmyqimb7cl21wpybpwks8tzkc) is now a manager. To add a worker to this swarm, run the following command: docker swarm join --token SWMTKN-1-42706vvezpdxjsqvidzlsio75xncxgovgbgkfy88xwik7rmcl5-2ohf2gc4whjnfnvoichzn19zi 172.19.0.4:2377 To add a manager to this swarm, run 'docker swarm join-token manager' and follow the instructions. [32m✓[0m e2e/cli-plugins (2.846s) [33m∅[0m e2e/cli-plugins/plugins/badmeta [33m∅[0m e2e/cli-plugins/plugins/nopersistentprerun [32m✓[0m e2e/context (46ms) [32m✓[0m e2e/global (148ms) [32m✓[0m e2e/container (9.433s) [32m✓[0m e2e/plugin (3.76s) [32m✓[0m e2e/stack (597ms) [32m✓[0m e2e/image (11.585s) [33m∅[0m e2e/internal/fixtures [33m∅[0m e2e/plugin/basic [32m✓[0m e2e/system (92ms) [32m✓[0m e2e/trust (2.155s) === [33mSkipped[0m === [33mSKIP[0m: e2e/cli-plugins TestCLIPluginDialStdio (0.00s) dial_test.go:16: skipping plugin dial-stdio test since DOCKER_CLI_PLUGIN_USE_DIAL_STDIO is not set === [33mSKIP[0m: e2e/container TestRunWithCgroupNamespace (0.10s) run_test.go:141: !cgroupNsFound: running against a daemon that doesn't support cgroup namespaces (security options: [name=apparmor name=seccomp,profile=default] ) === [33mSKIP[0m: e2e/image TestBuildIidFileSquash (0.06s) build_test.go:112: !experimentalBuild: running against a non-experimental daemon === [33mSKIP[0m: e2e/stack TestDeployWithNamedResources/Kubernetes (0.00s) --- SKIP: TestDeployWithNamedResources/Kubernetes (0.00s) deploy_test.go:22: FIXME(chris-crone): currently does not work with compose for kubernetes. === [33mSKIP[0m: e2e/stack TestRemove/Kubernetes (0.00s) --- SKIP: TestRemove/Kubernetes (0.00s) remove_test.go:22: !environment.KubernetesEnabled() DONE 94 tests, 5 skipped in 33.248s Stopping cliendtoendsuite_engine_1 ... Stopping cliendtoendsuite_notary-server_1 ... Stopping cliendtoendsuite_registry_1 ... Stopping cliendtoendsuite_evil-notary-server_1 ... [2A[2K Stopping cliendtoendsuite_registry_1 ... [32mdone[0m [2B[3A[2K Stopping cliendtoendsuite_notary-server_1 ... [32mdone[0m [3B[1A[2K Stopping cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [1B[4A[2K Stopping cliendtoendsuite_engine_1 ... [32mdone[0m [4BRemoving cliendtoendsuite_engine_1 ... Removing cliendtoendsuite_notary-server_1 ... Removing cliendtoendsuite_registry_1 ... Removing cliendtoendsuite_evil-notary-server_1 ... [1A[2K Removing cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [1B[3A[2K Removing cliendtoendsuite_notary-server_1 ... [32mdone[0m [3B[2A[2K Removing cliendtoendsuite_registry_1 ... [32mdone[0m [2B[4A[2K Removing cliendtoendsuite_engine_1 ... [32mdone[0m [4BRemoving network cliendtoendsuite_default Removing image cliendtoendsuite_notary-server Removing image cliendtoendsuite_evil-notary-server [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (e2e (experimental)) [Pipeline] sh + E2E_UNIQUE_ID=clie2e1 IMAGE_TAG=clie2e1 DOCKER_BUILDKIT=1 make -f docker.Makefile test-e2e-experimental docker build -t docker-cli-e2eclie2e1 --build-arg=GO_VERSION --build-arg VERSION=20.03.0-dev --build-arg GITCOMMIT= -f ./dockerfiles/Dockerfile.e2e . #2 [internal] load build definition from Dockerfile.e2e #2 transferring dockerfile: 42B done #2 DONE 0.0s #1 [internal] load .dockerignore #1 transferring context: 34B done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/golang:1.13.15-buster #3 DONE 0.2s #4 [1/11] FROM docker.io/library/golang:1.13.15-buster@sha256:66a3f6817c129... #4 DONE 0.0s #10 [internal] load build context #10 transferring context: 393.89kB 0.2s done #10 DONE 0.2s #11 [7/11] COPY e2e/testdata/notary/root-ca.cert /usr/share/ca-certificates/... #11 CACHED #14 [10/11] RUN ./scripts/build/binary #14 CACHED #5 [2/11] RUN apt-get update && apt-get install -y build-essential ... #5 CACHED #7 [4/11] RUN curl -fsSL https://github.com/theupdateframework/notary/relea... #7 CACHED #9 [6/11] WORKDIR /go/src/github.com/docker/cli #9 CACHED #13 [9/11] COPY . . #13 CACHED #6 [3/11] RUN curl -fsSL https://github.com/docker/compose/releases/downloa... #6 CACHED #8 [5/11] RUN curl -fsSL https://github.com/gotestyourself/gotestsum/releas... #8 CACHED #12 [8/11] RUN echo 'notary.cert' >> /etc/ca-certificates.conf && update-ca-... #12 CACHED #15 [11/11] RUN ./scripts/build/plugins e2e/cli-plugins/plugins/* #15 CACHED #16 exporting to image #16 exporting layers done #16 writing image sha256:11bf6470a145dfd94f65989866ad405d1584301d5dda3d41e7e2583be0188e35 #16 writing image sha256:11bf6470a145dfd94f65989866ad405d1584301d5dda3d41e7e2583be0188e35 done #16 naming to docker.io/library/docker-cli-e2eclie2e1 done #16 DONE 0.0s docker run --rm --mount type=bind,src=/var/run/docker.sock,dst=/var/run/docker.sock -e VERSION=20.03.0-dev -e GITCOMMIT -e PLATFORM -e TESTFLAGS -e TESTDIRS -e GOOS -e GOARCH -e GOARM -e TEST_ENGINE_VERSION= -e DOCKERD_EXPERIMENTAL=1 docker-cli-e2eclie2e1 Native build is an experimental feature and could change at any time Creating network "cliendtoendsuite_default" with the default driver Building evil-notary-server #2 [internal] load .dockerignore #2 sha256:046bf340f49ccbb803e3d72843d3bdba0b89bc8f4ad613d25b72033d64abbc9a #2 transferring context: 2B done #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.evil-notary-server #1 sha256:c7e40db9b005874550252640d1679a1507b724a248cdffd9e9fa3102a0888f1c #1 transferring dockerfile: 150B done #1 DONE 0.0s #3 [internal] load metadata for docker.io/library/notary:server-0.5.0 #3 sha256:a1da53cf05b63d7b254e8b6a3d96b994c4ae5f01cb10e10906db53f729e6903e #3 DONE 0.1s #4 [1/2] FROM docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 #4 sha256:cd52ec412b0b1cb53bf96e4160cee4046ce1447da482224a0d115d68a2468239 #4 DONE 0.0s #5 [internal] load build context #5 sha256:831c9835e1bf01a59645dea8441585935efa65247d8c3a2cd5796c63e4be7c0d #5 transferring context: 5.73kB done #5 DONE 0.0s #6 [2/2] COPY ./notary-evil/ /fixtures/ #6 sha256:e91fbfb58ddf985cd0328446f7374ca8ad461aa0952d178b0e18d3a2e442e8cf #6 CACHED #7 exporting to image #7 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00 #7 exporting layers done #7 writing image sha256:076be5ceee8a42397c1f151c32d4e7f923a3c2c61797b797376266ccb80ff51b done #7 naming to docker.io/library/cliendtoendsuite_evil-notary-server done #7 DONE 0.0s Successfully built 076be5ceee8a42397c1f151c32d4e7f923a3c2c61797b797376266ccb80ff51b Building notary-server #2 [internal] load .dockerignore #2 sha256:3833d23665a2773b18378ca0cd357c2137633c818ea2c3c6c90051e69fc43785 #2 DONE 0.0s #1 [internal] load build definition from Dockerfile.notary-server #1 sha256:91edfb38b9e2aa1e4dcdfe13fdb63c621770c4d2cb6cc9a6328ae641426feb19 #1 transferring dockerfile: 140B done #1 DONE 0.0s #2 [internal] load .dockerignore #2 sha256:3833d23665a2773b18378ca0cd357c2137633c818ea2c3c6c90051e69fc43785 #2 transferring context: 2B done #2 DONE 0.0s #3 [internal] load metadata for docker.io/library/notary:server-0.5.0 #3 sha256:a1da53cf05b63d7b254e8b6a3d96b994c4ae5f01cb10e10906db53f729e6903e #3 DONE 0.2s #4 [1/2] FROM docker.io/library/notary:server-0.5.0@sha256:d59e4ecc20198447a9818e7f16d0a02ba1d9905de71e5354bc8ee0017304a244 #4 sha256:cd52ec412b0b1cb53bf96e4160cee4046ce1447da482224a0d115d68a2468239 #4 DONE 0.0s #5 [internal] load build context #5 sha256:0bc3a8e1faf24e74e03d0831eabe6d631f19eebeb5b3d8ba30ad171787c82f0e #5 transferring context: 5.70kB done #5 DONE 0.0s #6 [2/2] COPY ./notary/ /fixtures/ #6 sha256:b558b762ff25356fe39dd32b96a8e0219afa6887f66fb111fc930967864f4961 #6 CACHED #7 exporting to image #7 sha256:e8c613e07b0b7ff33893b694f7759a10d42e180f2b4dc349fb57dc6b71dcab00 #7 exporting layers done #7 writing image sha256:79a84b7cfc8503e4a8452ee7f9db922aff9bf4eb1fb970487afd5986dd5b022d done #7 naming to docker.io/library/cliendtoendsuite_notary-server done #7 DONE 0.0s Successfully built 79a84b7cfc8503e4a8452ee7f9db922aff9bf4eb1fb970487afd5986dd5b022d Creating cliendtoendsuite_evil-notary-server_1 ... Creating cliendtoendsuite_notary-server_1 ... Creating cliendtoendsuite_engine_1 ... Creating cliendtoendsuite_registry_1 ... [1A[2K Creating cliendtoendsuite_registry_1 ... [32mdone[0m [1B[3A[2K Creating cliendtoendsuite_notary-server_1 ... [32mdone[0m [3B[4A[2K Creating cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [4B[2A[2K Creating cliendtoendsuite_engine_1 ... [32mdone[0m [2BWaiting for docker daemon to become available at tcp://172.20.0.4:2375 Client: Version: 20.03.0-dev API version: 1.40 Go version: go1.13.15 Git commit: Built: Fri Sep 18 00:06:06 2020 OS/Arch: linux/amd64 Context: default Experimental: false Server: Docker Engine - Community Engine: Version: 19.03.13 API version: 1.40 (minimum version 1.12) Go version: go1.13.15 Git commit: 4484c46 Built: Wed Sep 16 17:04:43 2020 OS/Arch: linux/amd64 Experimental: true containerd: Version: v1.3.7 GitCommit: 8fba4e9a7d01810a393d5d25a3621dc101981175 runc: Version: 1.0.0-rc10 GitCommit: dc9208a3303feef5b3839f4323d9beb36df0a9dd docker-init: Version: 0.18.0 GitCommit: fec3683 sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d: Pulling from library/alpine 88286f41530e: Pulling fs layer 88286f41530e: Download complete 88286f41530e: Pull complete Digest: sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d Status: Downloaded newer image for alpine@sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d docker.io/library/alpine@sha256:f006ecbb824d87947d0b51ab8488634bf69fe4094959d935c0c103f4820a417d The push refers to repository [registry:5000/alpine] 5bef08742407: Preparing 5bef08742407: Pushed 3.6: digest: sha256:641b95ddb2ea9dc2af1a0113b6b348ebc20872ba615204fbe12148e98fd6f23d size: 528 sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7: Pulling from library/busybox 03b1be98f3f9: Pulling fs layer 03b1be98f3f9: Verifying Checksum 03b1be98f3f9: Download complete 03b1be98f3f9: Pull complete Digest: sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 Status: Downloaded newer image for busybox@sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 docker.io/library/busybox@sha256:3e8fa85ddfef1af9ca85a5cfb714148956984e02f00bec3f7f49d3925a91e0e7 The push refers to repository [registry:5000/busybox] 6a749002dd6a: Preparing 6a749002dd6a: Pushed 1.27.2: digest: sha256:030fcb92e1487b18c974784dcc110a93147c9fc402188370fbfd17efabffc6af size: 527 Swarm initialized: current node (wvkmnv2knyudti06jkuyithma) is now a manager. To add a worker to this swarm, run the following command: docker swarm join --token SWMTKN-1-2bjhmqf9maxvrm1jxsv6ey26cgozyymxu7ud4j7ia33dgddew7-aujrej39r91p5euqgnlhp6go1 172.20.0.4:2377 To add a manager to this swarm, run 'docker swarm join-token manager' and follow the instructions. [32m✓[0m e2e/cli-plugins (2.797s) [33m∅[0m e2e/cli-plugins/plugins/badmeta [33m∅[0m e2e/cli-plugins/plugins/nopersistentprerun [32m✓[0m e2e/context (45ms) [32m✓[0m e2e/global (114ms) [32m✓[0m e2e/container (9.004s) [32m✓[0m e2e/plugin (3.621s) [32m✓[0m e2e/stack (666ms) [32m✓[0m e2e/system (76ms) [32m✓[0m e2e/image (13.792s) [33m∅[0m e2e/internal/fixtures [33m∅[0m e2e/plugin/basic [31m✖[0m e2e/trust (2.48s) === [33mSkipped[0m === [33mSKIP[0m: e2e/cli-plugins TestCLIPluginDialStdio (0.00s) dial_test.go:16: skipping plugin dial-stdio test since DOCKER_CLI_PLUGIN_USE_DIAL_STDIO is not set === [33mSKIP[0m: e2e/container TestRunWithCgroupNamespace (0.09s) run_test.go:141: !cgroupNsFound: running against a daemon that doesn't support cgroup namespaces (security options: [name=apparmor name=seccomp,profile=default] ) === [33mSKIP[0m: e2e/stack TestDeployWithNamedResources/Kubernetes (0.00s) --- SKIP: TestDeployWithNamedResources/Kubernetes (0.00s) deploy_test.go:22: FIXME(chris-crone): currently does not work with compose for kubernetes. === [33mSKIP[0m: e2e/stack TestRemove/Kubernetes (0.00s) --- SKIP: TestRemove/Kubernetes (0.00s) remove_test.go:22: !environment.KubernetesEnabled() === [31mFailed[0m === [31mFAIL[0m: e2e/trust TestSignWithLocalFlag (0.31s) sign_test.go:57: assertion failed: Command: docker -D trust sign registry:5000/signlocal:v1 ExitCode: 1 Error: exit status 1 Stdout: Created signer: eiais Finished initializing signed repository for registry:5000/signlocal:v1 The push refers to repository [registry:5000/signlocal] 5bef08742407: Preparing 5bef08742407: Mounted from sign v1: digest: sha256:641b95ddb2ea9dc2af1a0113b6b348ebc20872ba615204fbe12148e98fd6f23d size: 528 Signing and pushing trust metadata Stderr: time="2020-09-18T00:09:42Z" level=debug msg="reading certificate directory: /tmp/trust_test-641298897/tls/notary-server:4443" time="2020-09-18T00:09:42Z" level=debug msg="Making dir path: /tmp/trust_test-641298897/trust/tuf/registry:5000/signlocal/changelist" time="2020-09-18T00:09:42Z" level=debug msg="received HTTP status 404 when requesting root." time="2020-09-18T00:09:42Z" level=debug msg="generated ECDSA key with keyID: d19930816a220b54a7dfdd2c4af5aa055d2d426891fe4eebd7d5077294972311" time="2020-09-18T00:09:42Z" level=debug msg="generated new ecdsa key for role: root and keyID: d19930816a220b54a7dfdd2c4af5aa055d2d426891fe4eebd7d5077294972311" time="2020-09-18T00:09:42Z" level=debug msg="generated ECDSA key with keyID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="generated new ecdsa key for role: targets and keyID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="got remote timestamp ed25519 key with keyID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="got remote snapshot ed25519 key with keyID: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="generating new snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="Saving changes to Trusted Collection." time="2020-09-18T00:09:42Z" level=debug msg="signing root..." time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 0/0 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign targets called for role targets" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 0/0 required keys" time="2020-09-18T00:09:42Z" level=debug msg="generated ECDSA key with keyID: 79236fc4c0188b544a8f762d2a2f1602e09ccc76c6e34979c1b21dc4fe4d933e" time="2020-09-18T00:09:42Z" level=debug msg="generated new ecdsa key for role: eiais and keyID: 79236fc4c0188b544a8f762d2a2f1602e09ccc76c6e34979c1b21dc4fe4d933e" time="2020-09-18T00:09:42Z" level=debug msg="Adding delegation \"targets/eiais\" with threshold 1, and 1 keys\\n" time="2020-09-18T00:09:42Z" level=debug msg="Adding [] paths to delegation targets/eiais\\n" time="2020-09-18T00:09:42Z" level=debug msg="Adding delegation \"targets/releases\" with threshold 1, and 1 keys\\n" time="2020-09-18T00:09:42Z" level=debug msg="Adding [] paths to delegation targets/releases\\n" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="received HTTP status 404 when requesting root." time="2020-09-18T00:09:42Z" level=debug msg="Loading trusted collection." time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="targets role has key IDs: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="role targets/eiais with no Paths will never be able to publish content until one or more are added" time="2020-09-18T00:09:42Z" level=debug msg="role targets/releases with no Paths will never be able to publish content until one or more are added" time="2020-09-18T00:09:42Z" level=debug msg="applied 4 change(s)" time="2020-09-18T00:09:42Z" level=debug msg="sign targets called for role targets" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 0/0 required keys" time="2020-09-18T00:09:42Z" level=debug msg="generating new snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="signing snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="Client does not have the key to sign snapshot. Assuming that server should sign the snapshot." time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="updating TUF client" time="2020-09-18T00:09:42Z" level=debug msg="Loading timestamp..." time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for timestamp" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified downloaded timestamp" time="2020-09-18T00:09:42Z" level=debug msg="Loading snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="no snapshot in cache, must download" time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for snapshot.636f88dfd6f365fb8160800c066b23461ce320fd6334f1f574972a47ad428e16" time="2020-09-18T00:09:42Z" level=debug msg="snapshot role has key IDs: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified downloaded snapshot.636f88dfd6f365fb8160800c066b23461ce320fd6334f1f574972a47ad428e16" time="2020-09-18T00:09:42Z" level=debug msg="Loading targets..." time="2020-09-18T00:09:42Z" level=debug msg="cached targets is invalid (must download): sha256 checksum for targets did not match: expected 4da8e3170f117dc6ea1512d8e73470b9c86ccd6c191f130393174f08889e2bcd" time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for targets.4da8e3170f117dc6ea1512d8e73470b9c86ccd6c191f130393174f08889e2bcd" time="2020-09-18T00:09:42Z" level=debug msg="targets role has key IDs: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="targets role has key IDs: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified downloaded targets.4da8e3170f117dc6ea1512d8e73470b9c86ccd6c191f130393174f08889e2bcd" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/eiais because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/releases because there is no checksum for it" Signing and pushing trust data for local image registry:5000/signlocal:v1, may overwrite remote trust data time="2020-09-18T00:09:42Z" level=debug msg="reading certificate directory: /tmp/trust_test-641298897/tls/notary-server:4443" time="2020-09-18T00:09:42Z" level=debug msg="Making dir path: /tmp/trust_test-641298897/trust/tuf/registry:5000/signlocal/changelist" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="updating TUF client" time="2020-09-18T00:09:42Z" level=debug msg="Loading timestamp..." time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for timestamp" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified downloaded timestamp" time="2020-09-18T00:09:42Z" level=debug msg="Loading snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="snapshot role has key IDs: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified cached snapshot" time="2020-09-18T00:09:42Z" level=debug msg="Loading targets..." time="2020-09-18T00:09:42Z" level=debug msg="targets role has key IDs: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified cached targets" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/eiais because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/releases because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="updating TUF client" time="2020-09-18T00:09:42Z" level=debug msg="Loading timestamp..." time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for timestamp" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified downloaded timestamp" time="2020-09-18T00:09:42Z" level=debug msg="Loading snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="snapshot role has key IDs: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified cached snapshot" time="2020-09-18T00:09:42Z" level=debug msg="Loading targets..." time="2020-09-18T00:09:42Z" level=debug msg="targets role has key IDs: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified cached targets" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/eiais because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/releases because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="Adding target \"v1\" with sha256 \"641b95ddb2ea9dc2af1a0113b6b348ebc20872ba615204fbe12148e98fd6f23d\" and size 528 bytes.\n" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="entered ValidateRoot with dns: registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="found the following root keys: [a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3]" time="2020-09-18T00:09:42Z" level=debug msg="found 1 valid leaf certificates for registry:5000/signlocal: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="found 1 leaf certs, of which 1 are valid leaf certs for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking root against trust_pinning config for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="checking trust-pinning for cert: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg=" role has key IDs: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: a916ff31453620eb225cfab3ff176e7ebb28260b04645a645a3a54221b06d1e3" time="2020-09-18T00:09:42Z" level=debug msg="root validation succeeded for registry:5000/signlocal" time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for root" time="2020-09-18T00:09:42Z" level=debug msg="updating TUF client" time="2020-09-18T00:09:42Z" level=debug msg="Loading timestamp..." time="2020-09-18T00:09:42Z" level=debug msg="200 when retrieving metadata for timestamp" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="timestamp role has key IDs: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: bf70baeb00b7479612e8d24876e182575a3ca8036d1e961459f81829f6046abd" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified downloaded timestamp" time="2020-09-18T00:09:42Z" level=debug msg="Loading snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="snapshot role has key IDs: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 8e5617bfe8203393f5860c254418d53ff5d06f59e05feab65b54d0d120a7e610" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified cached snapshot" time="2020-09-18T00:09:42Z" level=debug msg="Loading targets..." time="2020-09-18T00:09:42Z" level=debug msg="targets role has key IDs: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="verifying signature for key ID: 202fb69a7b717c7ebd5f7d561cc6f552cd1d939b3c66acbdf5a25c4435ea44ab" time="2020-09-18T00:09:42Z" level=debug msg="successfully verified cached targets" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/eiais because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="skipping targets/releases because there is no checksum for it" time="2020-09-18T00:09:42Z" level=debug msg="changelist add: v1" time="2020-09-18T00:09:42Z" level=debug msg="changelist add: v1" time="2020-09-18T00:09:42Z" level=debug msg="applied 2 change(s)" time="2020-09-18T00:09:42Z" level=debug msg="sign targets called for role targets/eiais" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 0/0 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign targets called for role targets/releases" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="sign called with 0/0 required keys" time="2020-09-18T00:09:42Z" level=debug msg="signing snapshot..." time="2020-09-18T00:09:42Z" level=debug msg="sign called with 1/1 required keys" time="2020-09-18T00:09:42Z" level=debug msg="Client does not have the key to sign snapshot. Assuming that server should sign the snapshot." failed to sign registry:5000/signlocal:v1: remote error: tls: bad record MAC Failures: ExitCode was 1 expected 0 Expected no error DONE 94 tests, 4 skipped, 1 failure in 33.880s Stopping cliendtoendsuite_engine_1 ... Stopping cliendtoendsuite_registry_1 ... Stopping cliendtoendsuite_evil-notary-server_1 ... Stopping cliendtoendsuite_notary-server_1 ... [1A[2K Stopping cliendtoendsuite_notary-server_1 ... [32mdone[0m [1B[2A[2K Stopping cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [2B[3A[2K Stopping cliendtoendsuite_registry_1 ... [32mdone[0m [3B[4A[2K Stopping cliendtoendsuite_engine_1 ... [32mdone[0m [4BRemoving cliendtoendsuite_engine_1 ... Removing cliendtoendsuite_registry_1 ... Removing cliendtoendsuite_evil-notary-server_1 ... Removing cliendtoendsuite_notary-server_1 ... [1A[2K Removing cliendtoendsuite_notary-server_1 ... [32mdone[0m [1B[2A[2K Removing cliendtoendsuite_evil-notary-server_1 ... [32mdone[0m [2B[4A[2K Removing cliendtoendsuite_engine_1 ... [32mdone[0m [4B[3A[2K Removing cliendtoendsuite_registry_1 ... [32mdone[0m [3BRemoving network cliendtoendsuite_default Removing image cliendtoendsuite_notary-server Removing image cliendtoendsuite_evil-notary-server docker.Makefile:148: recipe for target 'test-e2e-experimental' failed make: *** [test-e2e-experimental] Error 1 [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (e2e (ssh connhelper)) Stage "e2e (ssh connhelper)" skipped due to earlier failure(s) [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // timeout [Pipeline] } [Pipeline] // withEnv [Pipeline] } [Pipeline] // node [Pipeline] End of Pipeline GitHub has been notified of this commit’s build result ERROR: script returned exit code 2 Finished: FAILURE