W1002 21:04:56.457] ************************************************************************** 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 ************************************************************************** I1002 21:04:56.458] Args: --job=pull-azuredisk-csi-driver-e2e --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azuredisk-csi-driver-e2e --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes --repo=github.com/kubernetes-sigs/azuredisk-csi-driver=master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:4576debb503a77bc3bc76cfe181116a50e294e61 --upload=gs://kubernetes-upstream/pr-logs --scenario=kubernetes_e2e -- --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=bazel --provider=skeleton --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-location=eastus2 '--acsengine-public-key=$AZURE_SSH_PUBLIC_KEY_FILE' --acsengine-template-url=https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz --test-azure-disk-csi-driver=True I1002 21:04:56.458] Bootstrap pull-azuredisk-csi-driver-e2e... I1002 21:04:56.461] Builder: 3b7aab7b-e558-11e9-918b-16773c0c7aed I1002 21:04:56.462] Image: chewong/kubekins-e2e:v20191002-b557491-azuredisk-csi-driver-job I1002 21:04:56.462] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776 I1002 21:04:56.462] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1002 21:04:56.947] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1002 21:04:56.975] process 213 exited with code 0 after 0.0m I1002 21:04:56.975] Call: gcloud config get-value account I1002 21:04:57.199] process 225 exited with code 0 after 0.0m I1002 21:04:57.200] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1002 21:04:57.200] Root: /go/src I1002 21:04:57.200] cd to /go/src I1002 21:04:57.200] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes I1002 21:04:57.200] Call: git init k8s.io/kubernetes I1002 21:04:57.204] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/ I1002 21:04:57.204] process 237 exited with code 0 after 0.0m I1002 21:04:57.204] Call: git config --local user.name 'K8S Bootstrap' I1002 21:04:57.207] process 238 exited with code 0 after 0.0m I1002 21:04:57.207] Call: git config --local user.email k8s_bootstrap@localhost I1002 21:04:57.209] process 239 exited with code 0 after 0.0m I1002 21:04:57.210] Call: git fetch --quiet --tags https://github.com/kubernetes/kubernetes master I1002 21:05:48.137] process 240 exited with code 0 after 0.8m I1002 21:05:48.138] Call: git checkout -B test FETCH_HEAD W1002 21:05:50.164] Switched to a new branch 'test' I1002 21:05:50.173] process 252 exited with code 0 after 0.0m I1002 21:05:50.173] Call: git show -s --format=format:%ct HEAD I1002 21:05:50.177] process 253 exited with code 0 after 0.0m I1002 21:05:50.177] Checkout: /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:4576debb503a77bc3bc76cfe181116a50e294e61 to /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver I1002 21:05:50.177] Call: git init github.com/kubernetes-sigs/azuredisk-csi-driver I1002 21:05:50.181] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/.git/ I1002 21:05:50.181] process 254 exited with code 0 after 0.0m I1002 21:05:50.181] Call: git config --local user.name 'K8S Bootstrap' I1002 21:05:50.184] process 255 exited with code 0 after 0.0m I1002 21:05:50.184] Call: git config --local user.email k8s_bootstrap@localhost I1002 21:05:50.186] process 256 exited with code 0 after 0.0m I1002 21:05:50.187] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azuredisk-csi-driver master +refs/pull/166/head:refs/pr/166 I1002 21:05:54.864] process 257 exited with code 0 after 0.1m I1002 21:05:54.865] Call: git checkout -B test 6b4d4209385f8a3b04a39f1970017a27f2667a9a W1002 21:05:55.290] Switched to a new branch 'test' I1002 21:05:55.293] process 269 exited with code 0 after 0.0m I1002 21:05:55.293] Call: git show -s --format=format:%ct HEAD I1002 21:05:55.296] process 270 exited with code 0 after 0.0m I1002 21:05:55.296] Call: git merge --no-ff -m 'Merge +refs/pull/166/head:refs/pr/166' 4576debb503a77bc3bc76cfe181116a50e294e61 I1002 21:05:55.675] Removing test/integration/azure.json I1002 21:05:55.675] Removing test/e2e/run-test.sh I1002 21:05:55.676] Removing .travis.yml I1002 21:05:55.676] Merge made by the 'recursive' strategy. I1002 21:05:55.682] .travis.yml | 36 - I1002 21:05:55.682] Gopkg.lock | 18 +- I1002 21:05:55.682] Makefile | 103 +- I1002 21:05:55.682] hack/verify-all.sh | 2 +- I1002 21:05:55.683] hack/verify-dep.sh | 1 + I1002 21:05:55.683] hack/verify-gofmt.sh | 2 +- I1002 21:05:55.683] hack/verify-golint.sh | 7 +- I1002 21:05:55.683] pkg/azuredisk/controllerserver.go | 2 +- I1002 21:05:55.683] test/e2e/dynamic_provisioning.go | 10 - I1002 21:05:55.683] test/e2e/pre_provisioning.go | 16 +- I1002 21:05:55.683] test/e2e/run-test.sh | 37 - I1002 21:05:55.683] test/e2e/suite_test.go | 87 + I1002 21:05:55.684] test/integration/azure.json | 33 - I1002 21:05:55.684] test/integration/integration_test.go | 100 + I1002 21:05:55.684] test/integration/run-test.sh | 157 +- I1002 21:05:55.684] test/integration/run-tests-all-clouds.sh | 32 +- I1002 21:05:55.684] test/sanity/run-test.sh | 47 +- I1002 21:05:55.684] test/sanity/run-tests-all-clouds.sh | 35 +- I1002 21:05:55.684] test/sanity/sanity_test.go | 76 +- I1002 21:05:55.684] test/utils/azure/azure_helpers.go | 246 +++ I1002 21:05:55.684] test/utils/credentials/credentials.go | 189 ++ I1002 21:05:55.685] test/utils/credentials/credentials_test.go | 181 ++ I1002 21:05:55.685] test/utils/testutil/testutil.go | 8 + I1002 21:05:55.685] .../resources/mgmt/2018-05-01/resources/client.go | 51 + I1002 21:05:55.685] .../2018-05-01/resources/deploymentoperations.go | 474 +++++ I1002 21:05:55.685] .../mgmt/2018-05-01/resources/deployments.go | 1553 +++++++++++++++ I1002 21:05:55.685] .../resources/mgmt/2018-05-01/resources/groups.go | 676 +++++++ I1002 21:05:55.685] .../resources/mgmt/2018-05-01/resources/models.go | 2044 ++++++++++++++++++++ I1002 21:05:55.685] .../mgmt/2018-05-01/resources/operations.go | 147 ++ I1002 21:05:55.686] .../mgmt/2018-05-01/resources/providers.go | 392 ++++ I1002 21:05:55.686] .../mgmt/2018-05-01/resources/resources.go | 1352 +++++++++++++ I1002 21:05:55.686] .../resources/mgmt/2018-05-01/resources/tags.go | 454 +++++ I1002 21:05:55.686] .../resources/mgmt/2018-05-01/resources/version.go | 30 + I1002 21:05:55.686] vendor/github.com/pelletier/go-toml/LICENSE | 21 + I1002 21:05:55.686] vendor/github.com/pelletier/go-toml/doc.go | 23 + I1002 21:05:55.686] vendor/github.com/pelletier/go-toml/fuzz.go | 31 + I1002 21:05:55.686] vendor/github.com/pelletier/go-toml/keysparsing.go | 113 ++ I1002 21:05:55.687] vendor/github.com/pelletier/go-toml/lexer.go | 752 +++++++ I1002 21:05:55.687] vendor/github.com/pelletier/go-toml/marshal.go | 803 ++++++++ I1002 21:05:55.687] vendor/github.com/pelletier/go-toml/parser.go | 442 +++++ I1002 21:05:55.687] vendor/github.com/pelletier/go-toml/position.go | 29 + I1002 21:05:55.687] vendor/github.com/pelletier/go-toml/token.go | 144 ++ I1002 21:05:55.687] vendor/github.com/pelletier/go-toml/toml.go | 393 ++++ I1002 21:05:55.687] .../pelletier/go-toml/tomltree_create.go | 142 ++ I1002 21:05:55.687] .../github.com/pelletier/go-toml/tomltree_write.go | 434 +++++ I1002 21:05:55.688] 45 files changed, 11559 insertions(+), 366 deletions(-) I1002 21:05:55.688] delete mode 100644 .travis.yml I1002 21:05:55.688] delete mode 100755 test/e2e/run-test.sh I1002 21:05:55.688] delete mode 100644 test/integration/azure.json I1002 21:05:55.688] create mode 100644 test/integration/integration_test.go I1002 21:05:55.688] create mode 100644 test/utils/azure/azure_helpers.go I1002 21:05:55.688] create mode 100644 test/utils/credentials/credentials.go I1002 21:05:55.688] create mode 100644 test/utils/credentials/credentials_test.go I1002 21:05:55.688] create mode 100644 test/utils/testutil/testutil.go I1002 21:05:55.689] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/client.go I1002 21:05:55.689] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deploymentoperations.go I1002 21:05:55.689] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deployments.go I1002 21:05:55.689] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/groups.go I1002 21:05:55.689] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/models.go I1002 21:05:55.689] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/operations.go I1002 21:05:55.690] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/providers.go I1002 21:05:55.690] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/resources.go I1002 21:05:55.690] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/tags.go I1002 21:05:55.690] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/version.go I1002 21:05:55.690] create mode 100644 vendor/github.com/pelletier/go-toml/LICENSE I1002 21:05:55.690] create mode 100644 vendor/github.com/pelletier/go-toml/doc.go I1002 21:05:55.690] create mode 100644 vendor/github.com/pelletier/go-toml/fuzz.go I1002 21:05:55.690] create mode 100644 vendor/github.com/pelletier/go-toml/keysparsing.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/lexer.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/marshal.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/parser.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/position.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/token.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/toml.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_create.go I1002 21:05:55.691] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_write.go I1002 21:05:55.692] process 271 exited with code 0 after 0.0m I1002 21:05:55.692] Configure environment... I1002 21:05:55.692] Call: git show -s --format=format:%ct HEAD I1002 21:05:55.695] process 273 exited with code 0 after 0.0m I1002 21:05:55.695] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1002 21:05:56.288] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1002 21:05:56.415] process 274 exited with code 0 after 0.0m I1002 21:05:56.415] Call: gcloud config get-value account I1002 21:05:56.645] process 286 exited with code 0 after 0.0m I1002 21:05:56.645] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1002 21:05:56.646] 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 ' I1002 21:05:56.964] process 298 exited with code 0 after 0.0m I1002 21:05:56.965] Start 1179502491394379776 at v1.17.0-alpha.0.2008+0960c74c3788b1... I1002 21:05:56.967] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_hD2UmS gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776/started.json I1002 21:05:58.461] process 331 exited with code 0 after 0.0m I1002 21:05:58.461] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776' cp /tmp/gsutil_hVXVRd gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/1179502491394379776.txt I1002 21:05:59.724] process 509 exited with code 0 after 0.0m I1002 21:05:59.725] Call: /workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=bazel --provider=skeleton --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-location=eastus2 --acsengine-public-key=/etc/azure-ssh/azure-ssh-pub --acsengine-template-url=https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz --test-azure-disk-csi-driver=True W1002 21:05:59.762] starts with local mode W1002 21:05:59.763] Environment: W1002 21:05:59.763] ARTIFACTS=/workspace/_artifacts W1002 21:05:59.763] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa W1002 21:05:59.763] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub W1002 21:05:59.763] AZURE_CREDENTIALS=/etc/azure-cred/credentials W1002 21:05:59.763] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub W1002 21:05:59.763] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer W1002 21:05:59.764] BAZEL_REMOTE_CACHE_ENABLED=false W1002 21:05:59.764] BAZEL_VERSION=0.23.2 W1002 21:05:59.764] BOOTSTRAP_MIGRATION=yes W1002 21:05:59.764] BUILD_ID=1179502491394379776 W1002 21:05:59.764] BUILD_NUMBER=1179502491394379776 W1002 21:05:59.765] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true W1002 21:05:59.765] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud W1002 21:05:59.765] CLOUDSDK_CORE_DISABLE_PROMPTS=1 W1002 21:05:59.765] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false W1002 21:05:59.765] CLOUD_CONFIG=random W1002 21:05:59.765] DECK_PORT=tcp://10.0.160.83:80 W1002 21:05:59.765] DECK_PORT_80_TCP=tcp://10.0.160.83:80 W1002 21:05:59.766] DECK_PORT_80_TCP_ADDR=10.0.160.83 W1002 21:05:59.766] DECK_PORT_80_TCP_PORT=80 W1002 21:05:59.766] DECK_PORT_80_TCP_PROTO=tcp W1002 21:05:59.766] DECK_SERVICE_HOST=10.0.160.83 W1002 21:05:59.766] DECK_SERVICE_PORT=80 W1002 21:05:59.766] DOCKER_IN_DOCKER_ENABLED=true W1002 21:05:59.766] DOCKER_IN_DOCKER_IPV6_ENABLED=false W1002 21:05:59.766] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776/artifacts W1002 21:05:59.767] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json W1002 21:05:59.767] GOPATH=/go W1002 21:05:59.767] GO_TARBALL=go1.12.1.linux-amd64.tar.gz W1002 21:05:59.767] HOME=/workspace W1002 21:05:59.767] HOOK_PORT=tcp://10.0.144.133:8888 W1002 21:05:59.767] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888 W1002 21:05:59.767] HOOK_PORT_8888_TCP_ADDR=10.0.144.133 W1002 21:05:59.767] HOOK_PORT_8888_TCP_PORT=8888 W1002 21:05:59.767] HOOK_PORT_8888_TCP_PROTO=tcp W1002 21:05:59.767] HOOK_SERVICE_HOST=10.0.144.133 W1002 21:05:59.768] HOOK_SERVICE_PORT=8888 W1002 21:05:59.768] HOSTNAME=3b7aab7b-e558-11e9-918b-16773c0c7aed W1002 21:05:59.768] IMAGE=chewong/kubekins-e2e:v20191002-b557491-azuredisk-csi-driver-job W1002 21:05:59.768] INSTANCE_PREFIX=bootstrap-e2e W1002 21:05:59.768] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine W1002 21:05:59.768] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub W1002 21:05:59.768] JOB_NAME=pull-azuredisk-csi-driver-e2e W1002 21:05:59.769] JOB_SPEC={"type":"presubmit","job":"pull-azuredisk-csi-driver-e2e","buildid":"1179502491394379776","prowjobid":"3b7aab7b-e558-11e9-918b-16773c0c7aed","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"0960c74c3788b1724bd7e7b9933bc49c7e5b5afa","base_link":"https://github.com/Azure/kubernetes/commit/0960c74c3788b1724bd7e7b9933bc49c7e5b5afa","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"}]}} W1002 21:05:59.769] JOB_TYPE=presubmit W1002 21:05:59.769] KUBERNETES_PORT=tcp://10.0.0.1:443 W1002 21:05:59.769] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443 W1002 21:05:59.769] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1 W1002 21:05:59.769] KUBERNETES_PORT_443_TCP_PORT=443 W1002 21:05:59.769] KUBERNETES_PORT_443_TCP_PROTO=tcp W1002 21:05:59.770] KUBERNETES_SERVICE_HOST=10.0.0.1 W1002 21:05:59.770] KUBERNETES_SERVICE_PORT=443 W1002 21:05:59.770] KUBERNETES_SERVICE_PORT_HTTPS=443 W1002 21:05:59.770] KUBETEST_IN_DOCKER=true W1002 21:05:59.770] KUBETEST_MANUAL_DUMP=y W1002 21:05:59.770] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e W1002 21:05:59.770] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e W1002 21:05:59.771] KUBE_VERBOSE=0 W1002 21:05:59.771] NODE_NAME=3b7aab7b-e558-11e9-918b-16773c0c7aed W1002 21:05:59.771] 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 W1002 21:05:59.771] PROW_JOB_ID=3b7aab7b-e558-11e9-918b-16773c0c7aed W1002 21:05:59.771] PULL_BASE_REF=master W1002 21:05:59.771] PULL_BASE_SHA=0960c74c3788b1724bd7e7b9933bc49c7e5b5afa W1002 21:05:59.772] PULL_NUMBER=113 W1002 21:05:59.772] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822 W1002 21:05:59.772] PULL_REFS=master:0960c74c3788b1724bd7e7b9933bc49c7e5b5afa,113:879465972041635fd7bc8269da49153b67e63822 W1002 21:05:59.772] PWD=/workspace W1002 21:05:59.772] REGISTRY=k8sprowinternal.azurecr.io W1002 21:05:59.772] REPO_NAME=kubernetes W1002 21:05:59.772] REPO_OWNER=Azure W1002 21:05:59.773] SHLVL=2 W1002 21:05:59.773] SOURCE_DATE_EPOCH=1570049553 W1002 21:05:59.773] TERM=xterm W1002 21:05:59.773] TIDE_PORT=tcp://10.0.180.53:80 W1002 21:05:59.773] TIDE_PORT_80_TCP=tcp://10.0.180.53:80 W1002 21:05:59.773] TIDE_PORT_80_TCP_ADDR=10.0.180.53 W1002 21:05:59.773] TIDE_PORT_80_TCP_PORT=80 W1002 21:05:59.774] TIDE_PORT_80_TCP_PROTO=tcp W1002 21:05:59.774] TIDE_SERVICE_HOST=10.0.180.53 W1002 21:05:59.774] TIDE_SERVICE_PORT=80 W1002 21:05:59.774] WORKSPACE=/workspace W1002 21:05:59.774] _=./test-infra/jenkins/bootstrap.py W1002 21:05:59.775] Run: ('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=bazel', '--up', '--down', '--test', '--deployment=acsengine', '--provider=skeleton', '--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-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz', '--test-azure-disk-csi-driver=True') W1002 21:05:59.779] 2019/10/02 21:05:59 azure.go:232: Reading credentials file /etc/azure-cred/credentials W1002 21:05:59.779] 2019/10/02 21:05:59 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release W1002 21:05:59.827] Extracting Bazel installation... I1002 21:05:59.927] make: Entering directory '/go/src/k8s.io/kubernetes' W1002 21:06:08.712] Starting local Bazel server and connecting to it... W1002 21:06:10.369] Loading: W1002 21:06:10.372] Loading: 0 packages loaded W1002 21:06:11.375] Loading: 0 packages loaded W1002 21:06:12.912] Loading: 0 packages loaded W1002 21:06:14.376] Loading: 0 packages loaded W1002 21:06:16.318] Loading: 0 packages loaded W1002 21:06:17.376] Loading: 0 packages loaded W1002 21:06:17.376] currently loading: build/release-tars W1002 21:06:19.748] Loading: 0 packages loaded W1002 21:06:19.749] currently loading: build/release-tars W1002 21:06:20.123] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured) W1002 21:06:21.604] Analyzing: target //build/release-tars:release-tars (158 packages loaded, 1021 targets configured) W1002 21:06:23.313] Analyzing: target //build/release-tars:release-tars (853 packages loaded, 3536 targets configured) W1002 21:06:25.271] Analyzing: target //build/release-tars:release-tars (1691 packages loaded, 9589 targets configured) W1002 21:06:27.526] Analyzing: target //build/release-tars:release-tars (2512 packages loaded, 18990 targets configured) W1002 21:06:30.277] Analyzing: target //build/release-tars:release-tars (3027 packages loaded, 27102 targets configured) W1002 21:06:33.579] Analyzing: target //build/release-tars:release-tars (3064 packages loaded, 28976 targets configured) W1002 21:06:37.079] Analyzing: target //build/release-tars:release-tars (3071 packages loaded, 34217 targets configured) W1002 21:06:45.130] Analyzing: target //build/release-tars:release-tars (3072 packages loaded, 38608 targets configured) W1002 21:06:55.834] INFO: Analysed target //build/release-tars:release-tars (3072 packages loaded, 42242 targets configured). W1002 21:06:55.836] Building: checking cached actions W1002 21:06:55.837] INFO: Found 1 target... W1002 21:06:56.470] [0 / 24] [-----] Writing file build/release-tars/kubernetes-client-linux-amd64.args W1002 21:07:03.524] [111 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 2s linux-sandbox ... (8 actions, 7 running) W1002 21:07:11.523] [201 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 10s linux-sandbox ... (7 actions running) W1002 21:07:21.337] [423 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 20s linux-sandbox ... (8 actions, 7 running) W1002 21:07:32.796] [558 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 31s linux-sandbox ... (8 actions, 7 running) W1002 21:07:45.409] [620 / 5,296] GoCompile vendor/gonum.org/v1/gonum/graph/formats/dot/internal/parser/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/gonum.org/v1/gonum/graph/formats/dot/internal/parser.a; 8s linux-sandbox ... (8 actions, 7 running) W1002 21:08:02.270] [771 / 5,296] GoCompile vendor/github.com/globalsign/mgo/bson/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/globalsign/mgo/bson.a; 3s linux-sandbox ... (8 actions running) W1002 21:08:19.297] [918 / 5,296] GoCompile vendor/github.com/modern-go/reflect2/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/modern-go/reflect2.a~partial.a; 4s linux-sandbox ... (8 actions running) W1002 21:08:39.899] [1,065 / 5,296] GoCompile staging/src/k8s.io/client-go/util/workqueue/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/util/workqueue.a; 1s linux-sandbox ... (8 actions, 7 running) W1002 21:09:02.530] [1,190 / 5,296] GoCompile vendor/github.com/prometheus/client_golang/prometheus/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/prometheus/client_golang/prometheus.a; 5s linux-sandbox ... (8 actions, 7 running) W1002 21:09:28.470] [1,370 / 5,296] GoCompile vendor/google.golang.org/api/compute/v0.beta/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/google.golang.org/api/compute/v0.beta.a; 12s linux-sandbox ... (8 actions running) W1002 21:09:58.203] [1,715 / 5,296] GoCompile vendor/github.com/prometheus/common/model/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/prometheus/common/model.a; 1s linux-sandbox ... (8 actions running) W1002 21:10:33.248] [2,263 / 5,296] GoCompile staging/src/k8s.io/client-go/util/workqueue/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/util/workqueue.a; 3s linux-sandbox ... (8 actions, 7 running) W1002 21:11:14.949] [2,566 / 5,296] GoCompile vendor/github.com/Azure/azure-sdk-for-go/services/network/mgmt/2019-06-01/network/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/Azure/azure-sdk-for-go/services/network/mgmt/2019-06-01/network.a; 19s linux-sandbox ... (8 actions, 7 running) W1002 21:12:03.902] [2,778 / 5,296] ; 24s linux-sandbox ... (8 actions, 7 running) W1002 21:12:56.948] [2,877 / 5,296] PackageTar build/release-tars/kubernetes-src.tar.gz; 18s linux-sandbox ... (8 actions, 7 running) W1002 21:13:58.231] [3,288 / 5,296] GoCompile pkg/apis/policy/validation/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/policy/validation.a; 5s linux-sandbox ... (8 actions, 7 running) W1002 21:15:11.149] [3,764 / 5,296] GoCompile pkg/controller/certificates/signer/config/v1alpha1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/controller/certificates/signer/config/v1alpha1.a; 2s linux-sandbox ... (8 actions, 7 running) W1002 21:16:33.596] [4,209 / 5,296] GoCompile pkg/registry/core/service/portallocator/controller/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/registry/core/service/portallocator/controller.a; 2s linux-sandbox ... (8 actions, 7 running) W1002 21:18:09.096] [4,628 / 5,296] GoLink cmd/kube-proxy/linux_amd64_pure_stripped/kube-proxy; 5s linux-sandbox ... (8 actions, 7 running) W1002 21:19:58.511] [4,915 / 5,296] MakeDeb build/debs/kube-apiserver__amd64.deb; 6s linux-sandbox ... (8 actions, 6 running) W1002 21:19:58.513] [4,915 / 5,296] MakeDeb build/debs/kube-apiserver__amd64.deb; 6s linux-sandbox ... (8 actions, 7 running) W1002 21:22:03.153] [5,264 / 5,296] GoLink cmd/kubelet/kubelet; 54s linux-sandbox ... (8 actions, 7 running) W1002 21:24:38.013] Target //build/release-tars:release-tars up-to-date: W1002 21:24:38.014] bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz W1002 21:24:38.015] bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz W1002 21:24:38.015] bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz W1002 21:24:38.015] bazel-bin/build/release-tars/kubernetes-manifests.tar.gz W1002 21:24:38.015] bazel-bin/build/release-tars/kubernetes-test-portable.tar.gz W1002 21:24:38.016] bazel-bin/build/release-tars/kubernetes-test-linux-amd64.tar.gz W1002 21:24:38.016] bazel-bin/build/release-tars/kubernetes.tar.gz W1002 21:24:38.016] bazel-bin/build/release-tars/kubernetes-src.tar.gz W1002 21:24:38.061] INFO: Elapsed time: 1118.194s, Critical Path: 303.22s W1002 21:24:38.061] INFO: 5205 processes: 5205 linux-sandbox. W1002 21:24:38.067] INFO: Build completed successfully, 5296 total actions W1002 21:24:38.069] INFO: Build completed successfully, 5296 total actions W1002 21:24:38.079] 2019/10/02 21:24:38 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 18m38.299732782s W1002 21:24:38.079] 2019/10/02 21:24:38 util.go:277: Flushing memory. I1002 21:24:38.179] make: Leaving directory '/go/src/k8s.io/kubernetes' W1002 21:25:02.302] 2019/10/02 21:25:02 azure.go:901: Deleting resource group: kubetest-6f3eed99-e558-11e9-adfd-024257fb7081. W1002 21:25:02.756] 2019/10/02 21:25:02 azure.go:782: downloading /workspace/aks625892228/kubernetes.json from https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json. W1002 21:25:02.756] 2019/10/02 21:25:02 util.go:42: curl https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json W1002 21:25:03.066] 2019/10/02 21:25:03 azure.go:529: downloading /workspace/aks-engine.tar.gz from https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz. W1002 21:25:03.066] 2019/10/02 21:25:03 util.go:42: curl https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz W1002 21:25:04.256] 2019/10/02 21:25:04 azure.go:556: Extracting tar file /workspace/aks-engine.tar.gz into directory /go/src/k8s.io/kubernetes . W1002 21:25:04.256] 2019/10/02 21:25:04 process.go:153: Running: tar -xzf /workspace/aks-engine.tar.gz --strip 1 W1002 21:25:04.415] 2019/10/02 21:25:04 process.go:155: Step 'tar -xzf /workspace/aks-engine.tar.gz --strip 1' finished in 159.49329ms W1002 21:25:04.416] 2019/10/02 21:25:04 process.go:153: Running: /go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks625892228/kubernetes.json --output-directory /workspace/aks625892228 I1002 21:25:04.621] INFO[0000] Generating assets into /workspace/aks625892228... W1002 21:25:23.201] 2019/10/02 21:25:23 process.go:155: Step '/go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks625892228/kubernetes.json --output-directory /workspace/aks625892228' finished in 18.784978647s W1002 21:25:23.204] 2019/10/02 21:25:23 azure.go:627: Setting kubeconfig env variable: kubeconfig path: /workspace/aks625892228/kubeconfig/kubeconfig.eastus2.json. W1002 21:25:23.204] 2019/10/02 21:25:23 azure.go:629: Creating resource group: kubetest-6f3eed99-e558-11e9-adfd-024257fb7081. W1002 21:25:23.204] 2019/10/02 21:25:23 azure.go:631: Creating Azure resource group: kubetest-6f3eed99-e558-11e9-adfd-024257fb7081 for cluster deployment. W1002 21:25:25.130] 2019/10/02 21:25:25 azure.go:636: Validating deployment ARM templates. W1002 21:25:26.817] 2019/10/02 21:25:26 azure.go:642: Deploying cluster kubetest-6f3eed99-e558-11e9-adfd-024257fb7081 in resource group kubetest-6f3eed99-e558-11e9-adfd-024257fb7081. W1002 21:36:33.762] 2019/10/02 21:36:33 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W1002 21:36:34.394] 2019/10/02 21:36:34 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 632.385002ms W1002 21:36:34.395] 2019/10/02 21:36:34 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false get nodes -oyaml W1002 21:36:35.851] 2019/10/02 21:36:35 process.go:155: Step './cluster/kubectl.sh --match-server-version=false get nodes -oyaml' finished in 1.456299989s W1002 21:36:35.852] 2019/10/02 21:36:35 azure.go:945: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W1002 21:36:35.853] 2019/10/02 21:36:35 azure.go:945: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W1002 21:36:35.854] 2019/10/02 21:36:35 process.go:153: Running: kubectl get nodes --no-headers W1002 21:36:36.725] 2019/10/02 21:36:36 process.go:155: Step 'kubectl get nodes --no-headers' finished in 870.968952ms W1002 21:36:36.725] 2019/10/02 21:36:36 e2e.go:462: Cluster nodes: W1002 21:36:36.725] k8s-master-35491747-0 Ready master 67s v1.15.0 W1002 21:36:36.725] 2019/10/02 21:36:36 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W1002 21:36:37.165] 2019/10/02 21:36:37 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 440.087512ms W1002 21:36:37.165] 2019/10/02 21:36:37 process.go:153: Running: make e2e-test I1002 21:36:37.265] go test -v -timeout=30m ./test/e2e "-ginkgo.noColor" I1002 21:37:26.165] Oct 2 21:37:26.165: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used. I1002 21:37:26.165] === RUN TestE2E I1002 21:37:26.165] Running Suite: AzureDisk CSI Driver End-to-End Tests I1002 21:37:26.165] ==================================================== I1002 21:37:26.166] Random Seed: 1570052246 - Will randomize all specs I1002 21:37:26.166] Will run 9 of 9 specs I1002 21:37:26.166] I1002 21:37:28.132] 2019/10/02 21:37:28 Attempting docker login with Azure service principal I1002 21:37:29.515] 2019/10/02 21:37:29 docker login is successful I1002 21:37:29.515] 2019/10/02 21:37:29 Installing Azure Disk CSI Driver... I1002 21:37:29.516] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 21:37:29.518] # Use v2.11.0 helm to match tiller's version in clusters made by aks-engine I1002 21:37:29.519] curl https://raw.githubusercontent.com/helm/helm/master/scripts/get | DESIRED_VERSION=v2.11.0 bash I1002 21:37:30.130] % Total % Received % Xferd Average Speed Time Time Time Current I1002 21:37:30.130] Dload Upload Total Spent Left Speed I1002 21:37:30.222] 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7034 100 7034 0 0 76203 0 --:--:-- --:--:-- --:--:-- 76456 I1002 21:37:30.239] Downloading https://get.helm.sh/helm-v2.11.0-linux-amd64.tar.gz I1002 21:37:31.644] Preparing to install helm and tiller into /usr/local/bin I1002 21:37:31.672] helm installed into /usr/local/bin/helm I1002 21:37:31.699] tiller installed into /usr/local/bin/tiller I1002 21:37:31.701] Run 'helm init' to configure helm. I1002 21:37:31.716] # Make sure tiller is ready I1002 21:37:31.717] kubectl wait pod -l name=tiller --namespace kube-system --for condition=ready --timeout 5m I1002 21:42:33.897] error: timed out waiting for the condition on pods/tiller-deploy-54c96cb5df-qwj8l I1002 21:42:33.899] Makefile:72: recipe for target 'install-helm' failed I1002 21:42:33.899] make[1]: *** [install-helm] Error 1 I1002 21:42:33.900] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 21:42:33.900] Failure [307.735 seconds] I1002 21:42:33.900] [BeforeSuite] BeforeSuite I1002 21:42:33.900] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:54 I1002 21:42:33.901] I1002 21:42:33.901] Unexpected error: I1002 21:42:33.901] <*exec.ExitError | 0xc0004cc020>: { I1002 21:42:33.901] ProcessState: { I1002 21:42:33.901] pid: 18987, I1002 21:42:33.902] status: 512, I1002 21:42:33.902] rusage: { I1002 21:42:33.902] Utime: {Sec: 0, Usec: 815643}, I1002 21:42:33.902] Stime: {Sec: 0, Usec: 336097}, I1002 21:42:33.902] Maxrss: 47780, I1002 21:42:33.903] Ixrss: 0, I1002 21:42:33.903] Idrss: 0, I1002 21:42:33.903] Isrss: 0, I1002 21:42:33.903] Minflt: 6838, I1002 21:42:33.903] Majflt: 93, I1002 21:42:33.904] Nswap: 0, I1002 21:42:33.904] Inblock: 23864, I1002 21:42:33.904] Oublock: 286584, I1002 21:42:33.904] Msgsnd: 0, I1002 21:42:33.904] Msgrcv: 0, I1002 21:42:33.905] Nsignals: 0, I1002 21:42:33.905] Nvcsw: 3761, I1002 21:42:33.905] Nivcsw: 129, I1002 21:42:33.905] }, I1002 21:42:33.905] }, I1002 21:42:33.906] Stderr: nil, I1002 21:42:33.906] } I1002 21:42:33.906] exit status 2 I1002 21:42:33.906] occurred I1002 21:42:33.906] I1002 21:42:33.907] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:93 I1002 21:42:33.907] ------------------------------ I1002 21:42:33.907] 2019/10/02 21:42:33 Uninstalling Azure Disk CSI Driver... I1002 21:42:33.907] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 21:42:33.907] helm delete --purge azuredisk-csi-driver I1002 21:42:34.331] Error: could not find a ready tiller pod I1002 21:42:34.333] Makefile:80: recipe for target 'e2e-teardown' failed I1002 21:42:34.333] make[1]: *** [e2e-teardown] Error 1 I1002 21:42:34.333] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 21:42:34.333] Failure [0.433 seconds] I1002 21:42:34.334] [AfterSuite] AfterSuite I1002 21:42:34.334] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:104 I1002 21:42:34.334] I1002 21:42:34.334] Unexpected error: I1002 21:42:34.334] <*exec.ExitError | 0xc0004cc2a0>: { I1002 21:42:34.334] ProcessState: { I1002 21:42:34.335] pid: 19038, I1002 21:42:34.335] status: 512, I1002 21:42:34.335] rusage: { I1002 21:42:34.335] Utime: {Sec: 0, Usec: 103596}, I1002 21:42:34.335] Stime: {Sec: 0, Usec: 29265}, I1002 21:42:34.335] Maxrss: 47780, I1002 21:42:34.336] Ixrss: 0, I1002 21:42:34.336] Idrss: 0, I1002 21:42:34.336] Isrss: 0, I1002 21:42:34.336] Minflt: 4070, I1002 21:42:34.336] Majflt: 0, I1002 21:42:34.337] Nswap: 0, I1002 21:42:34.337] Inblock: 0, I1002 21:42:34.337] Oublock: 0, I1002 21:42:34.337] Msgsnd: 0, I1002 21:42:34.337] Msgrcv: 0, I1002 21:42:34.338] Nsignals: 0, I1002 21:42:34.338] Nvcsw: 608, I1002 21:42:34.338] Nivcsw: 10, I1002 21:42:34.338] }, I1002 21:42:34.338] }, I1002 21:42:34.338] Stderr: nil, I1002 21:42:34.339] } I1002 21:42:34.339] exit status 2 I1002 21:42:34.339] occurred I1002 21:42:34.339] I1002 21:42:34.339] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:122 I1002 21:42:34.340] ------------------------------ I1002 21:42:34.340] I1002 21:42:34.340] Ran 9 of 0 Specs in 308.169 seconds I1002 21:42:34.340] FAIL! -- 0 Passed | 9 Failed | 0 Pending | 0 Skipped I1002 21:42:34.340] --- FAIL: TestE2E (308.17s) I1002 21:42:34.341] FAIL I1002 21:42:34.341] FAIL github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e 308.216s I1002 21:42:34.601] Makefile:59: recipe for target 'e2e-test' failed W1002 21:42:34.701] make: *** [e2e-test] Error 1 W1002 21:42:34.701] 2019/10/02 21:42:34 process.go:155: Step 'make e2e-test' finished in 5m57.435873914s W1002 21:42:34.702] 2019/10/02 21:42:34 azure.go:901: Deleting resource group: kubetest-6f3eed99-e558-11e9-adfd-024257fb7081. W1002 21:53:44.192] 2019/10/02 21:53:44 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1002 21:53:44.193] 2019/10/02 21:53:44 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1002 21:53:44.894] 2019/10/02 21:53:44 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 701.4779ms W1002 21:53:44.894] 2019/10/02 21:53:44 main.go:319: Something went wrong: encountered 1 errors: [error during make e2e-test: exit status 2] W1002 21:53:44.896] Traceback (most recent call last): W1002 21:53:44.896] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in W1002 21:53:44.897] main(parse_args()) W1002 21:53:44.897] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1002 21:53:44.897] mode.start(runner_args) W1002 21:53:44.897] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1002 21:53:44.897] check_env(env, self.command, *args) W1002 21:53:44.897] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1002 21:53:44.898] subprocess.check_call(cmd, env=env) W1002 21:53:44.898] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1002 21:53:44.898] raise CalledProcessError(retcode, cmd) W1002 21:53:44.898] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=bazel', '--up', '--down', '--test', '--deployment=acsengine', '--provider=skeleton', '--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-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz', '--test-azure-disk-csi-driver=True')' returned non-zero exit status 1 E1002 21:53:44.904] Command failed I1002 21:53:44.904] process 687 exited with code 1 after 47.8m E1002 21:53:44.904] FAIL: pull-azuredisk-csi-driver-e2e I1002 21:53:44.904] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1002 21:53:45.727] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1002 21:53:45.757] process 19088 exited with code 0 after 0.0m I1002 21:53:45.757] Call: gcloud config get-value account I1002 21:53:45.999] process 19100 exited with code 0 after 0.0m I1002 21:53:45.999] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1002 21:53:45.999] Upload result and artifacts... I1002 21:53:45.999] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776 I1002 21:53:45.999] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776/artifacts W1002 21:53:47.218] CommandException: One or more URLs matched no objects. E1002 21:53:47.296] Command failed I1002 21:53:47.296] process 19112 exited with code 1 after 0.0m W1002 21:53:47.296] Remote dir gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776/artifacts not exist yet I1002 21:53:47.297] Call: gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776/artifacts I1002 21:53:48.982] process 19254 exited with code 0 after 0.0m I1002 21:53:48.983] Call: git rev-parse HEAD I1002 21:53:48.986] process 19781 exited with code 0 after 0.0m I1002 21:53:48.986] Call: git rev-parse HEAD I1002 21:53:48.991] process 19782 exited with code 0 after 0.0m I1002 21:53:48.991] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 21:53:49.926] process 19783 exited with code 0 after 0.0m I1002 21:53:49.927] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/jobResultsCache.json#1570035605454231' I1002 21:53:51.176] process 19925 exited with code 0 after 0.0m I1002 21:53:51.177] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1570035605454231 cp /tmp/gsutil_OLO5Xz gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 21:53:52.561] process 20069 exited with code 0 after 0.0m I1002 21:53:52.562] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 21:53:53.510] process 20247 exited with code 0 after 0.0m I1002 21:53:53.510] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/jobResultsCache.json#1570035607674228' I1002 21:53:54.795] process 20389 exited with code 0 after 0.0m I1002 21:53:54.796] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1570035607674228 cp /tmp/gsutil_0xFvu3 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 21:53:56.261] process 20533 exited with code 0 after 0.0m I1002 21:53:56.261] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_G9vjf4 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179502491394379776/finished.json I1002 21:53:57.646] process 20711 exited with code 0 after 0.0m I1002 21:53:57.647] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_LX369p gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/latest-build.txt I1002 21:53:59.019] process 20889 exited with code 0 after 0.0m I1002 21:53:59.020] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_BSIfzW gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/latest-build.txt I1002 21:54:00.312] process 21067 exited with code 0 after 0.0m