W1205 23:43:29.264] ************************************************************************** bootstrap.py is deprecated! test-infra oncall does not support any job still using bootstrap.py. Please migrate your job to podutils! https://github.com/kubernetes/test-infra/blob/master/prow/pod-utilities.md ************************************************************************** I1205 23:43:29.265] Args: --job=chewong-kubernetes-azure-disk-csi-driver-e2e --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=chewong-kubernetes-azure-disk-csi-driver-e2e --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes=master:a3718d765343bc80aaaa8c95af96070b69bd7e19,85958:8b511b2d3ad3655232a326cc31d489ec099e5f60 --upload=gs://kubernetes-upstream/pr-logs --timeout=460 --scenario=kubernetes_e2e -- --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=host-go --provider=azure --ginkgo-parallel=1 --acsengine-agentpoolcount=2 --acsengine-admin-username=azureuser '--acsengine-creds=$AZURE_CREDENTIALS' --acsengine-orchestratorRelease=1.16 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-ccm=True --acsengine-hyperkube=True --acsengine-location=westus2 '--acsengine-public-key=$AZURE_SSH_PUBLIC_KEY_FILE' --acsengine-template-url=https://gist.githubusercontent.com/chewong/7a3cc2b7c8d7af920941a8f3532291df/raw/473d0bf824c8824525f51d85ac52c1db53d762fd/linux-ccm.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-linux-amd64.tar.gz '--test_args=--ginkgo.flakeAttempts=2 --ginkgo.focus=disk.csi.azure.com' --timeout=420m I1205 23:43:29.265] Bootstrap chewong-kubernetes-azure-disk-csi-driver-e2e... I1205 23:43:29.269] Builder: f7701582-17b8-11ea-918b-16773c0c7aed I1205 23:43:29.269] Image: chewong/kubekins-e2e:e4f5afd148972c05c198ac8621c8f1694c6d79d8 I1205 23:43:29.269] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992 I1205 23:43:29.269] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1205 23:43:29.770] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1205 23:43:29.799] process 209 exited with code 0 after 0.0m I1205 23:43:29.800] Call: gcloud config get-value account I1205 23:43:30.035] process 222 exited with code 0 after 0.0m I1205 23:43:30.036] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1205 23:43:30.036] Root: /go/src I1205 23:43:30.036] cd to /go/src I1205 23:43:30.036] Checkout: /go/src/k8s.io/kubernetes master:a3718d765343bc80aaaa8c95af96070b69bd7e19,85958:8b511b2d3ad3655232a326cc31d489ec099e5f60 to /go/src/k8s.io/kubernetes I1205 23:43:30.036] Call: git init k8s.io/kubernetes I1205 23:43:30.040] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/ I1205 23:43:30.040] process 235 exited with code 0 after 0.0m I1205 23:43:30.040] Call: git config --local user.name 'K8S Bootstrap' I1205 23:43:30.042] process 236 exited with code 0 after 0.0m I1205 23:43:30.043] Call: git config --local user.email k8s_bootstrap@localhost I1205 23:43:30.045] process 237 exited with code 0 after 0.0m I1205 23:43:30.045] Call: git fetch --quiet --tags https://github.com/kubernetes/kubernetes master +refs/pull/85958/head:refs/pr/85958 I1205 23:44:31.190] process 238 exited with code 0 after 1.0m I1205 23:44:31.190] Call: git checkout -B test a3718d765343bc80aaaa8c95af96070b69bd7e19 W1205 23:44:33.243] Switched to a new branch 'test' I1205 23:44:33.253] process 249 exited with code 0 after 0.0m I1205 23:44:33.253] Call: git show -s --format=format:%ct HEAD I1205 23:44:33.263] process 250 exited with code 0 after 0.0m I1205 23:44:33.264] Call: git merge --no-ff -m 'Merge +refs/pull/85958/head:refs/pr/85958' 8b511b2d3ad3655232a326cc31d489ec099e5f60 I1205 23:44:33.851] Merge made by the 'recursive' strategy. I1205 23:44:33.854] test/e2e/storage/csi_volumes.go | 1 + I1205 23:44:33.854] test/e2e/storage/drivers/csi.go | 87 +++++++++++++++++++++++++++++++++++++++++ I1205 23:44:33.854] 2 files changed, 88 insertions(+) I1205 23:44:33.854] process 251 exited with code 0 after 0.0m I1205 23:44:33.855] Configure environment... I1205 23:44:33.855] Call: git show -s --format=format:%ct HEAD I1205 23:44:33.859] process 293 exited with code 0 after 0.0m I1205 23:44:33.860] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1205 23:44:34.804] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1205 23:44:34.922] process 294 exited with code 0 after 0.0m I1205 23:44:34.922] Call: gcloud config get-value account I1205 23:44:35.151] process 307 exited with code 0 after 0.0m I1205 23:44:35.151] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1205 23:44:35.151] Call: bash -c ' set -o errexit set -o nounset export KUBE_ROOT=. source hack/lib/version.sh kube::version::get_version_vars echo $KUBE_GIT_VERSION ' I1205 23:44:35.561] process 320 exited with code 0 after 0.0m I1205 23:44:35.562] Start 1202735202640596992 at v1.18.0-alpha.0.1463+ffa65da1319433... I1205 23:44:35.563] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_hmHvYr gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992/started.json I1205 23:44:37.067] process 353 exited with code 0 after 0.0m I1205 23:44:37.068] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992' cp /tmp/gsutil_PGp3rz gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992.txt I1205 23:44:38.232] process 534 exited with code 0 after 0.0m I1205 23:44:38.233] Call: /workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=host-go --provider=azure --ginkgo-parallel=1 --acsengine-agentpoolcount=2 --acsengine-admin-username=azureuser --acsengine-creds=/etc/azure-cred/credentials --acsengine-orchestratorRelease=1.16 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-ccm=True --acsengine-hyperkube=True --acsengine-location=westus2 --acsengine-public-key=/etc/azure-ssh/azure-ssh-pub --acsengine-template-url=https://gist.githubusercontent.com/chewong/7a3cc2b7c8d7af920941a8f3532291df/raw/473d0bf824c8824525f51d85ac52c1db53d762fd/linux-ccm.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-linux-amd64.tar.gz '--test_args=--ginkgo.flakeAttempts=2 --ginkgo.focus=disk.csi.azure.com' --timeout=420m W1205 23:44:38.268] starts with local mode W1205 23:44:38.268] Environment: W1205 23:44:38.268] ARTIFACTS=/workspace/_artifacts W1205 23:44:38.268] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa W1205 23:44:38.268] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub W1205 23:44:38.268] AZURE_CREDENTIALS=/etc/azure-cred/credentials W1205 23:44:38.269] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub W1205 23:44:38.269] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer W1205 23:44:38.269] BAZEL_REMOTE_CACHE_ENABLED=false W1205 23:44:38.269] BAZEL_VERSION=0.23.2 W1205 23:44:38.269] BOOTSTRAP_MIGRATION=yes W1205 23:44:38.269] BUILD_ID=1202735202640596992 W1205 23:44:38.269] BUILD_NUMBER=1202735202640596992 W1205 23:44:38.269] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true W1205 23:44:38.270] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud W1205 23:44:38.270] CLOUDSDK_CORE_DISABLE_PROMPTS=1 W1205 23:44:38.270] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false W1205 23:44:38.270] CLOUD_CONFIG=random W1205 23:44:38.270] DECK_PORT=tcp://10.0.160.83:80 W1205 23:44:38.270] DECK_PORT_80_TCP=tcp://10.0.160.83:80 W1205 23:44:38.270] DECK_PORT_80_TCP_ADDR=10.0.160.83 W1205 23:44:38.270] DECK_PORT_80_TCP_PORT=80 W1205 23:44:38.270] DECK_PORT_80_TCP_PROTO=tcp W1205 23:44:38.271] DECK_SERVICE_HOST=10.0.160.83 W1205 23:44:38.271] DECK_SERVICE_PORT=80 W1205 23:44:38.271] DOCKER_IN_DOCKER_ENABLED=true W1205 23:44:38.271] DOCKER_IN_DOCKER_IPV6_ENABLED=false W1205 23:44:38.271] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992/artifacts W1205 23:44:38.271] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json W1205 23:44:38.272] GOPATH=/go W1205 23:44:38.272] GO_TARBALL=go1.13.4.linux-amd64.tar.gz W1205 23:44:38.272] HOME=/workspace W1205 23:44:38.272] HOOK_PORT=tcp://10.0.144.133:8888 W1205 23:44:38.272] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888 W1205 23:44:38.272] HOOK_PORT_8888_TCP_ADDR=10.0.144.133 W1205 23:44:38.272] HOOK_PORT_8888_TCP_PORT=8888 W1205 23:44:38.272] HOOK_PORT_8888_TCP_PROTO=tcp W1205 23:44:38.273] HOOK_SERVICE_HOST=10.0.144.133 W1205 23:44:38.273] HOOK_SERVICE_PORT=8888 W1205 23:44:38.273] HOSTNAME=f7701582-17b8-11ea-918b-16773c0c7aed W1205 23:44:38.273] IMAGE=chewong/kubekins-e2e:e4f5afd148972c05c198ac8621c8f1694c6d79d8 W1205 23:44:38.273] INSTANCE_PREFIX=bootstrap-e2e W1205 23:44:38.273] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine W1205 23:44:38.273] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub W1205 23:44:38.274] JOB_NAME=chewong-kubernetes-azure-disk-csi-driver-e2e W1205 23:44:38.274] JOB_SPEC={"type":"presubmit","job":"chewong-kubernetes-azure-disk-csi-driver-e2e","buildid":"1202735202640596992","prowjobid":"f7701582-17b8-11ea-918b-16773c0c7aed","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"bd4c9a2263a3828081a850907326ebdf14cb242a","base_link":"https://github.com/Azure/kubernetes/commit/bd4c9a2263a3828081a850907326ebdf14cb242a","pulls":[{"number":113,"author":"chewong","sha":"879465972041635fd7bc8269da49153b67e63822","link":"https://github.com/Azure/kubernetes/pull/113","commit_link":"https://github.com/Azure/kubernetes/pull/113/commits/879465972041635fd7bc8269da49153b67e63822","author_link":"https://github.com/chewong"}]}} W1205 23:44:38.274] JOB_TYPE=presubmit W1205 23:44:38.274] KUBERNETES_CONFORMANCE_PROVIDER=azure W1205 23:44:38.274] KUBERNETES_PORT=tcp://10.0.0.1:443 W1205 23:44:38.275] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443 W1205 23:44:38.275] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1 W1205 23:44:38.275] KUBERNETES_PORT_443_TCP_PORT=443 W1205 23:44:38.275] KUBERNETES_PORT_443_TCP_PROTO=tcp W1205 23:44:38.275] KUBERNETES_SERVICE_HOST=10.0.0.1 W1205 23:44:38.275] KUBERNETES_SERVICE_PORT=443 W1205 23:44:38.275] KUBERNETES_SERVICE_PORT_HTTPS=443 W1205 23:44:38.275] KUBETEST_IN_DOCKER=true W1205 23:44:38.275] KUBETEST_MANUAL_DUMP=y W1205 23:44:38.276] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e W1205 23:44:38.276] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e W1205 23:44:38.276] KUBE_VERBOSE=0 W1205 23:44:38.276] NODE_NAME=f7701582-17b8-11ea-918b-16773c0c7aed W1205 23:44:38.276] PATH=/go/bin:/go/bin:/usr/local/go/bin:/google-cloud-sdk/bin:/workspace:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin W1205 23:44:38.276] PROW_JOB_ID=f7701582-17b8-11ea-918b-16773c0c7aed W1205 23:44:38.276] PULL_BASE_REF=master W1205 23:44:38.277] PULL_BASE_SHA=bd4c9a2263a3828081a850907326ebdf14cb242a W1205 23:44:38.277] PULL_NUMBER=113 W1205 23:44:38.277] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822 W1205 23:44:38.277] PULL_REFS=master:bd4c9a2263a3828081a850907326ebdf14cb242a,113:879465972041635fd7bc8269da49153b67e63822 W1205 23:44:38.277] PWD=/workspace W1205 23:44:38.277] REGISTRY=k8sprowinternal.azurecr.io W1205 23:44:38.277] REPO_NAME=kubernetes W1205 23:44:38.277] REPO_OWNER=Azure W1205 23:44:38.278] SHLVL=2 W1205 23:44:38.278] SOURCE_DATE_EPOCH=1575565354 W1205 23:44:38.278] TERM=xterm W1205 23:44:38.278] TIDE_PORT=tcp://10.0.180.53:80 W1205 23:44:38.278] TIDE_PORT_80_TCP=tcp://10.0.180.53:80 W1205 23:44:38.278] TIDE_PORT_80_TCP_ADDR=10.0.180.53 W1205 23:44:38.278] TIDE_PORT_80_TCP_PORT=80 W1205 23:44:38.278] TIDE_PORT_80_TCP_PROTO=tcp W1205 23:44:38.278] TIDE_SERVICE_HOST=10.0.180.53 W1205 23:44:38.279] TIDE_SERVICE_PORT=80 W1205 23:44:38.279] WORKSPACE=/workspace W1205 23:44:38.279] _=./test-infra/jenkins/bootstrap.py W1205 23:44:38.279] Run: ('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=host-go', '--up', '--down', '--test', '--deployment=acsengine', '--provider=azure', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--ginkgo-parallel=1', '--acsengine-agentpoolcount=2', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.16', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-ccm=True', '--acsengine-hyperkube=True', '--acsengine-location=westus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://gist.githubusercontent.com/chewong/7a3cc2b7c8d7af920941a8f3532291df/raw/473d0bf824c8824525f51d85ac52c1db53d762fd/linux-ccm.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-linux-amd64.tar.gz', '--test_args=--ginkgo.flakeAttempts=2 --ginkgo.focus=disk.csi.azure.com', '--timeout=420m') W1205 23:44:38.315] 2019/12/05 23:44:38 main.go:332: Limiting testing to 7h0m0s W1205 23:44:38.316] 2019/12/05 23:44:38 azure.go:242: Reading credentials file /etc/azure-cred/credentials W1205 23:44:38.316] 2019/12/05 23:44:38 process.go:153: Running: make -C /go/src/k8s.io/kubernetes all I1205 23:44:38.416] make: Entering directory '/go/src/k8s.io/kubernetes' I1205 23:44:38.416] make[1]: Entering directory '/go/src/k8s.io/kubernetes' I1205 23:44:46.157] +++ [1205 23:44:46] Building go targets for linux/amd64: I1205 23:44:46.158] ./vendor/k8s.io/code-generator/cmd/deepcopy-gen I1205 23:44:53.128] +++ [1205 23:44:53] Building go targets for linux/amd64: I1205 23:44:53.128] ./vendor/k8s.io/code-generator/cmd/defaulter-gen I1205 23:44:58.648] +++ [1205 23:44:58] Building go targets for linux/amd64: I1205 23:44:58.649] ./vendor/k8s.io/code-generator/cmd/conversion-gen I1205 23:45:09.237] +++ [1205 23:45:09] Building go targets for linux/amd64: I1205 23:45:09.237] ./vendor/k8s.io/kube-openapi/cmd/openapi-gen I1205 23:45:21.030] +++ [1205 23:45:21] Building go targets for linux/amd64: I1205 23:45:21.030] ./vendor/github.com/go-bindata/go-bindata/go-bindata I1205 23:45:22.182] make[1]: Leaving directory '/go/src/k8s.io/kubernetes' I1205 23:45:22.466] +++ [1205 23:45:22] Building go targets for linux/amd64: I1205 23:45:22.466] cmd/kube-proxy I1205 23:45:22.466] cmd/kube-apiserver I1205 23:45:22.466] cmd/kube-controller-manager I1205 23:45:22.466] cmd/kubelet I1205 23:45:22.466] cmd/kubeadm I1205 23:45:22.466] cmd/kube-scheduler I1205 23:45:22.466] vendor/k8s.io/apiextensions-apiserver I1205 23:45:22.467] cluster/gce/gci/mounter I1205 23:45:22.467] cmd/kubectl I1205 23:45:22.467] cmd/gendocs I1205 23:45:22.467] cmd/genkubedocs I1205 23:45:22.467] cmd/genman I1205 23:45:22.467] cmd/genyaml I1205 23:45:22.467] cmd/genswaggertypedocs I1205 23:45:22.467] cmd/linkcheck I1205 23:45:22.467] vendor/github.com/onsi/ginkgo/ginkgo I1205 23:45:22.467] test/e2e/e2e.test I1205 23:45:22.467] cluster/images/conformance/go-runner I1205 23:45:22.467] cmd/kubemark I1205 23:45:22.468] vendor/github.com/onsi/ginkgo/ginkgo I1205 23:45:22.468] test/e2e_node/e2e_node.test I1205 23:49:38.136] make: Leaving directory '/go/src/k8s.io/kubernetes' W1205 23:49:38.236] 2019/12/05 23:49:38 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes all' finished in 4m59.820104479s W1205 23:49:38.237] 2019/12/05 23:49:38 util.go:265: Flushing memory. W1205 23:49:58.172] 2019/12/05 23:49:58 azure.go:989: Deleting resource group: kubetest-332c67f7-17b9-11ea-ab0b-02427c6ad54d. W1205 23:49:58.519] 2019/12/05 23:49:58 azure.go:720: CWD /go/src/k8s.io/kubernetes W1205 23:49:58.519] 2019/12/05 23:49:58 azure.go:739: Attempting Docker login with azure cred. W1205 23:49:59.891] 2019/12/05 23:49:59 azure.go:748: Docker login success. W1205 23:49:59.891] 2019/12/05 23:49:59 azure.go:759: Building cloud controller manager and cloud node manager. W1205 23:49:59.892] 2019/12/05 23:49:59 util.go:56: Warning: Couldn't find directory src/sigs.k8s.io/cloud-provider-azure under any of GOPATH /go, defaulting to /go/src/k8s.io/cloud-provider-azure W1205 23:49:59.892] 2019/12/05 23:49:59 process.go:153: Running: make -C /go/src/k8s.io/cloud-provider-azure image push W1205 23:49:59.896] make: *** /go/src/k8s.io/cloud-provider-azure: No such file or directory. Stop. W1205 23:49:59.896] 2019/12/05 23:49:59 process.go:155: Step 'make -C /go/src/k8s.io/cloud-provider-azure image push' finished in 3.74583ms W1205 23:49:59.896] 2019/12/05 23:49:59 azure.go:753: Docker logout. W1205 23:49:59.956] 2019/12/05 23:49:59 util.go:56: Warning: Couldn't find directory src/sigs.k8s.io/cloud-provider-azure under any of GOPATH /go, defaulting to /go/src/k8s.io/cloud-provider-azure W1205 23:49:59.957] 2019/12/05 23:49:59 azure.go:989: Deleting resource group: kubetest-332c67f7-17b9-11ea-ab0b-02427c6ad54d. W1205 23:49:59.969] 2019/12/05 23:49:59 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1205 23:49:59.970] 2019/12/05 23:49:59 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1205 23:50:01.224] 2019/12/05 23:50:01 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.254138521s W1205 23:50:01.224] 2019/12/05 23:50:01 main.go:319: Something went wrong: starting e2e cluster: error building cloud controller manager error during make -C /go/src/k8s.io/cloud-provider-azure image push: exit status 2 W1205 23:50:01.225] Traceback (most recent call last): W1205 23:50:01.226] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in W1205 23:50:01.226] main(parse_args()) W1205 23:50:01.227] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1205 23:50:01.227] mode.start(runner_args) W1205 23:50:01.227] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1205 23:50:01.227] check_env(env, self.command, *args) W1205 23:50:01.227] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1205 23:50:01.227] subprocess.check_call(cmd, env=env) W1205 23:50:01.227] File "/usr/lib/python2.7/subprocess.py", line 190, in check_call W1205 23:50:01.227] raise CalledProcessError(retcode, cmd) W1205 23:50:01.228] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=host-go', '--up', '--down', '--test', '--deployment=acsengine', '--provider=azure', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--ginkgo-parallel=1', '--acsengine-agentpoolcount=2', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.16', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-ccm=True', '--acsengine-hyperkube=True', '--acsengine-location=westus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://gist.githubusercontent.com/chewong/7a3cc2b7c8d7af920941a8f3532291df/raw/473d0bf824c8824525f51d85ac52c1db53d762fd/linux-ccm.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-linux-amd64.tar.gz', '--test_args=--ginkgo.flakeAttempts=2 --ginkgo.focus=disk.csi.azure.com', '--timeout=420m')' returned non-zero exit status 1 E1205 23:50:01.234] Command failed I1205 23:50:01.234] process 715 exited with code 1 after 5.4m E1205 23:50:01.234] FAIL: chewong-kubernetes-azure-disk-csi-driver-e2e I1205 23:50:01.234] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1205 23:50:02.264] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1205 23:50:02.300] process 13703 exited with code 0 after 0.0m I1205 23:50:02.301] Call: gcloud config get-value account I1205 23:50:02.546] process 13716 exited with code 0 after 0.0m I1205 23:50:02.546] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1205 23:50:02.546] Upload result and artifacts... I1205 23:50:02.547] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992 I1205 23:50:02.547] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992/artifacts W1205 23:50:03.643] CommandException: One or more URLs matched no objects. E1205 23:50:03.721] Command failed I1205 23:50:03.721] process 13729 exited with code 1 after 0.0m W1205 23:50:03.721] Remote dir gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992/artifacts not exist yet I1205 23:50:03.721] Call: gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992/artifacts I1205 23:50:05.173] process 13874 exited with code 0 after 0.0m I1205 23:50:05.174] Call: git rev-parse HEAD I1205 23:50:05.178] process 14401 exited with code 0 after 0.0m I1205 23:50:05.178] Call: git rev-parse HEAD I1205 23:50:05.195] process 14402 exited with code 0 after 0.0m I1205 23:50:05.195] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json W1205 23:50:05.939] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1205 23:50:05.940] process 14403 exited with code 1 after 0.0m I1205 23:50:05.941] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_BGLarj gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1205 23:50:07.162] process 14548 exited with code 0 after 0.0m I1205 23:50:07.163] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json W1205 23:50:07.936] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1205 23:50:07.936] process 14729 exited with code 1 after 0.0m I1205 23:50:07.937] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_DSqNRV gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1205 23:50:09.067] process 14874 exited with code 0 after 0.0m I1205 23:50:09.068] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_MYlUmp gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1202735202640596992/finished.json I1205 23:50:10.271] process 15055 exited with code 0 after 0.0m I1205 23:50:10.272] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_Ii0VE_ gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/latest-build.txt I1205 23:50:11.473] process 15236 exited with code 0 after 0.0m I1205 23:50:11.473] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_hvJnQ4 gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/latest-build.txt I1205 23:50:12.534] process 15417 exited with code 0 after 0.0m