W0927 04:45:08.497] ************************************************************************** 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 ************************************************************************** I0927 04:45:08.497] Args: --job=pull-azurefile-csi-driver-e2e --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azurefile-csi-driver-e2e --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes --repo=github.com/kubernetes-sigs/azurefile-csi-driver=master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:54f98c722b41e3e5fd2f6581defa0c4fafa962f2 --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-file-csi-driver=True I0927 04:45:08.498] Bootstrap pull-azurefile-csi-driver-e2e... I0927 04:45:08.501] Builder: 7a872a7d-e0e1-11e9-918b-16773c0c7aed I0927 04:45:08.501] Image: chewong/kubekins-e2e:v20190926-7484d81-azurefile-csi-driver-config I0927 04:45:08.501] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032 I0927 04:45:08.502] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0927 04:45:09.147] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0927 04:45:09.187] process 212 exited with code 0 after 0.0m I0927 04:45:09.187] Call: gcloud config get-value account I0927 04:45:09.574] process 224 exited with code 0 after 0.0m I0927 04:45:09.575] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0927 04:45:09.575] Root: /go/src I0927 04:45:09.575] cd to /go/src I0927 04:45:09.575] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes I0927 04:45:09.576] Call: git init k8s.io/kubernetes I0927 04:45:09.581] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/ I0927 04:45:09.582] process 236 exited with code 0 after 0.0m I0927 04:45:09.582] Call: git config --local user.name 'K8S Bootstrap' I0927 04:45:09.592] process 237 exited with code 0 after 0.0m I0927 04:45:09.592] Call: git config --local user.email k8s_bootstrap@localhost I0927 04:45:09.599] process 238 exited with code 0 after 0.0m I0927 04:45:09.600] Call: git fetch --quiet --tags https://github.com/kubernetes/kubernetes master I0927 04:47:15.355] process 239 exited with code 0 after 2.1m I0927 04:47:15.356] Call: git checkout -B test FETCH_HEAD W0927 04:47:21.750] Switched to a new branch 'test' I0927 04:47:21.762] process 251 exited with code 0 after 0.1m I0927 04:47:21.762] Call: git show -s --format=format:%ct HEAD I0927 04:47:21.778] process 252 exited with code 0 after 0.0m I0927 04:47:21.778] Checkout: /go/src/github.com/kubernetes-sigs/azurefile-csi-driver master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:54f98c722b41e3e5fd2f6581defa0c4fafa962f2 to /go/src/github.com/kubernetes-sigs/azurefile-csi-driver I0927 04:47:21.779] Call: git init github.com/kubernetes-sigs/azurefile-csi-driver I0927 04:47:21.790] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/.git/ I0927 04:47:21.791] process 253 exited with code 0 after 0.0m I0927 04:47:21.791] Call: git config --local user.name 'K8S Bootstrap' I0927 04:47:21.801] process 254 exited with code 0 after 0.0m I0927 04:47:21.801] Call: git config --local user.email k8s_bootstrap@localhost I0927 04:47:21.811] process 255 exited with code 0 after 0.0m I0927 04:47:21.813] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azurefile-csi-driver master +refs/pull/120/head:refs/pr/120 I0927 04:47:31.838] process 256 exited with code 0 after 0.2m I0927 04:47:31.842] Call: git checkout -B test daea67167aecc01a8bfa2b24f33c4a08998cdc74 W0927 04:47:33.729] Switched to a new branch 'test' I0927 04:47:33.733] process 268 exited with code 0 after 0.0m I0927 04:47:33.734] Call: git show -s --format=format:%ct HEAD I0927 04:47:33.762] process 269 exited with code 0 after 0.0m I0927 04:47:33.763] Call: git merge --no-ff -m 'Merge +refs/pull/120/head:refs/pr/120' 54f98c722b41e3e5fd2f6581defa0c4fafa962f2 I0927 04:47:34.107] Auto-merging test/utils/credentials/credentials_test.go I0927 04:47:34.107] Auto-merging test/utils/credentials/credentials.go I0927 04:47:34.107] Removing test/e2e/run-test.sh I0927 04:47:34.107] Removing hack/template/azure.json I0927 04:47:34.107] Removing .travis.yml I0927 04:47:34.108] Merge made by the 'recursive' strategy. I0927 04:47:34.115] .travis.yml | 30 -------- I0927 04:47:34.115] Makefile | 64 ++++++++++++----- I0927 04:47:34.115] hack/template/azure.json | 9 --- I0927 04:47:34.115] hack/verify-golint.sh | 7 +- I0927 04:47:34.116] test/e2e/README.md | 24 +++++-- I0927 04:47:34.116] test/e2e/dynamic_provisioning.go | 17 ----- I0927 04:47:34.116] test/e2e/pre_provisioning.go | 23 +------ I0927 04:47:34.116] test/e2e/run-test.sh | 31 --------- I0927 04:47:34.117] test/e2e/suite_test.go | 87 ++++++++++++++++++++++++ I0927 04:47:34.117] test/integration/integration_test.go | 20 +++++- I0927 04:47:34.117] test/integration/run-tests-all-clouds.sh | 4 +- I0927 04:47:34.117] test/sanity/run-test.sh | 3 +- I0927 04:47:34.117] test/sanity/run-tests-all-clouds.sh | 4 +- I0927 04:47:34.118] test/sanity/sanity_test.go | 17 ++--- I0927 04:47:34.118] test/{ => utils}/azure/azure_helpers.go | 0 I0927 04:47:34.118] test/{ => utils}/credentials/credentials.go | 65 ++++++++++++------ I0927 04:47:34.118] test/{ => utils}/credentials/credentials_test.go | 54 +++++++-------- I0927 04:47:34.118] test/utils/testutil/testutil.go | 8 +++ I0927 04:47:34.119] 18 files changed, 267 insertions(+), 200 deletions(-) I0927 04:47:34.119] delete mode 100644 .travis.yml I0927 04:47:34.119] delete mode 100644 hack/template/azure.json I0927 04:47:34.119] delete mode 100755 test/e2e/run-test.sh I0927 04:47:34.119] rename test/{ => utils}/azure/azure_helpers.go (100%) I0927 04:47:34.119] rename test/{ => utils}/credentials/credentials.go (68%) I0927 04:47:34.120] rename test/{ => utils}/credentials/credentials_test.go (77%) I0927 04:47:34.120] create mode 100644 test/utils/testutil/testutil.go I0927 04:47:34.121] process 270 exited with code 0 after 0.0m I0927 04:47:34.121] Configure environment... I0927 04:47:34.121] Call: git show -s --format=format:%ct HEAD I0927 04:47:34.131] process 272 exited with code 0 after 0.0m I0927 04:47:34.131] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0927 04:47:45.522] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0927 04:47:45.781] process 273 exited with code 0 after 0.2m I0927 04:47:45.782] Call: gcloud config get-value account I0927 04:47:46.545] process 285 exited with code 0 after 0.0m I0927 04:47:46.545] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0927 04:47:46.546] 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 ' I0927 04:47:51.861] process 297 exited with code 0 after 0.1m I0927 04:47:52.070] Start 1177443927049900032 at v1.17.0-alpha.0.1869+d92a250636fadb... I0927 04:47:52.073] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_HDH2Pt gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032/started.json I0927 04:47:56.166] process 330 exited with code 0 after 0.1m I0927 04:47:56.167] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032' cp /tmp/gsutil_7DoXIn gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/1177443927049900032.txt I0927 04:47:59.674] process 508 exited with code 0 after 0.1m I0927 04:47:59.675] 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-file-csi-driver=True W0927 04:47:59.753] starts with local mode W0927 04:47:59.753] Environment: W0927 04:47:59.754] ARTIFACTS=/workspace/_artifacts W0927 04:47:59.754] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa W0927 04:47:59.754] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub W0927 04:47:59.754] AZURE_CREDENTIALS=/etc/azure-cred/credentials W0927 04:47:59.755] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub W0927 04:47:59.755] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer W0927 04:47:59.755] BAZEL_REMOTE_CACHE_ENABLED=false W0927 04:47:59.755] BAZEL_VERSION=0.23.2 W0927 04:47:59.756] BOOTSTRAP_MIGRATION=yes W0927 04:47:59.756] BUILD_ID=1177443927049900032 W0927 04:47:59.756] BUILD_NUMBER=1177443927049900032 W0927 04:47:59.756] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true W0927 04:47:59.756] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud W0927 04:47:59.757] CLOUDSDK_CORE_DISABLE_PROMPTS=1 W0927 04:47:59.757] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false W0927 04:47:59.757] CLOUD_CONFIG=random W0927 04:47:59.757] DECK_PORT=tcp://10.0.160.83:80 W0927 04:47:59.758] DECK_PORT_80_TCP=tcp://10.0.160.83:80 W0927 04:47:59.758] DECK_PORT_80_TCP_ADDR=10.0.160.83 W0927 04:47:59.758] DECK_PORT_80_TCP_PORT=80 W0927 04:47:59.758] DECK_PORT_80_TCP_PROTO=tcp W0927 04:47:59.759] DECK_SERVICE_HOST=10.0.160.83 W0927 04:47:59.759] DECK_SERVICE_PORT=80 W0927 04:47:59.759] DOCKER_IN_DOCKER_ENABLED=true W0927 04:47:59.759] DOCKER_IN_DOCKER_IPV6_ENABLED=false W0927 04:47:59.760] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032/artifacts W0927 04:47:59.760] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json W0927 04:47:59.760] GOPATH=/go W0927 04:47:59.760] GO_TARBALL=go1.12.1.linux-amd64.tar.gz W0927 04:47:59.761] HOME=/workspace W0927 04:47:59.761] HOOK_PORT=tcp://10.0.144.133:8888 W0927 04:47:59.761] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888 W0927 04:47:59.761] HOOK_PORT_8888_TCP_ADDR=10.0.144.133 W0927 04:47:59.762] HOOK_PORT_8888_TCP_PORT=8888 W0927 04:47:59.763] HOOK_PORT_8888_TCP_PROTO=tcp W0927 04:47:59.764] HOOK_SERVICE_HOST=10.0.144.133 W0927 04:47:59.764] HOOK_SERVICE_PORT=8888 W0927 04:47:59.766] HOSTNAME=7a872a7d-e0e1-11e9-918b-16773c0c7aed W0927 04:47:59.766] IMAGE=chewong/kubekins-e2e:v20190926-7484d81-azurefile-csi-driver-config W0927 04:47:59.766] INSTANCE_PREFIX=bootstrap-e2e W0927 04:47:59.766] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine W0927 04:47:59.767] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub W0927 04:47:59.767] JOB_NAME=pull-azurefile-csi-driver-e2e W0927 04:47:59.768] JOB_SPEC={"type":"presubmit","job":"pull-azurefile-csi-driver-e2e","buildid":"1177443927049900032","prowjobid":"7a872a7d-e0e1-11e9-918b-16773c0c7aed","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"d92a250636fadb2ce77bcadf832272c852ea9ed0","base_link":"https://github.com/Azure/kubernetes/commit/d92a250636fadb2ce77bcadf832272c852ea9ed0","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"}]}} W0927 04:47:59.768] JOB_TYPE=presubmit W0927 04:47:59.768] KUBERNETES_PORT=tcp://10.0.0.1:443 W0927 04:47:59.768] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443 W0927 04:47:59.769] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1 W0927 04:47:59.769] KUBERNETES_PORT_443_TCP_PORT=443 W0927 04:47:59.769] KUBERNETES_PORT_443_TCP_PROTO=tcp W0927 04:47:59.769] KUBERNETES_SERVICE_HOST=10.0.0.1 W0927 04:47:59.770] KUBERNETES_SERVICE_PORT=443 W0927 04:47:59.770] KUBERNETES_SERVICE_PORT_HTTPS=443 W0927 04:47:59.770] KUBETEST_IN_DOCKER=true W0927 04:47:59.770] KUBETEST_MANUAL_DUMP=y W0927 04:47:59.770] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e W0927 04:47:59.771] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e W0927 04:47:59.771] KUBE_VERBOSE=0 W0927 04:47:59.771] NODE_NAME=7a872a7d-e0e1-11e9-918b-16773c0c7aed W0927 04:47:59.772] 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 W0927 04:47:59.772] PROW_JOB_ID=7a872a7d-e0e1-11e9-918b-16773c0c7aed W0927 04:47:59.773] PULL_BASE_REF=master W0927 04:47:59.773] PULL_BASE_SHA=d92a250636fadb2ce77bcadf832272c852ea9ed0 W0927 04:47:59.773] PULL_NUMBER=113 W0927 04:47:59.774] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822 W0927 04:47:59.774] PULL_REFS=master:d92a250636fadb2ce77bcadf832272c852ea9ed0,113:879465972041635fd7bc8269da49153b67e63822 W0927 04:47:59.775] PWD=/workspace W0927 04:47:59.775] REGISTRY=k8sprowinternal.azurecr.io W0927 04:47:59.775] REPO_NAME=kubernetes W0927 04:47:59.776] REPO_OWNER=Azure W0927 04:47:59.776] SHLVL=2 W0927 04:47:59.777] SOURCE_DATE_EPOCH=1569551257 W0927 04:47:59.782] TERM=xterm W0927 04:47:59.782] TIDE_PORT=tcp://10.0.180.53:80 W0927 04:47:59.786] TIDE_PORT_80_TCP=tcp://10.0.180.53:80 W0927 04:47:59.786] TIDE_PORT_80_TCP_ADDR=10.0.180.53 W0927 04:47:59.786] TIDE_PORT_80_TCP_PORT=80 W0927 04:47:59.786] TIDE_PORT_80_TCP_PROTO=tcp W0927 04:47:59.787] TIDE_SERVICE_HOST=10.0.180.53 W0927 04:47:59.787] TIDE_SERVICE_PORT=80 W0927 04:47:59.787] WORKSPACE=/workspace W0927 04:47:59.788] _=./test-infra/jenkins/bootstrap.py W0927 04:47:59.789] 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-file-csi-driver=True') W0927 04:48:00.260] 2019/09/27 04:48:00 azure.go:230: Reading credentials file /etc/azure-cred/credentials W0927 04:48:00.260] 2019/09/27 04:48:00 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release I0927 04:48:00.361] make: Entering directory '/go/src/k8s.io/kubernetes' W0927 04:48:00.466] Extracting Bazel installation... W0927 04:48:33.514] Starting local Bazel server and connecting to it... W0927 04:48:44.922] Loading: W0927 04:48:44.933] Loading: 0 packages loaded W0927 04:48:46.051] Loading: 0 packages loaded W0927 04:48:47.035] Loading: 0 packages loaded W0927 04:48:49.075] Loading: 0 packages loaded W0927 04:48:51.043] Loading: 0 packages loaded W0927 04:48:53.098] Loading: 0 packages loaded W0927 04:48:54.642] Loading: 0 packages loaded W0927 04:48:56.243] Loading: 0 packages loaded W0927 04:48:59.173] Loading: 0 packages loaded W0927 04:48:59.173] currently loading: build/release-tars W0927 04:49:00.491] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured) W0927 04:49:02.954] Analyzing: target //build/release-tars:release-tars (46 packages loaded, 303 targets configured) W0927 04:49:05.773] Analyzing: target //build/release-tars:release-tars (169 packages loaded, 1105 targets configured) W0927 04:49:09.174] Analyzing: target //build/release-tars:release-tars (596 packages loaded, 1781 targets configured) W0927 04:49:12.942] Analyzing: target //build/release-tars:release-tars (1109 packages loaded, 4834 targets configured) W0927 04:49:17.275] Analyzing: target //build/release-tars:release-tars (1837 packages loaded, 12269 targets configured) W0927 04:49:22.254] Analyzing: target //build/release-tars:release-tars (2755 packages loaded, 23819 targets configured) W0927 04:49:28.878] Analyzing: target //build/release-tars:release-tars (3027 packages loaded, 27097 targets configured) W0927 04:49:35.609] Analyzing: target //build/release-tars:release-tars (3059 packages loaded, 33716 targets configured) W0927 04:49:43.331] Analyzing: target //build/release-tars:release-tars (3072 packages loaded, 35175 targets configured) W0927 04:50:02.571] INFO: Analysed target //build/release-tars:release-tars (3072 packages loaded, 42238 targets configured). W0927 04:50:02.573] Building: checking cached actions W0927 04:50:02.574] INFO: Found 1 target... W0927 04:50:03.860] [0 / 20] [-----] Writing file build/release-tars/_client-bin.args W0927 04:50:15.902] [109 / 5,296] GoCompile vendor/k8s.io/klog/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/klog.a [for host]; 1s linux-sandbox ... (8 actions, 7 running) W0927 04:50:29.727] [355 / 5,296] Compiling vendor/golang.org/x/net/ipv4/linux_amd64_stripped/go_default_library%linux_amd64%cgo_codegen%/sys_stub.cgo2.c; 0s linux-sandbox ... (7 actions running) W0927 04:50:45.604] [474 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 4s linux-sandbox ... (8 actions, 7 running) W0927 04:51:04.282] [765 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 23s linux-sandbox ... (8 actions, 7 running) W0927 04:51:25.808] [898 / 5,296] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 44s linux-sandbox ... (8 actions, 7 running) W0927 04:51:50.087] [1,087 / 5,296] GoCompile vendor/github.com/json-iterator/go/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/json-iterator/go.a; 2s linux-sandbox ... (8 actions, 7 running) W0927 04:52:19.134] [1,215 / 5,296] GoCompile vendor/github.com/go-openapi/spec/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/go-openapi/spec.a; 1s linux-sandbox ... (8 actions, 7 running) W0927 04:52:54.755] [1,517 / 5,296] GoCompile vendor/gopkg.in/square/go-jose.v2/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/gopkg.in/square/go-jose.v2.a; 5s linux-sandbox ... (8 actions, 7 running) W0927 04:53:32.425] [1,706 / 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; 5s linux-sandbox ... (8 actions, 7 running) W0927 04:54:15.880] [2,013 / 5,296] GoCompile vendor/github.com/vmware/govmomi/property/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vmware/govmomi/property.a; 0s linux-sandbox ... (8 actions, 7 running) W0927 04:55:05.650] [2,494 / 5,296] GoCompile vendor/github.com/aws/aws-sdk-go/service/ec2/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/aws/aws-sdk-go/service/ec2.a; 6s linux-sandbox ... (8 actions, 7 running) W0927 04:56:03.024] [2,752 / 5,296] GoCompile staging/src/k8s.io/apiserver/pkg/storage/value/encrypt/aes/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apiserver/pkg/storage/value/encrypt/aes.a; 7s linux-sandbox ... (8 actions running) W0927 04:57:09.701] [2,930 / 5,296] GoCompile pkg/apis/core/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/core.a; 3s linux-sandbox ... (8 actions, 7 running) W0927 04:58:28.499] [3,459 / 5,296] GoCompile pkg/apis/core/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/core.a; 3s linux-sandbox ... (8 actions, 7 running) W0927 04:59:56.176] [3,865 / 5,296] GoCompile staging/src/k8s.io/client-go/informers/core/v1/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/informers/core/v1.a; 0s linux-sandbox ... (8 actions, 7 running) W0927 05:01:37.260] [4,495 / 5,296] GoCompile test/e2e/common/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/test/e2e/common.a; 6s linux-sandbox ... (8 actions running) W0927 05:03:35.757] [4,576 / 5,296] GoLink cmd/genkubedocs/genkubedocs; 113s linux-sandbox ... (8 actions running) W0927 05:04:39.965] Slow read: a 1058838-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/io_k8s_kubernetes/bazel-out/k8-fastbuild/bin/staging/src/k8s.io/client-go/kubernetes/typed/apps/v1beta2/fake/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/kubernetes/typed/apps/v1beta2/fake.a took 25193 ms. W0927 05:05:15.346] Slow read: a 474454-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/io_k8s_kubernetes/bazel-out/k8-fastbuild/bin/staging/src/k8s.io/client-go/kubernetes/typed/storage/v1alpha1/fake/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/kubernetes/typed/storage/v1alpha1/fake.a took 33088 ms. W0927 05:05:50.792] [4,623 / 5,296] GoLink test/e2e/linux_amd64_stripped/_go_default_test-cgo; 194s linux-sandbox ... (8 actions, 7 running) W0927 05:08:26.303] [5,097 / 5,296] GoCompile staging/src/k8s.io/apiserver/pkg/server/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apiserver/pkg/server.a; 5s linux-sandbox ... (8 actions, 7 running) W0927 05:10:41.925] Target //build/release-tars:release-tars up-to-date: W0927 05:10:41.925] bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz W0927 05:10:41.926] bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz W0927 05:10:41.926] bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz W0927 05:10:41.926] bazel-bin/build/release-tars/kubernetes-manifests.tar.gz W0927 05:10:41.926] bazel-bin/build/release-tars/kubernetes-test-portable.tar.gz W0927 05:10:41.927] bazel-bin/build/release-tars/kubernetes-test-linux-amd64.tar.gz W0927 05:10:41.927] bazel-bin/build/release-tars/kubernetes.tar.gz W0927 05:10:41.927] bazel-bin/build/release-tars/kubernetes-src.tar.gz W0927 05:10:41.954] INFO: Elapsed time: 1361.416s, Critical Path: 435.61s W0927 05:10:41.955] INFO: 5205 processes: 5205 linux-sandbox. W0927 05:10:41.959] INFO: Build completed successfully, 5296 total actions W0927 05:10:41.961] INFO: Build completed successfully, 5296 total actions W0927 05:10:41.969] 2019/09/27 05:10:41 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 22m41.709040665s W0927 05:10:41.969] 2019/09/27 05:10:41 util.go:277: Flushing memory. I0927 05:10:42.069] make: Leaving directory '/go/src/k8s.io/kubernetes' W0927 05:11:08.638] 2019/09/27 05:11:08 azure.go:899: Deleting resource group: kubetest-fb7611be-e0e1-11e9-82e1-02426913df38. W0927 05:11:09.441] 2019/09/27 05:11:09 azure.go:780: downloading /workspace/aks601429613/kubernetes.json from https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json. W0927 05:11:09.442] 2019/09/27 05:11:09 util.go:42: curl https://raw.githubusercontent.com/kubernetes/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json W0927 05:11:09.695] 2019/09/27 05:11:09 azure.go:527: 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. W0927 05:11:09.696] 2019/09/27 05:11:09 util.go:42: curl https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz W0927 05:11:11.112] 2019/09/27 05:11:11 azure.go:554: Extracting tar file /workspace/aks-engine.tar.gz into directory /go/src/k8s.io/kubernetes . W0927 05:11:11.112] 2019/09/27 05:11:11 process.go:153: Running: tar -xzf /workspace/aks-engine.tar.gz --strip 1 W0927 05:11:11.283] 2019/09/27 05:11:11 process.go:155: Step 'tar -xzf /workspace/aks-engine.tar.gz --strip 1' finished in 170.950925ms W0927 05:11:11.283] 2019/09/27 05:11:11 process.go:153: Running: /go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks601429613/kubernetes.json --output-directory /workspace/aks601429613 I0927 05:11:11.495] INFO[0000] Generating assets into /workspace/aks601429613... W0927 05:11:28.258] 2019/09/27 05:11:28 process.go:155: Step '/go/src/k8s.io/kubernetes/aks-engine generate /workspace/aks601429613/kubernetes.json --output-directory /workspace/aks601429613' finished in 16.975170545s W0927 05:11:28.260] 2019/09/27 05:11:28 azure.go:625: Setting kubeconfig env variable: kubeconfig path: /workspace/aks601429613/kubeconfig/kubeconfig.eastus2.json. W0927 05:11:28.261] 2019/09/27 05:11:28 azure.go:627: Creating resource group: kubetest-fb7611be-e0e1-11e9-82e1-02426913df38. W0927 05:11:28.261] 2019/09/27 05:11:28 azure.go:629: Creating Azure resource group: kubetest-fb7611be-e0e1-11e9-82e1-02426913df38 for cluster deployment. W0927 05:11:34.698] 2019/09/27 05:11:34 azure.go:634: Validating deployment ARM templates. W0927 05:11:36.657] 2019/09/27 05:11:36 azure.go:640: Deploying cluster kubetest-fb7611be-e0e1-11e9-82e1-02426913df38 in resource group kubetest-fb7611be-e0e1-11e9-82e1-02426913df38. W0927 05:21:45.690] 2019/09/27 05:21:45 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W0927 05:21:46.354] 2019/09/27 05:21:46 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 664.002452ms W0927 05:21:46.354] 2019/09/27 05:21:46 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false get nodes -oyaml W0927 05:21:47.888] 2019/09/27 05:21:47 process.go:155: Step './cluster/kubectl.sh --match-server-version=false get nodes -oyaml' finished in 1.534286213s W0927 05:21:47.889] 2019/09/27 05:21:47 azure.go:944: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W0927 05:21:47.889] 2019/09/27 05:21:47 azure.go:944: KUBE_TEST_REPO_LIST_DOWNLOAD_LOCATION not set. Using default test image repos. W0927 05:21:47.890] 2019/09/27 05:21:47 process.go:153: Running: kubectl get nodes --no-headers W0927 05:21:48.760] 2019/09/27 05:21:48 process.go:155: Step 'kubectl get nodes --no-headers' finished in 870.170561ms W0927 05:21:48.761] 2019/09/27 05:21:48 e2e.go:462: Cluster nodes: W0927 05:21:48.761] k8s-agentpool1-41088983-1 Ready agent 60s v1.15.0 W0927 05:21:48.761] k8s-master-41088983-0 Ready master 93s v1.15.0 W0927 05:21:48.761] 2019/09/27 05:21:48 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version W0927 05:21:49.237] 2019/09/27 05:21:49 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 476.220181ms W0927 05:21:49.237] 2019/09/27 05:21:49 process.go:153: Running: make e2e-test I0927 05:21:49.337] go test -v -timeout=30m ./test/e2e "-ginkgo.noColor" W0927 05:21:49.438] go: finding github.com/json-iterator/go v1.1.5 W0927 05:21:49.438] go: finding github.com/google/uuid v1.1.1 W0927 05:21:49.439] go: finding github.com/PuerkitoBio/purell v1.1.1 W0927 05:21:49.439] go: finding github.com/googleapis/gnostic v0.2.0 W0927 05:21:49.439] go: finding github.com/Azure/azure-storage-file-go v0.5.0 W0927 05:21:49.439] go: finding github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0927 05:21:49.439] go: finding github.com/coreos/go-systemd v0.0.0-20190321100706-95778dfbb74e W0927 05:21:49.440] go: finding github.com/go-openapi/swag v0.19.0 W0927 05:21:49.440] go: finding github.com/emicklei/go-restful v2.9.3+incompatible W0927 05:21:49.627] go: finding golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0927 05:21:50.180] go: finding github.com/kr/pretty v0.1.0 W0927 05:21:50.181] go: finding github.com/golang/protobuf v1.3.0 W0927 05:21:50.195] go: finding github.com/dgrijalva/jwt-go v3.2.0+incompatible W0927 05:21:50.242] go: finding github.com/grpc-ecosystem/go-grpc-middleware v1.0.0 W0927 05:21:50.268] go: finding github.com/pkg/errors v0.8.1 W0927 05:21:50.355] go: finding sigs.k8s.io/yaml v1.1.0 W0927 05:21:50.392] go: finding k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0927 05:21:50.505] go: finding go.etcd.io/bbolt v1.3.2 W0927 05:21:50.524] go: finding go.uber.org/zap v1.9.1 W0927 05:21:50.729] go: finding github.com/marstr/guid v1.1.0 W0927 05:21:50.822] go: finding github.com/onsi/gomega v1.4.3 W0927 05:21:50.867] go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0927 05:21:50.902] go: finding github.com/grpc-ecosystem/go-grpc-prometheus v1.2.0 W0927 05:21:51.011] go: finding github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0927 05:21:51.194] go: finding github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0927 05:21:51.243] go: finding github.com/coreos/bbolt v1.3.2 W0927 05:21:51.244] go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0927 05:21:51.320] go: finding github.com/container-storage-interface/spec v1.1.0 W0927 05:21:51.374] go: finding github.com/prometheus/common v0.2.0 W0927 05:21:51.458] go: finding github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0927 05:21:51.567] go: finding k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0927 05:21:51.719] go: finding golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0927 05:21:51.749] go: finding gopkg.in/inf.v0 v0.9.1 W0927 05:21:51.846] go: finding gopkg.in/square/go-jose.v2 v2.3.0 W0927 05:21:51.847] go: finding k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0927 05:21:51.952] go: finding github.com/coreos/go-semver v0.3.0 W0927 05:21:51.989] go: finding golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0927 05:21:52.044] go: finding google.golang.org/api v0.1.0 W0927 05:21:52.191] go: finding github.com/Azure/azure-pipeline-go v0.2.1 W0927 05:21:52.215] go: finding k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0927 05:21:52.455] go: finding github.com/soheilhy/cmux v0.1.4 W0927 05:21:52.529] go: finding github.com/imdario/mergo v0.3.7 W0927 05:21:52.724] go: finding github.com/stretchr/testify v1.3.0 W0927 05:21:52.838] go: finding golang.org/x/text v0.3.0 W0927 05:21:52.915] go: finding k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0927 05:21:53.036] go: finding golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0927 05:21:53.128] go: finding contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0927 05:21:53.360] go: finding github.com/spf13/cobra v0.0.3 W0927 05:21:53.391] go: finding github.com/onsi/ginkgo v1.7.0 W0927 05:21:53.484] go: finding github.com/kubernetes-csi/external-snapshotter v1.0.1 W0927 05:21:53.540] go: finding k8s.io/client-go v11.0.0+incompatible W0927 05:21:53.651] go: finding github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0927 05:21:53.936] go: finding github.com/fatih/camelcase v1.0.0 W0927 05:21:54.007] go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 W0927 05:21:54.052] go: finding github.com/coreos/etcd v3.3.12+incompatible W0927 05:21:54.169] go: finding k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0927 05:21:54.187] go: finding github.com/kr/text v0.1.0 W0927 05:21:54.359] go: finding gopkg.in/fsnotify.v1 v1.4.7 W0927 05:21:54.420] go: finding github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0927 05:21:54.536] go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf W0927 05:21:54.699] go: finding github.com/julienschmidt/httprouter v1.2.0 W0927 05:21:54.767] go: finding github.com/evanphx/json-patch v3.0.0+incompatible W0927 05:21:54.904] go: finding k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0927 05:21:54.935] go: finding github.com/coreos/pkg v0.0.0-20180928190104-399ea9e2e55f W0927 05:21:55.088] go: finding k8s.io/klog v0.2.0 W0927 05:21:55.302] go: finding github.com/spf13/pflag v1.0.3 W0927 05:21:55.399] go: finding gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0927 05:21:55.409] go: finding google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0927 05:21:55.648] go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 W0927 05:21:55.677] go: finding k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0927 05:21:55.807] go: finding github.com/Azure/go-autorest v11.5.1+incompatible W0927 05:21:55.883] go: finding github.com/go-stack/stack v1.8.0 W0927 05:21:56.060] go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 W0927 05:21:56.195] go: finding go.uber.org/multierr v1.1.0 W0927 05:21:56.198] go: finding sigs.k8s.io/kustomize v2.0.3+incompatible W0927 05:21:56.438] go: finding github.com/pelletier/go-toml v1.4.0 W0927 05:21:56.475] go: finding golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0927 05:21:56.584] go: finding k8s.io/kubernetes v1.14.0 W0927 05:21:56.884] go: finding go.opencensus.io v0.18.0 W0927 05:21:57.629] go: finding k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0927 05:21:57.667] go: finding github.com/stretchr/objx v0.1.1 W0927 05:21:57.707] go: finding github.com/elazarl/goproxy/ext v0.0.0-20190410145444-c548f45dcf1d W0927 05:21:57.759] go: finding gopkg.in/yaml.v2 v2.2.2 W0927 05:21:57.896] go: finding github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0927 05:21:57.896] go: finding github.com/hpcloud/tail v1.0.0 W0927 05:21:58.303] go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0927 05:21:58.352] go: finding k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0927 05:21:58.378] go: finding github.com/tmc/grpc-websocket-proxy v0.0.0-20190109142713-0ad062ec5ee5 W0927 05:21:59.320] go: finding github.com/go-openapi/jsonreference v0.19.0 W0927 05:21:59.380] go: finding github.com/census-instrumentation/opencensus-proto v0.1.0 W0927 05:21:59.399] go: finding go.uber.org/atomic v1.3.2 W0927 05:21:59.412] go: finding github.com/ghodss/yaml v1.0.0 W0927 05:21:59.427] go: finding github.com/gogo/protobuf v1.2.1 W0927 05:21:59.576] go: finding k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0927 05:21:59.588] go: finding google.golang.org/grpc v1.19.0 W0927 05:21:59.902] go: finding github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0927 05:21:59.931] go: finding github.com/prometheus/client_golang v0.9.2 W0927 05:21:59.932] go: finding github.com/kr/pty v1.1.5 W0927 05:22:00.081] go: finding github.com/pmezard/go-difflib v1.0.0 W0927 05:22:00.447] go: finding github.com/go-openapi/spec v0.19.0 W0927 05:22:00.497] go: finding google.golang.org/appengine v1.4.0 W0927 05:22:00.585] go: finding github.com/go-logfmt/logfmt v0.3.0 W0927 05:22:00.587] go: finding github.com/inconshreveable/mousetrap v1.0.0 W0927 05:22:00.618] go: finding golang.org/x/exp v0.0.0-20190121172915-509febef88a4 W0927 05:22:00.653] go: finding k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0927 05:22:00.684] go: finding github.com/go-kit/kit v0.8.0 W0927 05:22:00.767] go: finding github.com/BurntSushi/toml v0.3.1 W0927 05:22:00.811] go: finding github.com/aws/aws-sdk-go v1.16.26 W0927 05:22:01.047] go: finding github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0927 05:22:01.148] go: finding github.com/GoogleCloudPlatform/k8s-cloud-provider v0.0.0-20181220005116-f8e995905100 W0927 05:22:01.168] go: finding github.com/ugorji/go v1.1.4 W0927 05:22:01.227] go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b W0927 05:22:01.357] go: finding github.com/golang/mock v1.1.1 W0927 05:22:01.366] go: finding github.com/google/btree v1.0.0 W0927 05:22:01.438] go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0927 05:22:01.529] go: finding github.com/jonboulle/clockwork v0.1.0 W0927 05:22:01.846] go: finding github.com/dnaeon/go-vcr v1.0.1 W0927 05:22:02.019] go: finding github.com/openzipkin/zipkin-go v0.1.1 W0927 05:22:02.051] go: finding github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0927 05:22:02.061] go: finding github.com/vmware/govmomi v0.20.1 W0927 05:22:02.064] go: finding github.com/davecgh/go-spew v1.1.1 W0927 05:22:02.132] go: finding github.com/fsnotify/fsnotify v1.4.7 W0927 05:22:02.133] go: finding golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0927 05:22:02.452] go: finding github.com/elazarl/goproxy v0.0.0-20190410145444-c548f45dcf1d W0927 05:22:02.516] go: finding github.com/kisielk/errcheck v1.1.0 W0927 05:22:02.523] go: finding github.com/opencontainers/go-digest v1.0.0-rc1 W0927 05:22:02.672] go: finding github.com/rogpeppe/go-charset v0.0.0-20180617210344-2471d30d28b4 W0927 05:22:02.723] go: finding golang.org/x/lint v0.0.0-20190227174305-5b3e6a55c961 W0927 05:22:02.803] go: finding github.com/apache/thrift v0.0.0-20180902110319-2566ecd5d999 W0927 05:22:02.999] go: finding github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0927 05:22:03.276] go: finding github.com/xiang90/probing v0.0.0-20190116061207-43a291ad63a2 W0927 05:22:03.278] go: finding gopkg.in/warnings.v0 v0.1.1 W0927 05:22:03.515] go: finding honnef.co/go/tools v0.0.0-20190102054323-c2f93a96b099 W0927 05:22:03.656] go: finding github.com/grpc-ecosystem/grpc-gateway v1.5.0 W0927 05:22:03.699] go: finding github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0927 05:22:03.990] go: finding github.com/hashicorp/golang-lru v0.5.1 W0927 05:22:04.127] go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 W0927 05:22:04.147] go: finding github.com/peterbourgon/diskv v2.0.1+incompatible W0927 05:22:04.366] go: finding github.com/client9/misspell v0.3.4 W0927 05:22:04.482] go: finding github.com/satori/go.uuid v1.2.0 W0927 05:22:04.694] go: finding github.com/go-openapi/jsonpointer v0.19.0 W0927 05:22:04.720] go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc W0927 05:22:04.764] go: finding cloud.google.com/go v0.34.0 W0927 05:22:04.856] go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 W0927 05:22:05.152] go: finding k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0927 05:22:05.256] go: finding gopkg.in/gcfg.v1 v1.2.0 W0927 05:22:05.587] go: finding github.com/sirupsen/logrus v1.2.0 W0927 05:22:05.939] go: finding github.com/spf13/afero v1.2.2 W0927 05:22:05.971] go: finding golang.org/x/tools v0.0.0-20190226205152-f727befe758c W0927 05:22:06.188] go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 W0927 05:22:06.639] go: finding github.com/google/go-cmp v0.2.0 W0927 05:22:06.640] go: finding github.com/kisielk/gotool v1.0.0 W0927 05:22:06.687] go: finding github.com/gorilla/websocket v1.4.0 W0927 05:22:10.663] go: finding github.com/jmespath/go-jmespath v0.0.0-20180206201540-c2b33e8439af W0927 05:22:11.875] go: downloading k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0927 05:22:11.875] go: downloading k8s.io/kubernetes v1.14.0 W0927 05:22:11.876] go: downloading k8s.io/client-go v11.0.0+incompatible W0927 05:22:11.876] go: downloading github.com/container-storage-interface/spec v1.1.0 W0927 05:22:11.879] go: downloading k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0927 05:22:11.883] go: downloading golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0927 05:22:11.884] go: downloading github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0927 05:22:11.884] go: downloading sigs.k8s.io/yaml v1.1.0 W0927 05:22:11.886] go: downloading github.com/golang/protobuf v1.3.0 W0927 05:22:11.973] go: extracting sigs.k8s.io/yaml v1.1.0 W0927 05:22:11.979] go: extracting github.com/container-storage-interface/spec v1.1.0 W0927 05:22:11.989] go: downloading google.golang.org/grpc v1.19.0 W0927 05:22:12.244] go: extracting github.com/golang/protobuf v1.3.0 W0927 05:22:12.330] go: extracting k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0927 05:22:12.410] go: downloading gopkg.in/yaml.v2 v2.2.2 W0927 05:22:12.524] go: extracting gopkg.in/yaml.v2 v2.2.2 W0927 05:22:12.534] go: downloading k8s.io/klog v0.2.0 W0927 05:22:12.590] go: extracting k8s.io/klog v0.2.0 W0927 05:22:12.593] go: downloading github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0927 05:22:12.666] go: extracting github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0927 05:22:12.669] go: downloading github.com/kubernetes-csi/external-snapshotter v1.0.1 W0927 05:22:12.675] go: extracting k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0927 05:22:12.675] go: extracting google.golang.org/grpc v1.19.0 W0927 05:22:12.754] go: downloading github.com/pelletier/go-toml v1.4.0 W0927 05:22:12.802] go: extracting k8s.io/client-go v11.0.0+incompatible W0927 05:22:12.876] go: extracting github.com/pelletier/go-toml v1.4.0 W0927 05:22:12.942] go: extracting golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0927 05:22:12.954] go: downloading github.com/onsi/ginkgo v1.7.0 W0927 05:22:12.975] go: downloading gopkg.in/inf.v0 v0.9.1 W0927 05:22:13.031] go: extracting gopkg.in/inf.v0 v0.9.1 W0927 05:22:13.035] go: downloading github.com/google/uuid v1.1.1 W0927 05:22:13.103] go: extracting github.com/google/uuid v1.1.1 W0927 05:22:13.127] go: downloading github.com/Azure/go-autorest v11.5.1+incompatible W0927 05:22:13.242] go: extracting github.com/onsi/ginkgo v1.7.0 W0927 05:22:13.285] go: extracting github.com/Azure/go-autorest v11.5.1+incompatible W0927 05:22:13.305] go: downloading k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0927 05:22:13.354] go: downloading github.com/gogo/protobuf v1.2.1 W0927 05:22:13.630] go: extracting k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0927 05:22:13.687] go: downloading k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0927 05:22:14.163] go: extracting k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0927 05:22:14.864] go: extracting github.com/gogo/protobuf v1.2.1 W0927 05:22:15.097] go: downloading github.com/onsi/gomega v1.4.3 W0927 05:22:15.258] go: extracting github.com/onsi/gomega v1.4.3 W0927 05:22:15.281] go: downloading golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0927 05:22:16.299] go: extracting golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0927 05:22:22.379] go: extracting github.com/kubernetes-csi/external-snapshotter v1.0.1 W0927 05:22:22.389] go: downloading github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0927 05:22:22.400] go: downloading github.com/dgrijalva/jwt-go v3.2.0+incompatible W0927 05:22:22.476] go: extracting github.com/dgrijalva/jwt-go v3.2.0+incompatible W0927 05:22:22.494] go: downloading go.opencensus.io v0.18.0 W0927 05:22:22.641] go: extracting github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0927 05:22:22.669] go: extracting github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0927 05:22:22.680] go: extracting go.opencensus.io v0.18.0 W0927 05:22:22.702] go: downloading golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0927 05:22:22.708] go: downloading github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0927 05:22:22.778] go: extracting github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0927 05:22:22.787] go: downloading golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0927 05:22:22.828] go: extracting golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0927 05:22:22.833] go: downloading github.com/json-iterator/go v1.1.5 W0927 05:22:22.935] go: extracting github.com/json-iterator/go v1.1.5 W0927 05:22:22.948] go: downloading golang.org/x/text v0.3.0 W0927 05:22:23.606] go: extracting golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0927 05:22:23.693] go: downloading github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0927 05:22:23.747] go: extracting github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0927 05:22:23.750] go: downloading k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0927 05:22:23.855] go: extracting k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0927 05:22:24.083] go: downloading github.com/hpcloud/tail v1.0.0 W0927 05:22:24.131] go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0927 05:22:24.147] go: extracting github.com/hpcloud/tail v1.0.0 W0927 05:22:24.165] go: extracting github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0927 05:22:24.167] go: downloading golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0927 05:22:24.226] go: extracting golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0927 05:22:24.234] go: downloading github.com/davecgh/go-spew v1.1.1 W0927 05:22:24.290] go: extracting github.com/davecgh/go-spew v1.1.1 W0927 05:22:24.304] go: downloading github.com/prometheus/client_golang v0.9.2 W0927 05:22:24.425] go: extracting github.com/prometheus/client_golang v0.9.2 W0927 05:22:24.440] go: downloading github.com/satori/go.uuid v1.2.0 W0927 05:22:24.469] go: extracting github.com/satori/go.uuid v1.2.0 W0927 05:22:24.471] go: downloading k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0927 05:22:25.079] go: extracting k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0927 05:22:25.130] go: downloading github.com/prometheus/common v0.2.0 W0927 05:22:25.135] go: extracting k8s.io/kubernetes v1.14.0 W0927 05:22:25.213] go: extracting github.com/prometheus/common v0.2.0 W0927 05:22:25.226] go: downloading github.com/marstr/guid v1.1.0 W0927 05:22:25.262] go: extracting github.com/marstr/guid v1.1.0 W0927 05:22:25.263] go: downloading github.com/googleapis/gnostic v0.2.0 W0927 05:22:25.651] go: extracting github.com/googleapis/gnostic v0.2.0 W0927 05:22:25.695] go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0927 05:22:25.781] go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0927 05:22:25.788] go: downloading gopkg.in/fsnotify.v1 v1.4.7 W0927 05:22:25.846] go: extracting gopkg.in/fsnotify.v1 v1.4.7 W0927 05:22:26.423] go: extracting golang.org/x/text v0.3.0 W0927 05:22:26.698] go: downloading github.com/hashicorp/golang-lru v0.5.1 W0927 05:22:26.700] go: downloading github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0927 05:22:26.700] go: downloading google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0927 05:22:26.700] go: downloading github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0927 05:22:26.701] go: downloading contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0927 05:22:26.710] go: downloading gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0927 05:22:26.890] go: extracting github.com/hashicorp/golang-lru v0.5.1 W0927 05:22:26.903] go: downloading github.com/Azure/azure-storage-file-go v0.5.0 W0927 05:22:26.904] go: extracting gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0927 05:22:26.905] go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 W0927 05:22:26.913] go: extracting github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0927 05:22:26.913] go: extracting contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0927 05:22:26.913] go: extracting github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0927 05:22:26.917] go: downloading github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0927 05:22:26.930] go: downloading google.golang.org/api v0.1.0 W0927 05:22:26.989] go: downloading github.com/census-instrumentation/opencensus-proto v0.1.0 W0927 05:22:27.012] go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 W0927 05:22:27.137] go: downloading k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0927 05:22:27.141] go: downloading k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0927 05:22:27.145] go: extracting github.com/Azure/azure-storage-file-go v0.5.0 W0927 05:22:27.182] go: extracting github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0927 05:22:27.183] go: downloading k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0927 05:22:27.215] go: downloading k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0927 05:22:27.219] go: extracting github.com/census-instrumentation/opencensus-proto v0.1.0 W0927 05:22:27.229] go: downloading github.com/Azure/azure-pipeline-go v0.2.1 W0927 05:22:27.232] go: downloading k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0927 05:22:27.307] go: extracting github.com/Azure/azure-pipeline-go v0.2.1 W0927 05:22:27.315] go: downloading github.com/pkg/errors v0.8.1 W0927 05:22:27.370] go: extracting k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0927 05:22:27.398] go: extracting github.com/pkg/errors v0.8.1 W0927 05:22:27.401] go: downloading gopkg.in/square/go-jose.v2 v2.3.0 W0927 05:22:27.483] go: extracting k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0927 05:22:27.656] go: extracting gopkg.in/square/go-jose.v2 v2.3.0 W0927 05:22:27.676] go: downloading github.com/stretchr/testify v1.3.0 W0927 05:22:27.810] go: extracting github.com/stretchr/testify v1.3.0 W0927 05:22:27.819] go: extracting k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0927 05:22:27.820] go: downloading github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0927 05:22:27.873] go: extracting github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0927 05:22:27.935] go: downloading github.com/spf13/pflag v1.0.3 W0927 05:22:27.936] go: downloading k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0927 05:22:27.940] go: downloading github.com/pmezard/go-difflib v1.0.0 W0927 05:22:27.944] go: downloading github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0927 05:22:27.949] go: downloading github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0927 05:22:28.109] go: extracting github.com/pmezard/go-difflib v1.0.0 W0927 05:22:28.115] go: downloading github.com/imdario/mergo v0.3.7 W0927 05:22:28.152] go: extracting github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0927 05:22:28.157] go: downloading github.com/fatih/camelcase v1.0.0 W0927 05:22:28.175] go: extracting k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0927 05:22:28.181] go: downloading k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0927 05:22:28.202] go: extracting github.com/imdario/mergo v0.3.7 W0927 05:22:28.205] go: extracting github.com/spf13/pflag v1.0.3 W0927 05:22:28.228] go: extracting github.com/fatih/camelcase v1.0.0 W0927 05:22:28.324] go: extracting k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0927 05:22:28.343] go: downloading github.com/spf13/cobra v0.0.3 W0927 05:22:28.346] go: downloading github.com/evanphx/json-patch v3.0.0+incompatible W0927 05:22:28.346] go: downloading github.com/peterbourgon/diskv v2.0.1+incompatible W0927 05:22:28.346] go: downloading sigs.k8s.io/kustomize v2.0.3+incompatible W0927 05:22:28.442] go: extracting github.com/evanphx/json-patch v3.0.0+incompatible W0927 05:22:28.450] go: extracting github.com/peterbourgon/diskv v2.0.1+incompatible W0927 05:22:28.484] go: extracting github.com/spf13/cobra v0.0.3 W0927 05:22:28.497] go: downloading github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0927 05:22:28.552] go: extracting github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0927 05:22:28.644] go: extracting k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0927 05:22:28.777] go: extracting github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0927 05:22:28.847] go: downloading github.com/opencontainers/go-digest v1.0.0-rc1 W0927 05:22:28.893] go: extracting github.com/opencontainers/go-digest v1.0.0-rc1 W0927 05:22:28.896] go: downloading github.com/google/btree v1.0.0 W0927 05:22:28.939] go: extracting github.com/google/btree v1.0.0 W0927 05:22:28.950] go: extracting k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0927 05:22:29.503] go: extracting sigs.k8s.io/kustomize v2.0.3+incompatible W0927 05:22:29.536] go: downloading github.com/ghodss/yaml v1.0.0 W0927 05:22:29.538] go: downloading github.com/go-openapi/spec v0.19.0 W0927 05:22:29.538] go: downloading github.com/emicklei/go-restful v2.9.3+incompatible W0927 05:22:29.595] go: extracting github.com/ghodss/yaml v1.0.0 W0927 05:22:29.644] go: extracting github.com/emicklei/go-restful v2.9.3+incompatible W0927 05:22:29.767] go: extracting github.com/go-openapi/spec v0.19.0 W0927 05:22:29.787] go: downloading github.com/go-openapi/jsonreference v0.19.0 W0927 05:22:29.787] go: downloading github.com/go-openapi/swag v0.19.0 W0927 05:22:29.787] go: downloading github.com/go-openapi/jsonpointer v0.19.0 W0927 05:22:29.847] go: extracting github.com/go-openapi/jsonpointer v0.19.0 W0927 05:22:29.880] go: extracting github.com/go-openapi/swag v0.19.0 W0927 05:22:29.880] go: extracting github.com/go-openapi/jsonreference v0.19.0 W0927 05:22:29.882] go: downloading github.com/PuerkitoBio/purell v1.1.1 W0927 05:22:29.884] go: downloading github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0927 05:22:29.939] go: extracting github.com/PuerkitoBio/purell v1.1.1 W0927 05:22:29.941] go: downloading github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0927 05:22:29.974] go: extracting google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0927 05:22:29.986] go: extracting github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0927 05:22:29.994] go: extracting github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0927 05:22:33.217] go: extracting google.golang.org/api v0.1.0 W0927 05:22:33.702] go: downloading golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0927 05:22:33.754] go: extracting golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 I0927 05:23:24.998] Sep 27 05:23:24.998: INFO: The --provider flag is not set. Continuing as if --provider=skeleton had been used. I0927 05:23:24.998] === RUN TestE2E I0927 05:23:24.998] Running Suite: AzureFile CSI Driver End-to-End Tests I0927 05:23:24.998] ==================================================== I0927 05:23:24.999] Random Seed: 1569561804 - Will randomize all specs I0927 05:23:24.999] Will run 8 of 8 specs I0927 05:23:24.999] I0927 05:23:27.521] 2019/09/27 05:23:27 Attempting docker login with Azure service principal I0927 05:23:28.760] 2019/09/27 05:23:28 docker login is successful I0927 05:23:28.760] 2019/09/27 05:23:28 Installing Azure File CSI Driver... I0927 05:23:28.761] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 05:23:28.761] # Use v2.11.0 helm to match tiller's version in clusters made by aks-engine I0927 05:23:28.761] curl https://raw.githubusercontent.com/helm/helm/master/scripts/get | DESIRED_VERSION=v2.11.0 bash I0927 05:23:28.787] % Total % Received % Xferd Average Speed Time Time Time Current I0927 05:23:28.787] Dload Upload Total Spent Left Speed I0927 05:23:28.848] 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7034 100 7034 0 0 112k 0 --:--:-- --:--:-- --:--:-- 112k I0927 05:23:28.864] Downloading https://get.helm.sh/helm-v2.11.0-linux-amd64.tar.gz I0927 05:23:30.039] Preparing to install helm and tiller into /usr/local/bin I0927 05:23:30.067] helm installed into /usr/local/bin/helm I0927 05:23:30.093] tiller installed into /usr/local/bin/tiller I0927 05:23:30.095] Run 'helm init' to configure helm. I0927 05:23:30.111] # Make sure tiller is ready I0927 05:23:30.112] kubectl wait pod -l name=tiller --namespace kube-system --for condition=ready I0927 05:23:33.514] pod/tiller-deploy-54c96cb5df-stq5w condition met I0927 05:23:33.517] helm version I0927 05:23:33.571] Client: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I0927 05:23:34.217] Error: forwarding ports: error upgrading connection: no preferred addresses found; known addresses: [] I0927 05:23:34.219] Makefile:71: recipe for target 'install-helm' failed I0927 05:23:34.219] make[1]: *** [install-helm] Error 1 I0927 05:23:34.219] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 05:23:34.220] Failure [9.221 seconds] I0927 05:23:34.220] [BeforeSuite] BeforeSuite I0927 05:23:34.220] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e/suite_test.go:54 I0927 05:23:34.220] I0927 05:23:34.220] Expected error: I0927 05:23:34.221] <*exec.ExitError | 0xc00099e660>: { I0927 05:23:34.221] ProcessState: { I0927 05:23:34.221] pid: 22921, I0927 05:23:34.221] status: 512, I0927 05:23:34.221] rusage: { I0927 05:23:34.222] Utime: {Sec: 0, Usec: 911146}, I0927 05:23:34.222] Stime: {Sec: 0, Usec: 339659}, I0927 05:23:34.222] Maxrss: 51444, I0927 05:23:34.222] Ixrss: 0, I0927 05:23:34.223] Idrss: 0, I0927 05:23:34.223] Isrss: 0, I0927 05:23:34.223] Minflt: 10525, I0927 05:23:34.223] Majflt: 46, I0927 05:23:34.223] Nswap: 0, I0927 05:23:34.223] Inblock: 13272, I0927 05:23:34.224] Oublock: 286584, I0927 05:23:34.224] Msgsnd: 0, I0927 05:23:34.224] Msgrcv: 0, I0927 05:23:34.224] Nsignals: 0, I0927 05:23:34.224] Nvcsw: 3692, I0927 05:23:34.224] Nivcsw: 181, I0927 05:23:34.225] }, I0927 05:23:34.225] }, I0927 05:23:34.225] Stderr: nil, I0927 05:23:34.225] } I0927 05:23:34.225] exit status 2 I0927 05:23:34.225] not to have occurred I0927 05:23:34.225] I0927 05:23:34.226] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e/suite_test.go:93 I0927 05:23:34.226] ------------------------------ I0927 05:23:34.226] 2019/09/27 05:23:34 Uninstalling Azure File CSI Driver... I0927 05:23:34.226] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 05:23:34.226] helm delete --purge azurefile-csi-driver I0927 05:23:34.975] Error: forwarding ports: error upgrading connection: no preferred addresses found; known addresses: [] I0927 05:23:34.977] Makefile:86: recipe for target 'uninstall-driver' failed I0927 05:23:34.978] make[1]: *** [uninstall-driver] Error 1 I0927 05:23:34.978] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver' I0927 05:23:34.978] Failure [0.758 seconds] I0927 05:23:34.978] [AfterSuite] AfterSuite I0927 05:23:34.979] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e/suite_test.go:104 I0927 05:23:34.979] I0927 05:23:34.979] Expected error: I0927 05:23:34.979] <*exec.ExitError | 0xc000966500>: { I0927 05:23:34.980] ProcessState: { I0927 05:23:34.980] pid: 22983, I0927 05:23:34.980] status: 512, I0927 05:23:34.980] rusage: { I0927 05:23:34.981] Utime: {Sec: 0, Usec: 129478}, I0927 05:23:34.981] Stime: {Sec: 0, Usec: 31762}, I0927 05:23:34.981] Maxrss: 51444, I0927 05:23:34.981] Ixrss: 0, I0927 05:23:34.982] Idrss: 0, I0927 05:23:34.982] Isrss: 0, I0927 05:23:34.982] Minflt: 3926, I0927 05:23:34.983] Majflt: 0, I0927 05:23:34.983] Nswap: 0, I0927 05:23:34.983] Inblock: 0, I0927 05:23:34.983] Oublock: 0, I0927 05:23:34.983] Msgsnd: 0, I0927 05:23:34.984] Msgrcv: 0, I0927 05:23:34.984] Nsignals: 0, I0927 05:23:34.984] Nvcsw: 754, I0927 05:23:34.984] Nivcsw: 8, I0927 05:23:34.985] }, I0927 05:23:34.985] }, I0927 05:23:34.985] Stderr: nil, I0927 05:23:34.985] } I0927 05:23:34.985] exit status 2 I0927 05:23:34.986] not to have occurred I0927 05:23:34.986] I0927 05:23:34.986] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e/suite_test.go:122 I0927 05:23:34.986] ------------------------------ I0927 05:23:34.986] I0927 05:23:34.987] Ran 8 of 0 Specs in 9.980 seconds I0927 05:23:34.987] FAIL! -- 0 Passed | 8 Failed | 0 Pending | 0 Skipped I0927 05:23:34.987] --- FAIL: TestE2E (9.98s) I0927 05:23:34.987] FAIL I0927 05:23:34.987] FAIL github.com/kubernetes-sigs/azurefile-csi-driver/test/e2e 10.033s I0927 05:23:35.227] Makefile:58: recipe for target 'e2e-test' failed W0927 05:23:35.328] make: *** [e2e-test] Error 1 W0927 05:23:35.328] 2019/09/27 05:23:35 process.go:155: Step 'make e2e-test' finished in 1m45.991056382s W0927 05:23:35.328] 2019/09/27 05:23:35 azure.go:899: Deleting resource group: kubetest-fb7611be-e0e1-11e9-82e1-02426913df38. W0927 05:33:44.753] 2019/09/27 05:33:44 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W0927 05:33:44.754] 2019/09/27 05:33:44 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W0927 05:33:45.452] 2019/09/27 05:33:45 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 698.295117ms W0927 05:33:45.452] 2019/09/27 05:33:45 main.go:319: Something went wrong: encountered 1 errors: [error during make e2e-test: exit status 2] W0927 05:33:45.455] Traceback (most recent call last): W0927 05:33:45.455] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in W0927 05:33:45.455] main(parse_args()) W0927 05:33:45.456] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W0927 05:33:45.456] mode.start(runner_args) W0927 05:33:45.456] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W0927 05:33:45.456] check_env(env, self.command, *args) W0927 05:33:45.456] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W0927 05:33:45.456] subprocess.check_call(cmd, env=env) W0927 05:33:45.456] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W0927 05:33:45.456] raise CalledProcessError(retcode, cmd) W0927 05:33:45.457] 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-file-csi-driver=True')' returned non-zero exit status 1 E0927 05:33:45.462] Command failed I0927 05:33:45.463] process 686 exited with code 1 after 45.8m E0927 05:33:45.463] FAIL: pull-azurefile-csi-driver-e2e I0927 05:33:45.463] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0927 05:33:46.231] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0927 05:33:46.260] process 23033 exited with code 0 after 0.0m I0927 05:33:46.260] Call: gcloud config get-value account I0927 05:33:46.496] process 23045 exited with code 0 after 0.0m I0927 05:33:46.497] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0927 05:33:46.497] Upload result and artifacts... I0927 05:33:46.497] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032 I0927 05:33:46.497] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032/artifacts W0927 05:33:47.665] CommandException: One or more URLs matched no objects. E0927 05:33:47.742] Command failed I0927 05:33:47.743] process 23057 exited with code 1 after 0.0m W0927 05:33:47.743] Remote dir gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032/artifacts not exist yet I0927 05:33:47.743] 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_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032/artifacts I0927 05:33:49.229] process 23199 exited with code 0 after 0.0m I0927 05:33:49.230] Call: git rev-parse HEAD I0927 05:33:49.233] process 23726 exited with code 0 after 0.0m I0927 05:33:49.233] Call: git rev-parse HEAD I0927 05:33:49.237] process 23727 exited with code 0 after 0.0m I0927 05:33:49.237] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 05:33:50.187] process 23728 exited with code 0 after 0.0m I0927 05:33:50.188] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/jobResultsCache.json#1569559125155574' I0927 05:33:51.335] process 23870 exited with code 0 after 0.0m I0927 05:33:51.336] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569559125155574 cp /tmp/gsutil_jzr9Ky gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 05:33:52.621] process 24015 exited with code 0 after 0.0m I0927 05:33:52.622] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 05:33:53.548] process 24193 exited with code 0 after 0.0m I0927 05:33:53.548] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/jobResultsCache.json#1569559128482517' I0927 05:33:54.738] process 24335 exited with code 0 after 0.0m I0927 05:33:54.739] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569559128482517 cp /tmp/gsutil_yKODdW gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/jobResultsCache.json I0927 05:33:55.987] process 24479 exited with code 0 after 0.0m I0927 05:33:55.988] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_E5ZD9I gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/1177443927049900032/finished.json I0927 05:33:57.339] process 24657 exited with code 0 after 0.0m I0927 05:33:57.340] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_a_kjIH gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-e2e/latest-build.txt I0927 05:33:58.636] process 24835 exited with code 0 after 0.0m I0927 05:33:58.637] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_8Ge0tV gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-e2e/latest-build.txt I0927 05:34:00.019] process 25013 exited with code 0 after 0.0m