W1002 16:17:29.011] ************************************************************************** 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 16:17:29.011] 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:1d1e9fbcf84a7c03221a42da6d93d0a806ad4319 --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 16:17:29.011] Bootstrap pull-azuredisk-csi-driver-e2e... I1002 16:17:29.015] Builder: 126ed09b-e530-11e9-918b-16773c0c7aed I1002 16:17:29.016] Image: chewong/kubekins-e2e:v20191002-b557491-azuredisk-csi-driver-job I1002 16:17:29.016] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472 I1002 16:17:29.016] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1002 16:17:29.541] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1002 16:17:29.571] process 215 exited with code 0 after 0.0m I1002 16:17:29.572] Call: gcloud config get-value account I1002 16:17:29.804] process 227 exited with code 0 after 0.0m I1002 16:17:29.804] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1002 16:17:29.804] Root: /go/src I1002 16:17:29.805] cd to /go/src I1002 16:17:29.805] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes I1002 16:17:29.805] Call: git init k8s.io/kubernetes I1002 16:17:29.808] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/ I1002 16:17:29.809] process 239 exited with code 0 after 0.0m I1002 16:17:29.809] Call: git config --local user.name 'K8S Bootstrap' I1002 16:17:29.811] process 240 exited with code 0 after 0.0m I1002 16:17:29.811] Call: git config --local user.email k8s_bootstrap@localhost I1002 16:17:29.814] process 241 exited with code 0 after 0.0m I1002 16:17:29.814] Call: git fetch --quiet --tags https://github.com/kubernetes/kubernetes master I1002 16:18:18.223] process 242 exited with code 0 after 0.8m I1002 16:18:18.223] Call: git checkout -B test FETCH_HEAD W1002 16:18:20.290] Switched to a new branch 'test' I1002 16:18:20.297] process 254 exited with code 0 after 0.0m I1002 16:18:20.297] Call: git show -s --format=format:%ct HEAD I1002 16:18:20.304] process 255 exited with code 0 after 0.0m I1002 16:18:20.305] Checkout: /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:1d1e9fbcf84a7c03221a42da6d93d0a806ad4319 to /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver I1002 16:18:20.305] Call: git init github.com/kubernetes-sigs/azuredisk-csi-driver I1002 16:18:20.309] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/.git/ I1002 16:18:20.309] process 256 exited with code 0 after 0.0m I1002 16:18:20.309] Call: git config --local user.name 'K8S Bootstrap' I1002 16:18:20.312] process 257 exited with code 0 after 0.0m I1002 16:18:20.312] Call: git config --local user.email k8s_bootstrap@localhost I1002 16:18:20.314] process 258 exited with code 0 after 0.0m I1002 16:18:20.315] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azuredisk-csi-driver master +refs/pull/166/head:refs/pr/166 I1002 16:18:25.226] process 259 exited with code 0 after 0.1m I1002 16:18:25.226] Call: git checkout -B test 6b4d4209385f8a3b04a39f1970017a27f2667a9a W1002 16:18:25.652] Switched to a new branch 'test' I1002 16:18:25.655] process 271 exited with code 0 after 0.0m I1002 16:18:25.655] Call: git show -s --format=format:%ct HEAD I1002 16:18:25.658] process 272 exited with code 0 after 0.0m I1002 16:18:25.659] Call: git merge --no-ff -m 'Merge +refs/pull/166/head:refs/pr/166' 1d1e9fbcf84a7c03221a42da6d93d0a806ad4319 I1002 16:18:26.027] Removing test/integration/azure.json I1002 16:18:26.027] Removing test/e2e/run-test.sh I1002 16:18:26.027] Removing .travis.yml I1002 16:18:26.027] Merge made by the 'recursive' strategy. I1002 16:18:26.034] .travis.yml | 36 - I1002 16:18:26.034] Gopkg.lock | 18 +- I1002 16:18:26.034] Makefile | 103 +- I1002 16:18:26.034] hack/verify-all.sh | 2 +- I1002 16:18:26.035] hack/verify-dep.sh | 1 + I1002 16:18:26.035] hack/verify-gofmt.sh | 2 +- I1002 16:18:26.035] hack/verify-golint.sh | 7 +- I1002 16:18:26.035] pkg/azuredisk/controllerserver.go | 2 +- I1002 16:18:26.035] test/e2e/dynamic_provisioning.go | 10 - I1002 16:18:26.035] test/e2e/pre_provisioning.go | 16 +- I1002 16:18:26.035] test/e2e/run-test.sh | 37 - I1002 16:18:26.036] test/e2e/suite_test.go | 87 + I1002 16:18:26.036] test/integration/azure.json | 33 - I1002 16:18:26.036] test/integration/integration_test.go | 100 + I1002 16:18:26.036] test/integration/run-test.sh | 157 +- I1002 16:18:26.036] test/integration/run-tests-all-clouds.sh | 32 +- I1002 16:18:26.036] test/sanity/run-test.sh | 47 +- I1002 16:18:26.037] test/sanity/run-tests-all-clouds.sh | 35 +- I1002 16:18:26.037] test/sanity/sanity_test.go | 76 +- I1002 16:18:26.037] test/utils/azure/azure_helpers.go | 246 +++ I1002 16:18:26.037] test/utils/credentials/credentials.go | 188 ++ I1002 16:18:26.037] test/utils/credentials/credentials_test.go | 181 ++ I1002 16:18:26.037] test/utils/testutil/testutil.go | 8 + I1002 16:18:26.038] .../resources/mgmt/2018-05-01/resources/client.go | 51 + I1002 16:18:26.038] .../2018-05-01/resources/deploymentoperations.go | 474 +++++ I1002 16:18:26.038] .../mgmt/2018-05-01/resources/deployments.go | 1553 +++++++++++++++ I1002 16:18:26.038] .../resources/mgmt/2018-05-01/resources/groups.go | 676 +++++++ I1002 16:18:26.038] .../resources/mgmt/2018-05-01/resources/models.go | 2044 ++++++++++++++++++++ I1002 16:18:26.038] .../mgmt/2018-05-01/resources/operations.go | 147 ++ I1002 16:18:26.039] .../mgmt/2018-05-01/resources/providers.go | 392 ++++ I1002 16:18:26.039] .../mgmt/2018-05-01/resources/resources.go | 1352 +++++++++++++ I1002 16:18:26.039] .../resources/mgmt/2018-05-01/resources/tags.go | 454 +++++ I1002 16:18:26.039] .../resources/mgmt/2018-05-01/resources/version.go | 30 + I1002 16:18:26.039] vendor/github.com/pelletier/go-toml/LICENSE | 21 + I1002 16:18:26.039] vendor/github.com/pelletier/go-toml/doc.go | 23 + I1002 16:18:26.039] vendor/github.com/pelletier/go-toml/fuzz.go | 31 + I1002 16:18:26.040] vendor/github.com/pelletier/go-toml/keysparsing.go | 113 ++ I1002 16:18:26.040] vendor/github.com/pelletier/go-toml/lexer.go | 752 +++++++ I1002 16:18:26.040] vendor/github.com/pelletier/go-toml/marshal.go | 803 ++++++++ I1002 16:18:26.040] vendor/github.com/pelletier/go-toml/parser.go | 442 +++++ I1002 16:18:26.040] vendor/github.com/pelletier/go-toml/position.go | 29 + I1002 16:18:26.041] vendor/github.com/pelletier/go-toml/token.go | 144 ++ I1002 16:18:26.041] vendor/github.com/pelletier/go-toml/toml.go | 393 ++++ I1002 16:18:26.041] .../pelletier/go-toml/tomltree_create.go | 142 ++ I1002 16:18:26.041] .../github.com/pelletier/go-toml/tomltree_write.go | 434 +++++ I1002 16:18:26.041] 45 files changed, 11558 insertions(+), 366 deletions(-) I1002 16:18:26.041] delete mode 100644 .travis.yml I1002 16:18:26.042] delete mode 100755 test/e2e/run-test.sh I1002 16:18:26.042] delete mode 100644 test/integration/azure.json I1002 16:18:26.042] create mode 100644 test/integration/integration_test.go I1002 16:18:26.042] create mode 100644 test/utils/azure/azure_helpers.go I1002 16:18:26.042] create mode 100644 test/utils/credentials/credentials.go I1002 16:18:26.042] create mode 100644 test/utils/credentials/credentials_test.go I1002 16:18:26.043] create mode 100644 test/utils/testutil/testutil.go I1002 16:18:26.043] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/client.go I1002 16:18:26.043] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deploymentoperations.go I1002 16:18:26.043] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deployments.go I1002 16:18:26.043] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/groups.go I1002 16:18:26.044] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/models.go I1002 16:18:26.044] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/operations.go I1002 16:18:26.044] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/providers.go I1002 16:18:26.044] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/resources.go I1002 16:18:26.044] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/tags.go I1002 16:18:26.044] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/version.go I1002 16:18:26.045] create mode 100644 vendor/github.com/pelletier/go-toml/LICENSE I1002 16:18:26.045] create mode 100644 vendor/github.com/pelletier/go-toml/doc.go I1002 16:18:26.045] create mode 100644 vendor/github.com/pelletier/go-toml/fuzz.go I1002 16:18:26.045] create mode 100644 vendor/github.com/pelletier/go-toml/keysparsing.go I1002 16:18:26.045] create mode 100644 vendor/github.com/pelletier/go-toml/lexer.go I1002 16:18:26.046] create mode 100644 vendor/github.com/pelletier/go-toml/marshal.go I1002 16:18:26.046] create mode 100644 vendor/github.com/pelletier/go-toml/parser.go I1002 16:18:26.046] create mode 100644 vendor/github.com/pelletier/go-toml/position.go I1002 16:18:26.046] create mode 100644 vendor/github.com/pelletier/go-toml/token.go I1002 16:18:26.046] create mode 100644 vendor/github.com/pelletier/go-toml/toml.go I1002 16:18:26.047] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_create.go I1002 16:18:26.047] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_write.go I1002 16:18:26.047] process 273 exited with code 0 after 0.0m I1002 16:18:26.047] Configure environment... I1002 16:18:26.048] Call: git show -s --format=format:%ct HEAD I1002 16:18:26.055] process 275 exited with code 0 after 0.0m I1002 16:18:26.056] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1002 16:18:26.653] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1002 16:18:26.759] process 276 exited with code 0 after 0.0m I1002 16:18:26.759] Call: gcloud config get-value account I1002 16:18:26.993] process 288 exited with code 0 after 0.0m I1002 16:18:26.993] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1002 16:18:26.994] 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 16:18:27.367] process 300 exited with code 0 after 0.0m I1002 16:18:27.367] Start 1179430139646185472 at v1.17.0-alpha.0.2001+bd89dc462c4fce... I1002 16:18:27.369] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_W5jDzw gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472/started.json I1002 16:18:28.802] process 333 exited with code 0 after 0.0m I1002 16:18:28.803] 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/1179430139646185472' cp /tmp/gsutil_gAGMbr gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/1179430139646185472.txt I1002 16:18:30.168] process 511 exited with code 0 after 0.0m I1002 16:18:30.169] 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 16:18:30.207] starts with local mode W1002 16:18:30.207] Environment: W1002 16:18:30.207] ARTIFACTS=/workspace/_artifacts W1002 16:18:30.207] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa W1002 16:18:30.208] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub W1002 16:18:30.208] AZURE_CREDENTIALS=/etc/azure-cred/credentials W1002 16:18:30.208] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub W1002 16:18:30.208] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer W1002 16:18:30.208] BAZEL_REMOTE_CACHE_ENABLED=false W1002 16:18:30.208] BAZEL_VERSION=0.23.2 W1002 16:18:30.208] BOOTSTRAP_MIGRATION=yes W1002 16:18:30.208] BUILD_ID=1179430139646185472 W1002 16:18:30.208] BUILD_NUMBER=1179430139646185472 W1002 16:18:30.209] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true W1002 16:18:30.209] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud W1002 16:18:30.209] CLOUDSDK_CORE_DISABLE_PROMPTS=1 W1002 16:18:30.209] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false W1002 16:18:30.209] CLOUD_CONFIG=random W1002 16:18:30.209] DECK_PORT=tcp://10.0.160.83:80 W1002 16:18:30.209] DECK_PORT_80_TCP=tcp://10.0.160.83:80 W1002 16:18:30.209] DECK_PORT_80_TCP_ADDR=10.0.160.83 W1002 16:18:30.209] DECK_PORT_80_TCP_PORT=80 W1002 16:18:30.210] DECK_PORT_80_TCP_PROTO=tcp W1002 16:18:30.210] DECK_SERVICE_HOST=10.0.160.83 W1002 16:18:30.210] DECK_SERVICE_PORT=80 W1002 16:18:30.210] DOCKER_IN_DOCKER_ENABLED=true W1002 16:18:30.210] DOCKER_IN_DOCKER_IPV6_ENABLED=false W1002 16:18:30.210] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472/artifacts W1002 16:18:30.210] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json W1002 16:18:30.210] GOPATH=/go W1002 16:18:30.211] GO_TARBALL=go1.12.1.linux-amd64.tar.gz W1002 16:18:30.211] HOME=/workspace W1002 16:18:30.211] HOOK_PORT=tcp://10.0.144.133:8888 W1002 16:18:30.211] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888 W1002 16:18:30.211] HOOK_PORT_8888_TCP_ADDR=10.0.144.133 W1002 16:18:30.211] HOOK_PORT_8888_TCP_PORT=8888 W1002 16:18:30.211] HOOK_PORT_8888_TCP_PROTO=tcp W1002 16:18:30.211] HOOK_SERVICE_HOST=10.0.144.133 W1002 16:18:30.211] HOOK_SERVICE_PORT=8888 W1002 16:18:30.211] HOSTNAME=126ed09b-e530-11e9-918b-16773c0c7aed W1002 16:18:30.212] IMAGE=chewong/kubekins-e2e:v20191002-b557491-azuredisk-csi-driver-job W1002 16:18:30.212] INSTANCE_PREFIX=bootstrap-e2e W1002 16:18:30.212] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine W1002 16:18:30.212] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub W1002 16:18:30.212] JOB_NAME=pull-azuredisk-csi-driver-e2e W1002 16:18:30.213] JOB_SPEC={"type":"presubmit","job":"pull-azuredisk-csi-driver-e2e","buildid":"1179430139646185472","prowjobid":"126ed09b-e530-11e9-918b-16773c0c7aed","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"bd89dc462c4fced99eac966d244c9b031aa56df4","base_link":"https://github.com/Azure/kubernetes/commit/bd89dc462c4fced99eac966d244c9b031aa56df4","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 16:18:30.213] JOB_TYPE=presubmit W1002 16:18:30.213] KUBERNETES_PORT=tcp://10.0.0.1:443 W1002 16:18:30.213] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443 W1002 16:18:30.213] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1 W1002 16:18:30.213] KUBERNETES_PORT_443_TCP_PORT=443 W1002 16:18:30.213] KUBERNETES_PORT_443_TCP_PROTO=tcp W1002 16:18:30.213] KUBERNETES_SERVICE_HOST=10.0.0.1 W1002 16:18:30.213] KUBERNETES_SERVICE_PORT=443 W1002 16:18:30.214] KUBERNETES_SERVICE_PORT_HTTPS=443 W1002 16:18:30.214] KUBETEST_IN_DOCKER=true W1002 16:18:30.214] KUBETEST_MANUAL_DUMP=y W1002 16:18:30.214] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e W1002 16:18:30.214] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e W1002 16:18:30.214] KUBE_VERBOSE=0 W1002 16:18:30.214] NODE_NAME=126ed09b-e530-11e9-918b-16773c0c7aed W1002 16:18:30.215] 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 16:18:30.215] PROW_JOB_ID=126ed09b-e530-11e9-918b-16773c0c7aed W1002 16:18:30.215] PULL_BASE_REF=master W1002 16:18:30.215] PULL_BASE_SHA=bd89dc462c4fced99eac966d244c9b031aa56df4 W1002 16:18:30.215] PULL_NUMBER=113 W1002 16:18:30.215] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822 W1002 16:18:30.215] PULL_REFS=master:bd89dc462c4fced99eac966d244c9b031aa56df4,113:879465972041635fd7bc8269da49153b67e63822 W1002 16:18:30.215] PWD=/workspace W1002 16:18:30.216] REGISTRY=k8sprowinternal.azurecr.io W1002 16:18:30.216] REPO_NAME=kubernetes W1002 16:18:30.216] REPO_OWNER=Azure W1002 16:18:30.216] SHLVL=2 W1002 16:18:30.216] SOURCE_DATE_EPOCH=1570032188 W1002 16:18:30.216] TERM=xterm W1002 16:18:30.216] TIDE_PORT=tcp://10.0.180.53:80 W1002 16:18:30.216] TIDE_PORT_80_TCP=tcp://10.0.180.53:80 W1002 16:18:30.217] TIDE_PORT_80_TCP_ADDR=10.0.180.53 W1002 16:18:30.217] TIDE_PORT_80_TCP_PORT=80 W1002 16:18:30.217] TIDE_PORT_80_TCP_PROTO=tcp W1002 16:18:30.217] TIDE_SERVICE_HOST=10.0.180.53 W1002 16:18:30.217] TIDE_SERVICE_PORT=80 W1002 16:18:30.217] WORKSPACE=/workspace W1002 16:18:30.217] _=./test-infra/jenkins/bootstrap.py W1002 16:18:30.218] 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 16:18:30.222] 2019/10/02 16:18:30 azure.go:232: Reading credentials file /etc/azure-cred/credentials W1002 16:18:30.222] 2019/10/02 16:18:30 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release W1002 16:18:30.289] Extracting Bazel installation... I1002 16:18:30.390] make: Entering directory '/go/src/k8s.io/kubernetes' W1002 16:18:39.431] Starting local Bazel server and connecting to it... W1002 16:18:41.120] Loading: W1002 16:18:41.123] Loading: 0 packages loaded W1002 16:18:42.126] Loading: 0 packages loaded W1002 16:18:43.640] Loading: 0 packages loaded W1002 16:18:45.127] Loading: 0 packages loaded W1002 16:18:46.846] Loading: 0 packages loaded W1002 16:18:48.127] Loading: 0 packages loaded W1002 16:18:48.128] currently loading: build/release-tars W1002 16:18:50.027] Loading: 0 packages loaded W1002 16:18:50.027] currently loading: build/release-tars W1002 16:18:50.357] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured) W1002 16:18:51.765] Analyzing: target //build/release-tars:release-tars (197 packages loaded, 1233 targets configured) W1002 16:18:53.381] Analyzing: target //build/release-tars:release-tars (1076 packages loaded, 4210 targets configured) W1002 16:18:55.242] Analyzing: target //build/release-tars:release-tars (1877 packages loaded, 11717 targets configured) W1002 16:18:57.380] Analyzing: target //build/release-tars:release-tars (2681 packages loaded, 20045 targets configured) W1002 16:19:00.431] Analyzing: target //build/release-tars:release-tars (3027 packages loaded, 27102 targets configured) W1002 16:19:03.559] Analyzing: target //build/release-tars:release-tars (3064 packages loaded, 29388 targets configured) W1002 16:19:07.421] Analyzing: target //build/release-tars:release-tars (3072 packages loaded, 34127 targets configured) W1002 16:19:24.495] INFO: Analysed target //build/release-tars:release-tars (3072 packages loaded, 42242 targets configured). W1002 16:19:24.497] Building: checking cached actions W1002 16:19:24.498] INFO: Found 1 target... W1002 16:19:24.678] [0 / 20] [-----] BazelWorkspaceStatusAction stable-status.txt ... (4 actions, 0 running) W1002 16:19:32.319] [117 / 5,077] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 4s linux-sandbox ... (8 actions, 7 running) W1002 16:19:41.494] [139 / 5,077] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 13s linux-sandbox ... (8 actions, 7 running) W1002 16:19:50.891] [165 / 5,077] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 22s linux-sandbox ... (8 actions, 7 running) W1002 16:20:02.477] [252 / 5,077] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 34s linux-sandbox ... (8 actions, 7 running) W1002 16:20:20.183] [356 / 5,077] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 52s linux-sandbox ... (7 actions, 6 running) W1002 16:20:36.113] [495 / 5,077] GoCompile vendor/golang.org/x/net/websocket/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/golang.org/x/net/websocket.a; 2s linux-sandbox ... (8 actions, 7 running) W1002 16:20:53.905] [641 / 5,077] GoCompile staging/src/k8s.io/component-base/metrics/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/component-base/metrics.a; 5s linux-sandbox ... (8 actions, 7 running) W1002 16:21:13.946] [892 / 5,077] Compiling vendor/github.com/google/certificate-transparency-go/x509/linux_amd64_stripped/go_default_library%linux_amd64%cgo_codegen%/_cgo_export.c; 0s linux-sandbox ... (8 actions, 5 running) W1002 16:21:13.949] [892 / 5,077] Compiling vendor/github.com/google/certificate-transparency-go/x509/linux_amd64_stripped/go_default_library%linux_amd64%cgo_codegen%/_cgo_export.c; 0s linux-sandbox ... (8 actions, 7 running) W1002 16:21:38.031] [1,100 / 5,077] GoLink vendor/github.com/go-bindata/go-bindata/go-bindata/linux_amd64_stripped/go-bindata [for host]; 2s linux-sandbox ... (8 actions, 7 running) W1002 16:22:05.018] [1,386 / 5,077] GoCompile vendor/k8s.io/kube-openapi/pkg/generators/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/kube-openapi/pkg/generators.a [for host]; 0s linux-sandbox ... (7 actions running) W1002 16:22:35.650] [1,670 / 5,077] GoCompile vendor/github.com/vmware/govmomi/pbm/types/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vmware/govmomi/pbm/types.a; 0s linux-sandbox ... (8 actions, 7 running) W1002 16:23:11.519] [1,952 / 5,077] GoCompile vendor/github.com/spf13/afero/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/spf13/afero.a; 2s linux-sandbox ... (5 actions running) W1002 16:23:52.960] [2,214 / 5,077] GoCompile pkg/apis/networking/v1beta1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/networking/v1beta1.a; 1s linux-sandbox ... (8 actions, 7 running) W1002 16:24:40.114] [2,630 / 5,077] GoCompile vendor/github.com/GoogleCloudPlatform/k8s-cloud-provider/pkg/cloud/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/GoogleCloudPlatform/k8s-cloud-provider/pkg/cloud.a; 2s linux-sandbox ... (8 actions, 7 running) W1002 16:25:34.574] [3,118 / 5,077] GoCompile vendor/github.com/sirupsen/logrus/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/sirupsen/logrus.a; 0s linux-sandbox ... (8 actions, 7 running) W1002 16:26:36.911] [3,630 / 5,077] GoCompile pkg/volume/rbd/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/volume/rbd.a; 1s linux-sandbox ... (8 actions, 7 running) W1002 16:27:49.530] [4,109 / 5,077] GoCompile staging/src/k8s.io/kubectl/pkg/cmd/util/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/kubectl/pkg/cmd/util.a; 2s linux-sandbox ... (8 actions running) W1002 16:29:11.674] [4,506 / 5,077] GoCompile staging/src/k8s.io/kubectl/pkg/cmd/util/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/kubectl/pkg/cmd/util.a; 7s linux-sandbox ... (8 actions, 7 running) W1002 16:30:46.408] [4,872 / 5,077] GoLink cmd/kubectl/linux_amd64_pure_stripped/kubectl; 7s linux-sandbox ... (8 actions running) W1002 16:32:36.195] [5,040 / 5,102] GoLink cmd/kubelet/kubelet; 49s linux-sandbox ... (8 actions running) W1002 16:34:41.481] [5,206 / 5,253] GoCompile test/e2e/framework/ingress/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/test/e2e/framework/ingress.a; 56s linux-sandbox ... (8 actions, 6 running) W1002 16:35:05.013] Slow read: a 5518336-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/io_k8s_kubernetes/bazel-out/k8-fastbuild/bin/test/e2e/framework/ingress/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/test/e2e/framework/ingress.a took 32291 ms. W1002 16:36:44.456] Target //build/release-tars:release-tars up-to-date: W1002 16:36:44.458] bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz W1002 16:36:44.459] bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz W1002 16:36:44.459] bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz W1002 16:36:44.459] bazel-bin/build/release-tars/kubernetes-manifests.tar.gz W1002 16:36:44.460] bazel-bin/build/release-tars/kubernetes-test-portable.tar.gz W1002 16:36:44.460] bazel-bin/build/release-tars/kubernetes-test-linux-amd64.tar.gz W1002 16:36:44.460] bazel-bin/build/release-tars/kubernetes.tar.gz W1002 16:36:44.460] bazel-bin/build/release-tars/kubernetes-src.tar.gz W1002 16:36:44.512] INFO: Elapsed time: 1094.199s, Critical Path: 410.67s W1002 16:36:44.512] INFO: 5205 processes: 5205 linux-sandbox. W1002 16:36:44.516] INFO: Build completed successfully, 5296 total actions W1002 16:36:44.518] INFO: Build completed successfully, 5296 total actions W1002 16:36:44.527] 2019/10/02 16:36:44 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 18m14.304983115s W1002 16:36:44.527] 2019/10/02 16:36:44 util.go:277: Flushing memory. I1002 16:36:44.628] make: Leaving directory '/go/src/k8s.io/kubernetes' W1002 16:37:13.810] 2019/10/02 16:37:13 azure.go:901: Deleting resource group: kubetest-45b56d72-e530-11e9-bbe9-02424f59735f. W1002 16:37:14.531] 2019/10/02 16:37:14 azure.go:782: downloading /workspace/aks303541914/kubernetes.json from https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json. W1002 16:37:14.532] 2019/10/02 16:37:14 util.go:42: curl https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json W1002 16:37:14.726] 2019/10/02 16:37:14 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 16:37:14.727] 2019/10/02 16:37:14 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 16:37:15.882] 2019/10/02 16:37:15 azure.go:556: Extracting tar file /workspace/aks-engine.tar.gz into directory /go/src/k8s.io/kubernetes . W1002 16:37:15.883] 2019/10/02 16:37:15 process.go:153: Running: tar -xzf /workspace/aks-engine.tar.gz --strip 1 W1002 16:37:16.051] 2019/10/02 16:37:16 process.go:155: Step 'tar -xzf /workspace/aks-engine.tar.gz --strip 1' finished in 168.877006ms W1002 16:37:16.052] 2019/10/02 16:37:16 process.go:153: Running: /go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks303541914/kubernetes.json --output-directory /workspace/aks303541914 I1002 16:37:16.255] INFO[0000] Generating assets into /workspace/aks303541914... W1002 16:37:23.310] 2019/10/02 16:37:23 process.go:155: Step '/go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks303541914/kubernetes.json --output-directory /workspace/aks303541914' finished in 7.258875612s W1002 16:37:23.313] 2019/10/02 16:37:23 azure.go:627: Setting kubeconfig env variable: kubeconfig path: /workspace/aks303541914/kubeconfig/kubeconfig.eastus2.json. W1002 16:37:23.313] 2019/10/02 16:37:23 azure.go:629: Creating resource group: kubetest-45b56d72-e530-11e9-bbe9-02424f59735f. W1002 16:37:23.314] 2019/10/02 16:37:23 azure.go:631: Creating Azure resource group: kubetest-45b56d72-e530-11e9-bbe9-02424f59735f for cluster deployment. W1002 16:37:25.145] 2019/10/02 16:37:25 azure.go:636: Validating deployment ARM templates. W1002 16:37:26.915] 2019/10/02 16:37:26 azure.go:642: Deploying cluster kubetest-45b56d72-e530-11e9-bbe9-02424f59735f in resource group kubetest-45b56d72-e530-11e9-bbe9-02424f59735f. W1002 16:48:34.524] 2019/10/02 16:48:34 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W1002 16:48:35.184] 2019/10/02 16:48:35 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 660.075613ms W1002 16:48:35.185] 2019/10/02 16:48:35 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false get nodes -oyaml W1002 16:48:36.663] 2019/10/02 16:48:36 process.go:155: Step './cluster/kubectl.sh --match-server-version=false get nodes -oyaml' finished in 1.478777737s W1002 16:48:36.664] 2019/10/02 16:48:36 azure.go:945: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W1002 16:48:36.664] 2019/10/02 16:48:36 azure.go:945: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W1002 16:48:36.665] 2019/10/02 16:48:36 process.go:153: Running: kubectl get nodes --no-headers W1002 16:48:37.516] 2019/10/02 16:48:37 process.go:155: Step 'kubectl get nodes --no-headers' finished in 851.369852ms W1002 16:48:37.517] 2019/10/02 16:48:37 e2e.go:462: Cluster nodes: W1002 16:48:37.517] k8s-master-35487616-0 Ready master 72s v1.15.0 W1002 16:48:37.517] 2019/10/02 16:48:37 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W1002 16:48:37.937] 2019/10/02 16:48:37 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 420.149937ms W1002 16:48:37.937] 2019/10/02 16:48:37 process.go:153: Running: make e2e-test I1002 16:48:38.037] go test -v -timeout=30m ./test/e2e "-ginkgo.noColor" I1002 16:49:26.321] Oct 2 16:49:26.321: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used. I1002 16:49:26.321] === RUN TestE2E I1002 16:49:26.322] Running Suite: AzureDisk CSI Driver End-to-End Tests I1002 16:49:26.322] ==================================================== I1002 16:49:26.322] Random Seed: 1570034966 - Will randomize all specs I1002 16:49:26.322] Will run 9 of 9 specs I1002 16:49:26.322] I1002 16:49:28.707] 2019/10/02 16:49:28 Attempting docker login with Azure service principal I1002 16:49:30.498] 2019/10/02 16:49:30 docker login is successful I1002 16:49:30.499] 2019/10/02 16:49:30 Installing Azure Disk CSI Driver... I1002 16:49:30.500] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 16:49:30.502] # Use v2.11.0 helm to match tiller's version in clusters made by aks-engine I1002 16:49:30.503] curl https://raw.githubusercontent.com/helm/helm/master/scripts/get | DESIRED_VERSION=v2.11.0 bash I1002 16:49:30.602] % Total % Received % Xferd Average Speed Time Time Time Current I1002 16:49:30.603] Dload Upload Total Spent Left Speed I1002 16:49:30.864] 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7034 100 7034 0 0 26883 0 --:--:-- --:--:-- --:--:-- 26950 I1002 16:49:30.881] Downloading https://get.helm.sh/helm-v2.11.0-linux-amd64.tar.gz I1002 16:49:31.878] Preparing to install helm and tiller into /usr/local/bin I1002 16:49:32.427] helm installed into /usr/local/bin/helm I1002 16:49:32.453] tiller installed into /usr/local/bin/tiller I1002 16:49:32.455] Run 'helm init' to configure helm. I1002 16:49:32.471] # Make sure tiller is ready I1002 16:49:32.471] kubectl wait pod -l name=tiller --namespace kube-system --for condition=ready I1002 16:50:03.337] error: timed out waiting for the condition on pods/tiller-deploy-54c96cb5df-999hg I1002 16:50:03.340] Makefile:72: recipe for target 'install-helm' failed I1002 16:50:03.340] make[1]: *** [install-helm] Error 1 I1002 16:50:03.340] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 16:50:03.340] Failure [37.019 seconds] I1002 16:50:03.341] [BeforeSuite] BeforeSuite I1002 16:50:03.341] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:54 I1002 16:50:03.341] I1002 16:50:03.341] Unexpected error: I1002 16:50:03.341] <*exec.ExitError | 0xc0002b2020>: { I1002 16:50:03.341] ProcessState: { I1002 16:50:03.342] pid: 19048, I1002 16:50:03.342] status: 512, I1002 16:50:03.342] rusage: { I1002 16:50:03.342] Utime: {Sec: 0, Usec: 796274}, I1002 16:50:03.342] Stime: {Sec: 0, Usec: 391928}, I1002 16:50:03.343] Maxrss: 49752, I1002 16:50:03.343] Ixrss: 0, I1002 16:50:03.343] Idrss: 0, I1002 16:50:03.343] Isrss: 0, I1002 16:50:03.343] Minflt: 6848, I1002 16:50:03.343] Majflt: 87, I1002 16:50:03.344] Nswap: 0, I1002 16:50:03.344] Inblock: 23232, I1002 16:50:03.344] Oublock: 286584, I1002 16:50:03.344] Msgsnd: 0, I1002 16:50:03.344] Msgrcv: 0, I1002 16:50:03.345] Nsignals: 0, I1002 16:50:03.345] Nvcsw: 3140, I1002 16:50:03.345] Nivcsw: 206, I1002 16:50:03.345] }, I1002 16:50:03.345] }, I1002 16:50:03.345] Stderr: nil, I1002 16:50:03.346] } I1002 16:50:03.346] exit status 2 I1002 16:50:03.346] occurred I1002 16:50:03.346] I1002 16:50:03.346] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:93 I1002 16:50:03.346] ------------------------------ I1002 16:50:03.347] 2019/10/02 16:50:03 Uninstalling Azure Disk CSI Driver... I1002 16:50:03.347] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 16:50:03.349] helm delete --purge azuredisk-csi-driver I1002 16:50:03.965] Error: could not find a ready tiller pod I1002 16:50:03.967] Makefile:80: recipe for target 'e2e-teardown' failed I1002 16:50:03.967] make[1]: *** [e2e-teardown] Error 1 I1002 16:50:03.967] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azuredisk-csi-driver' I1002 16:50:03.968] Failure [0.627 seconds] I1002 16:50:03.968] [AfterSuite] AfterSuite I1002 16:50:03.968] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:104 I1002 16:50:03.968] I1002 16:50:03.968] Unexpected error: I1002 16:50:03.968] <*exec.ExitError | 0xc0002b22a0>: { I1002 16:50:03.968] ProcessState: { I1002 16:50:03.968] pid: 19095, I1002 16:50:03.969] status: 512, I1002 16:50:03.969] rusage: { I1002 16:50:03.969] Utime: {Sec: 0, Usec: 102147}, I1002 16:50:03.969] Stime: {Sec: 0, Usec: 24556}, I1002 16:50:03.969] Maxrss: 49752, I1002 16:50:03.969] Ixrss: 0, I1002 16:50:03.969] Idrss: 0, I1002 16:50:03.969] Isrss: 0, I1002 16:50:03.970] Minflt: 4047, I1002 16:50:03.970] Majflt: 0, I1002 16:50:03.970] Nswap: 0, I1002 16:50:03.970] Inblock: 0, I1002 16:50:03.970] Oublock: 0, I1002 16:50:03.970] Msgsnd: 0, I1002 16:50:03.970] Msgrcv: 0, I1002 16:50:03.970] Nsignals: 0, I1002 16:50:03.971] Nvcsw: 627, I1002 16:50:03.971] Nivcsw: 19, I1002 16:50:03.971] }, I1002 16:50:03.971] }, I1002 16:50:03.971] Stderr: nil, I1002 16:50:03.971] } I1002 16:50:03.971] exit status 2 I1002 16:50:03.972] occurred I1002 16:50:03.972] I1002 16:50:03.972] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e/suite_test.go:122 I1002 16:50:03.972] ------------------------------ I1002 16:50:03.972] I1002 16:50:03.972] Ran 9 of 0 Specs in 37.646 seconds I1002 16:50:03.973] FAIL! -- 0 Passed | 9 Failed | 0 Pending | 0 Skipped I1002 16:50:03.973] --- FAIL: TestE2E (37.65s) I1002 16:50:03.973] FAIL I1002 16:50:03.973] FAIL github.com/kubernetes-sigs/azuredisk-csi-driver/test/e2e 37.694s I1002 16:50:04.614] Makefile:59: recipe for target 'e2e-test' failed W1002 16:50:04.715] make: *** [e2e-test] Error 1 W1002 16:50:04.715] 2019/10/02 16:50:04 process.go:155: Step 'make e2e-test' finished in 1m26.678049064s W1002 16:50:04.715] 2019/10/02 16:50:04 azure.go:901: Deleting resource group: kubetest-45b56d72-e530-11e9-bbe9-02424f59735f. W1002 16:59:58.646] 2019/10/02 16:59:58 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1002 16:59:58.646] 2019/10/02 16:59:58 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1002 16:59:59.336] 2019/10/02 16:59:59 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 689.527835ms W1002 16:59:59.336] 2019/10/02 16:59:59 main.go:319: Something went wrong: encountered 1 errors: [error during make e2e-test: exit status 2] W1002 16:59:59.337] Traceback (most recent call last): W1002 16:59:59.338] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in W1002 16:59:59.338] main(parse_args()) W1002 16:59:59.338] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1002 16:59:59.338] mode.start(runner_args) W1002 16:59:59.338] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1002 16:59:59.339] check_env(env, self.command, *args) W1002 16:59:59.339] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1002 16:59:59.339] subprocess.check_call(cmd, env=env) W1002 16:59:59.339] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1002 16:59:59.339] raise CalledProcessError(retcode, cmd) W1002 16:59:59.340] 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 16:59:59.344] Command failed I1002 16:59:59.345] process 689 exited with code 1 after 41.5m E1002 16:59:59.345] FAIL: pull-azuredisk-csi-driver-e2e I1002 16:59:59.345] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1002 17:00:00.172] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1002 17:00:00.203] process 19147 exited with code 0 after 0.0m I1002 17:00:00.203] Call: gcloud config get-value account I1002 17:00:00.450] process 19159 exited with code 0 after 0.0m I1002 17:00:00.450] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1002 17:00:00.450] Upload result and artifacts... I1002 17:00:00.450] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472 I1002 17:00:00.450] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472/artifacts W1002 17:00:01.568] CommandException: One or more URLs matched no objects. E1002 17:00:01.646] Command failed I1002 17:00:01.647] process 19171 exited with code 1 after 0.0m W1002 17:00:01.647] Remote dir gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472/artifacts not exist yet I1002 17:00:01.647] 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/1179430139646185472/artifacts I1002 17:00:03.399] process 19313 exited with code 0 after 0.0m I1002 17:00:03.400] Call: git rev-parse HEAD I1002 17:00:03.403] process 19840 exited with code 0 after 0.0m I1002 17:00:03.403] Call: git rev-parse HEAD I1002 17:00:03.407] process 19841 exited with code 0 after 0.0m I1002 17:00:03.407] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/jobResultsCache.json W1002 17:00:04.334] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 17:00:04.334] process 19842 exited with code 1 after 0.0m I1002 17:00:04.335] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_ASs2qZ gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 17:00:05.678] process 19984 exited with code 0 after 0.0m I1002 17:00:05.679] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/jobResultsCache.json W1002 17:00:06.587] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 17:00:06.587] process 20162 exited with code 1 after 0.0m I1002 17:00:06.588] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_oqm3_G gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/jobResultsCache.json I1002 17:00:07.930] process 20304 exited with code 0 after 0.0m I1002 17:00:07.931] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_GxWNrG gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/1179430139646185472/finished.json I1002 17:00:09.197] process 20482 exited with code 0 after 0.0m I1002 17:00:09.198] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_3uGmAr gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-e2e/latest-build.txt I1002 17:00:10.564] process 20660 exited with code 0 after 0.0m I1002 17:00:10.565] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_lDczuQ gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-e2e/latest-build.txt I1002 17:00:11.802] process 20838 exited with code 0 after 0.0m