W1206 21:52:59.247] ************************************************************************** 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 ************************************************************************** I1206 21:52:59.247] 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 I1206 21:52:59.247] Bootstrap chewong-kubernetes-azure-disk-csi-driver-e2e... I1206 21:52:59.251] Builder: b5719461-1872-11ea-b3d3-b20db476995a I1206 21:52:59.251] Image: chewong/kubekins-e2e:e4f5afd148972c05c198ac8621c8f1694c6d79d8 I1206 21:52:59.251] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080 I1206 21:52:59.252] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1206 21:52:59.754] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1206 21:52:59.785] process 211 exited with code 0 after 0.0m I1206 21:52:59.785] Call: gcloud config get-value account I1206 21:53:00.021] process 224 exited with code 0 after 0.0m I1206 21:53:00.022] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1206 21:53:00.023] Root: /go/src I1206 21:53:00.024] cd to /go/src I1206 21:53:00.024] Checkout: /go/src/k8s.io/kubernetes master:a3718d765343bc80aaaa8c95af96070b69bd7e19,85958:8b511b2d3ad3655232a326cc31d489ec099e5f60 to /go/src/k8s.io/kubernetes I1206 21:53:00.024] Call: git init k8s.io/kubernetes I1206 21:53:00.028] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/ I1206 21:53:00.028] process 237 exited with code 0 after 0.0m I1206 21:53:00.028] Call: git config --local user.name 'K8S Bootstrap' I1206 21:53:00.031] process 238 exited with code 0 after 0.0m I1206 21:53:00.031] Call: git config --local user.email k8s_bootstrap@localhost I1206 21:53:00.033] process 239 exited with code 0 after 0.0m I1206 21:53:00.033] Call: git fetch --quiet --tags https://github.com/kubernetes/kubernetes master +refs/pull/85958/head:refs/pr/85958 I1206 21:53:59.061] process 240 exited with code 0 after 1.0m I1206 21:53:59.061] Call: git checkout -B test a3718d765343bc80aaaa8c95af96070b69bd7e19 W1206 21:54:01.138] Switched to a new branch 'test' I1206 21:54:01.147] process 251 exited with code 0 after 0.0m I1206 21:54:01.147] Call: git show -s --format=format:%ct HEAD I1206 21:54:01.157] process 252 exited with code 0 after 0.0m I1206 21:54:01.158] Call: git merge --no-ff -m 'Merge +refs/pull/85958/head:refs/pr/85958' 8b511b2d3ad3655232a326cc31d489ec099e5f60 I1206 21:54:01.474] Merge made by the 'recursive' strategy. I1206 21:54:01.476] test/e2e/storage/csi_volumes.go | 1 + I1206 21:54:01.476] test/e2e/storage/drivers/csi.go | 87 +++++++++++++++++++++++++++++++++++++++++ I1206 21:54:01.477] 2 files changed, 88 insertions(+) I1206 21:54:01.477] process 253 exited with code 0 after 0.0m I1206 21:54:01.477] Configure environment... I1206 21:54:01.477] Call: git show -s --format=format:%ct HEAD I1206 21:54:01.481] process 295 exited with code 0 after 0.0m I1206 21:54:01.482] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1206 21:54:02.099] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1206 21:54:02.206] process 296 exited with code 0 after 0.0m I1206 21:54:02.206] Call: gcloud config get-value account I1206 21:54:02.422] process 309 exited with code 0 after 0.0m I1206 21:54:02.423] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1206 21:54:02.423] 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 ' I1206 21:54:02.756] process 322 exited with code 0 after 0.0m I1206 21:54:02.756] Start 1203069782258094080 at v1.18.0-alpha.0.1463+ffa65da1319433... I1206 21:54:02.758] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_z7nH2I gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080/started.json I1206 21:54:04.152] process 355 exited with code 0 after 0.0m I1206 21:54:04.153] 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/1203069782258094080' cp /tmp/gsutil_Gva7eu gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080.txt I1206 21:54:05.228] process 536 exited with code 0 after 0.0m I1206 21:54:05.229] 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 W1206 21:54:05.263] starts with local mode W1206 21:54:05.264] Environment: W1206 21:54:05.264] ARTIFACTS=/workspace/_artifacts W1206 21:54:05.264] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa W1206 21:54:05.264] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub W1206 21:54:05.264] AZURE_CREDENTIALS=/etc/azure-cred/credentials W1206 21:54:05.264] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub W1206 21:54:05.264] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer W1206 21:54:05.264] BAZEL_REMOTE_CACHE_ENABLED=false W1206 21:54:05.264] BAZEL_VERSION=0.23.2 W1206 21:54:05.264] BOOTSTRAP_MIGRATION=yes W1206 21:54:05.265] BUILD_ID=1203069782258094080 W1206 21:54:05.265] BUILD_NUMBER=1203069782258094080 W1206 21:54:05.265] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true W1206 21:54:05.265] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud W1206 21:54:05.265] CLOUDSDK_CORE_DISABLE_PROMPTS=1 W1206 21:54:05.265] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false W1206 21:54:05.265] CLOUD_CONFIG=random W1206 21:54:05.265] DECK_PORT=tcp://10.0.160.83:80 W1206 21:54:05.265] DECK_PORT_80_TCP=tcp://10.0.160.83:80 W1206 21:54:05.265] DECK_PORT_80_TCP_ADDR=10.0.160.83 W1206 21:54:05.266] DECK_PORT_80_TCP_PORT=80 W1206 21:54:05.266] DECK_PORT_80_TCP_PROTO=tcp W1206 21:54:05.266] DECK_SERVICE_HOST=10.0.160.83 W1206 21:54:05.266] DECK_SERVICE_PORT=80 W1206 21:54:05.266] DOCKER_IN_DOCKER_ENABLED=true W1206 21:54:05.266] DOCKER_IN_DOCKER_IPV6_ENABLED=false W1206 21:54:05.266] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080/artifacts W1206 21:54:05.266] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json W1206 21:54:05.267] GOPATH=/go W1206 21:54:05.267] GO_TARBALL=go1.13.4.linux-amd64.tar.gz W1206 21:54:05.267] HOME=/workspace W1206 21:54:05.267] HOOK_PORT=tcp://10.0.144.133:8888 W1206 21:54:05.267] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888 W1206 21:54:05.267] HOOK_PORT_8888_TCP_ADDR=10.0.144.133 W1206 21:54:05.267] HOOK_PORT_8888_TCP_PORT=8888 W1206 21:54:05.267] HOOK_PORT_8888_TCP_PROTO=tcp W1206 21:54:05.267] HOOK_SERVICE_HOST=10.0.144.133 W1206 21:54:05.268] HOOK_SERVICE_PORT=8888 W1206 21:54:05.268] HOSTNAME=b5719461-1872-11ea-b3d3-b20db476995a W1206 21:54:05.268] IMAGE=chewong/kubekins-e2e:e4f5afd148972c05c198ac8621c8f1694c6d79d8 W1206 21:54:05.268] INSTANCE_PREFIX=bootstrap-e2e W1206 21:54:05.268] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine W1206 21:54:05.268] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub W1206 21:54:05.268] JOB_NAME=chewong-kubernetes-azure-disk-csi-driver-e2e W1206 21:54:05.269] JOB_SPEC={"type":"presubmit","job":"chewong-kubernetes-azure-disk-csi-driver-e2e","buildid":"1203069782258094080","prowjobid":"b5719461-1872-11ea-b3d3-b20db476995a","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"a380c291a683c0a71b660e372adb7cb505eb4195","base_link":"https://github.com/Azure/kubernetes/commit/a380c291a683c0a71b660e372adb7cb505eb4195","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"}]}} W1206 21:54:05.269] JOB_TYPE=presubmit W1206 21:54:05.269] KUBERNETES_CONFORMANCE_PROVIDER=azure W1206 21:54:05.269] KUBERNETES_PORT=tcp://10.0.0.1:443 W1206 21:54:05.269] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443 W1206 21:54:05.270] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1 W1206 21:54:05.270] KUBERNETES_PORT_443_TCP_PORT=443 W1206 21:54:05.270] KUBERNETES_PORT_443_TCP_PROTO=tcp W1206 21:54:05.270] KUBERNETES_SERVICE_HOST=10.0.0.1 W1206 21:54:05.270] KUBERNETES_SERVICE_PORT=443 W1206 21:54:05.270] KUBERNETES_SERVICE_PORT_HTTPS=443 W1206 21:54:05.270] KUBETEST_IN_DOCKER=true W1206 21:54:05.270] KUBETEST_MANUAL_DUMP=y W1206 21:54:05.270] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e W1206 21:54:05.271] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e W1206 21:54:05.271] KUBE_VERBOSE=0 W1206 21:54:05.271] NODE_NAME=b5719461-1872-11ea-b3d3-b20db476995a W1206 21:54:05.271] 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 W1206 21:54:05.271] PROW_JOB_ID=b5719461-1872-11ea-b3d3-b20db476995a W1206 21:54:05.271] PULL_BASE_REF=master W1206 21:54:05.271] PULL_BASE_SHA=a380c291a683c0a71b660e372adb7cb505eb4195 W1206 21:54:05.271] PULL_NUMBER=113 W1206 21:54:05.271] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822 W1206 21:54:05.272] PULL_REFS=master:a380c291a683c0a71b660e372adb7cb505eb4195,113:879465972041635fd7bc8269da49153b67e63822 W1206 21:54:05.272] PWD=/workspace W1206 21:54:05.272] REGISTRY=k8sprowinternal.azurecr.io W1206 21:54:05.272] REPO_NAME=kubernetes W1206 21:54:05.272] REPO_OWNER=Azure W1206 21:54:05.272] SHLVL=2 W1206 21:54:05.272] SOURCE_DATE_EPOCH=1575565354 W1206 21:54:05.272] TERM=xterm W1206 21:54:05.272] TIDE_PORT=tcp://10.0.180.53:80 W1206 21:54:05.273] TIDE_PORT_80_TCP=tcp://10.0.180.53:80 W1206 21:54:05.273] TIDE_PORT_80_TCP_ADDR=10.0.180.53 W1206 21:54:05.273] TIDE_PORT_80_TCP_PORT=80 W1206 21:54:05.273] TIDE_PORT_80_TCP_PROTO=tcp W1206 21:54:05.273] TIDE_SERVICE_HOST=10.0.180.53 W1206 21:54:05.273] TIDE_SERVICE_PORT=80 W1206 21:54:05.273] WORKSPACE=/workspace W1206 21:54:05.273] _=./test-infra/jenkins/bootstrap.py W1206 21:54:05.274] 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') W1206 21:54:05.280] 2019/12/06 21:54:05 main.go:332: Limiting testing to 7h0m0s W1206 21:54:05.286] 2019/12/06 21:54:05 azure.go:242: Reading credentials file /etc/azure-cred/credentials W1206 21:54:05.287] 2019/12/06 21:54:05 process.go:153: Running: make -C /go/src/k8s.io/kubernetes all I1206 21:54:05.387] make: Entering directory '/go/src/k8s.io/kubernetes' I1206 21:54:05.387] make[1]: Entering directory '/go/src/k8s.io/kubernetes' I1206 21:54:12.928] +++ [1206 21:54:12] Building go targets for linux/amd64: I1206 21:54:12.928] ./vendor/k8s.io/code-generator/cmd/deepcopy-gen I1206 21:54:19.638] +++ [1206 21:54:19] Building go targets for linux/amd64: I1206 21:54:19.639] ./vendor/k8s.io/code-generator/cmd/defaulter-gen I1206 21:54:25.007] +++ [1206 21:54:25] Building go targets for linux/amd64: I1206 21:54:25.007] ./vendor/k8s.io/code-generator/cmd/conversion-gen I1206 21:54:34.291] +++ [1206 21:54:34] Building go targets for linux/amd64: I1206 21:54:34.291] ./vendor/k8s.io/kube-openapi/cmd/openapi-gen I1206 21:54:46.731] +++ [1206 21:54:46] Building go targets for linux/amd64: I1206 21:54:46.732] ./vendor/github.com/go-bindata/go-bindata/go-bindata I1206 21:54:48.338] make[1]: Leaving directory '/go/src/k8s.io/kubernetes' I1206 21:54:48.620] +++ [1206 21:54:48] Building go targets for linux/amd64: I1206 21:54:48.621] cmd/kube-proxy I1206 21:54:48.621] cmd/kube-apiserver I1206 21:54:48.621] cmd/kube-controller-manager I1206 21:54:48.621] cmd/kubelet I1206 21:54:48.621] cmd/kubeadm I1206 21:54:48.621] cmd/kube-scheduler I1206 21:54:48.622] vendor/k8s.io/apiextensions-apiserver I1206 21:54:48.622] cluster/gce/gci/mounter I1206 21:54:48.622] cmd/kubectl I1206 21:54:48.622] cmd/gendocs I1206 21:54:48.622] cmd/genkubedocs I1206 21:54:48.622] cmd/genman I1206 21:54:48.622] cmd/genyaml I1206 21:54:48.622] cmd/genswaggertypedocs I1206 21:54:48.622] cmd/linkcheck I1206 21:54:48.623] vendor/github.com/onsi/ginkgo/ginkgo I1206 21:54:48.623] test/e2e/e2e.test I1206 21:54:48.623] cluster/images/conformance/go-runner I1206 21:54:48.623] cmd/kubemark I1206 21:54:48.623] vendor/github.com/onsi/ginkgo/ginkgo I1206 21:54:48.623] test/e2e_node/e2e_node.test I1206 21:59:01.155] make: Leaving directory '/go/src/k8s.io/kubernetes' W1206 21:59:01.256] 2019/12/06 21:59:01 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes all' finished in 4m55.868755633s W1206 21:59:01.256] 2019/12/06 21:59:01 util.go:265: Flushing memory. W1206 21:59:21.190] 2019/12/06 21:59:21 azure.go:989: Deleting resource group: kubetest-ebfd38a6-1872-11ea-97f0-02426fd7a88c. W1206 21:59:21.485] 2019/12/06 21:59:21 azure.go:720: CWD /go/src/k8s.io/kubernetes W1206 21:59:21.485] 2019/12/06 21:59:21 azure.go:739: Attempting Docker login with azure cred. W1206 21:59:22.873] 2019/12/06 21:59:22 azure.go:748: Docker login success. W1206 21:59:22.873] 2019/12/06 21:59:22 azure.go:759: Building cloud controller manager and cloud node manager. W1206 21:59:22.873] 2019/12/06 21:59:22 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 W1206 21:59:22.874] 2019/12/06 21:59:22 process.go:153: Running: make -C /go/src/k8s.io/cloud-provider-azure image push W1206 21:59:22.876] make: *** /go/src/k8s.io/cloud-provider-azure: No such file or directory. Stop. W1206 21:59:22.876] 2019/12/06 21:59:22 process.go:155: Step 'make -C /go/src/k8s.io/cloud-provider-azure image push' finished in 2.689221ms W1206 21:59:22.877] 2019/12/06 21:59:22 azure.go:753: Docker logout. W1206 21:59:22.935] 2019/12/06 21:59:22 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 W1206 21:59:22.936] 2019/12/06 21:59:22 azure.go:989: Deleting resource group: kubetest-ebfd38a6-1872-11ea-97f0-02426fd7a88c. W1206 21:59:22.955] 2019/12/06 21:59:22 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1206 21:59:22.955] 2019/12/06 21:59:22 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1206 21:59:24.135] 2019/12/06 21:59:24 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.18001053s W1206 21:59:24.136] 2019/12/06 21:59:24 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 W1206 21:59:24.137] Traceback (most recent call last): W1206 21:59:24.137] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in W1206 21:59:24.138] main(parse_args()) W1206 21:59:24.138] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1206 21:59:24.138] mode.start(runner_args) W1206 21:59:24.138] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1206 21:59:24.138] check_env(env, self.command, *args) W1206 21:59:24.138] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1206 21:59:24.138] subprocess.check_call(cmd, env=env) W1206 21:59:24.139] File "/usr/lib/python2.7/subprocess.py", line 190, in check_call W1206 21:59:24.139] raise CalledProcessError(retcode, cmd) W1206 21:59:24.139] 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 E1206 21:59:24.145] Command failed I1206 21:59:24.145] process 717 exited with code 1 after 5.3m E1206 21:59:24.146] FAIL: chewong-kubernetes-azure-disk-csi-driver-e2e I1206 21:59:24.146] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1206 21:59:24.915] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1206 21:59:24.947] process 13698 exited with code 0 after 0.0m I1206 21:59:24.948] Call: gcloud config get-value account I1206 21:59:25.179] process 13711 exited with code 0 after 0.0m I1206 21:59:25.179] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1206 21:59:25.179] Upload result and artifacts... I1206 21:59:25.179] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080 I1206 21:59:25.180] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080/artifacts W1206 21:59:26.150] CommandException: One or more URLs matched no objects. E1206 21:59:26.219] Command failed I1206 21:59:26.219] process 13724 exited with code 1 after 0.0m W1206 21:59:26.219] Remote dir gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080/artifacts not exist yet I1206 21:59:26.219] 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/1203069782258094080/artifacts I1206 21:59:27.577] process 13869 exited with code 0 after 0.0m I1206 21:59:27.578] Call: git rev-parse HEAD I1206 21:59:27.580] process 14396 exited with code 0 after 0.0m I1206 21:59:27.580] Call: git rev-parse HEAD I1206 21:59:27.585] process 14397 exited with code 0 after 0.0m I1206 21:59:27.585] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1206 21:59:28.342] process 14398 exited with code 0 after 0.0m I1206 21:59:28.342] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json#1575589806887949' I1206 21:59:29.394] process 14543 exited with code 0 after 0.0m I1206 21:59:29.394] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1575589806887949 cp /tmp/gsutil_hNlhkJ gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1206 21:59:30.694] process 14690 exited with code 0 after 0.0m I1206 21:59:30.695] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1206 21:59:31.470] process 14871 exited with code 0 after 0.0m I1206 21:59:31.470] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json#1575589808832684' I1206 21:59:32.504] process 15016 exited with code 0 after 0.0m I1206 21:59:32.504] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1575589808832684 cp /tmp/gsutil_3kXknp gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/jobResultsCache.json I1206 21:59:33.803] process 15163 exited with code 0 after 0.0m I1206 21:59:33.804] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_MPSSI8 gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/1203069782258094080/finished.json I1206 21:59:34.979] process 15344 exited with code 0 after 0.0m I1206 21:59:34.979] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_T2enFw gs://kubernetes-upstream/pr-logs/pull/85958/chewong-kubernetes-azure-disk-csi-driver-e2e/latest-build.txt I1206 21:59:36.085] process 15525 exited with code 0 after 0.0m I1206 21:59:36.086] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_mFWXmi gs://kubernetes-upstream/pr-logs/directory/chewong-kubernetes-azure-disk-csi-driver-e2e/latest-build.txt I1206 21:59:37.237] process 15706 exited with code 0 after 0.0m