W1126 16:55:58.034] **************************************************************************
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
**************************************************************************
I1126 16:55:58.034] Args: --job=chewong-in-tree-azure-disk-e2e --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=chewong-in-tree-azure-disk-e2e --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes=master:1c5b6cb66e6ae85177e76d4fddf7d99473ab2aed,85621:dd76fdfce5554cc15ee7ed999bea1cffb0436d0a --upload=gs://kubernetes-upstream/pr-logs --timeout=460 --scenario=kubernetes_e2e -- --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=quick --provider=azure --ginkgo-parallel=10 --acsengine-agentpoolcount=2 --acsengine-admin-username=azureuser '--acsengine-creds=$AZURE_CREDENTIALS' --acsengine-orchestratorRelease=1.17 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --aksengine-custom-k8s-comopnents=True --acsengine-location=westus2 '--acsengine-public-key=$AZURE_SSH_PUBLIC_KEY_FILE' --acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes-vmss/kubernetes.json --acsengine-download-url=https://chuwontest.blob.core.windows.net/aks-engine/aks-engine-master-linux-amd64.tar.gz '--test_args=--ginkgo.flakeAttempts=2 --num-nodes=2 --ginkgo.focus=azure-disk --ginkgo.skip=\[Slow\]|\[Serial\]|\[Disruptive\]|\[Flaky\]|\[Feature:.+\]' --timeout=420m
I1126 16:55:58.034] Bootstrap chewong-in-tree-azure-disk-e2e...
I1126 16:55:58.038] Builder: 862f3c7e-106d-11ea-918b-16773c0c7aed
I1126 16:55:58.038] Image: chewong/kubekins-e2e:4383e720281ef6701f5560a15d04fc7362552b00
I1126 16:55:58.038] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424
I1126 16:55:58.038] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1126 16:55:58.556] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1126 16:55:58.587] process 210 exited with code 0 after 0.0m
I1126 16:55:58.587] Call:  gcloud config get-value account
I1126 16:55:58.834] process 223 exited with code 0 after 0.0m
I1126 16:55:58.834] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1126 16:55:58.834] Root: /go/src
I1126 16:55:58.834] cd to /go/src
I1126 16:55:58.835] Checkout: /go/src/k8s.io/kubernetes master:1c5b6cb66e6ae85177e76d4fddf7d99473ab2aed,85621:dd76fdfce5554cc15ee7ed999bea1cffb0436d0a to /go/src/k8s.io/kubernetes
I1126 16:55:58.835] Call:  git init k8s.io/kubernetes
I1126 16:55:58.839] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1126 16:55:58.840] process 236 exited with code 0 after 0.0m
I1126 16:55:58.840] Call:  git config --local user.name 'K8S Bootstrap'
I1126 16:55:58.842] process 237 exited with code 0 after 0.0m
I1126 16:55:58.842] Call:  git config --local user.email k8s_bootstrap@localhost
I1126 16:55:58.845] process 238 exited with code 0 after 0.0m
I1126 16:55:58.845] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master +refs/pull/85621/head:refs/pr/85621
I1126 16:56:59.411] process 239 exited with code 0 after 1.0m
I1126 16:56:59.411] Call:  git checkout -B test 1c5b6cb66e6ae85177e76d4fddf7d99473ab2aed
W1126 16:57:01.549] Switched to a new branch 'test'
I1126 16:57:01.557] process 250 exited with code 0 after 0.0m
I1126 16:57:01.557] Call:  git show -s --format=format:%ct HEAD
I1126 16:57:01.581] process 251 exited with code 0 after 0.0m
I1126 16:57:01.581] Call:  git merge --no-ff -m 'Merge +refs/pull/85621/head:refs/pr/85621' dd76fdfce5554cc15ee7ed999bea1cffb0436d0a
I1126 16:57:02.354] Merge made by the 'recursive' strategy.
I1126 16:57:02.357]  test/e2e/storage/drivers/in_tree.go | 46 ++++++++++++++++++-------------------
I1126 16:57:02.357]  test/e2e/storage/in_tree_volumes.go |  2 +-
I1126 16:57:02.357]  2 files changed, 24 insertions(+), 24 deletions(-)
I1126 16:57:02.358] process 252 exited with code 0 after 0.0m
I1126 16:57:02.358] Configure environment...
I1126 16:57:02.358] Call:  git show -s --format=format:%ct HEAD
I1126 16:57:02.363] process 294 exited with code 0 after 0.0m
I1126 16:57:02.363] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1126 16:57:03.190] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1126 16:57:03.356] process 295 exited with code 0 after 0.0m
I1126 16:57:03.357] Call:  gcloud config get-value account
I1126 16:57:03.614] process 308 exited with code 0 after 0.0m
I1126 16:57:03.614] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1126 16:57:03.614] 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
'
I1126 16:57:03.858] process 321 exited with code 0 after 0.0m
I1126 16:57:03.858] Start 1199371161150951424 at v1.18.0-alpha.0.1190+cff90159704e47...
I1126 16:57:03.860] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_AgXLjC gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424/started.json
I1126 16:57:05.117] process 354 exited with code 0 after 0.0m
I1126 16:57:05.117] Call:  gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424' cp /tmp/gsutil_oqQePF gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/1199371161150951424.txt
I1126 16:57:06.280] process 535 exited with code 0 after 0.0m
I1126 16:57:06.281] Call:  /workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=quick --provider=azure --ginkgo-parallel=10 --acsengine-agentpoolcount=2 --acsengine-admin-username=azureuser --acsengine-creds=/etc/azure-cred/credentials --acsengine-orchestratorRelease=1.17 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --aksengine-custom-k8s-comopnents=True --acsengine-location=westus2 --acsengine-public-key=/etc/azure-ssh/azure-ssh-pub --acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes-vmss/kubernetes.json --acsengine-download-url=https://chuwontest.blob.core.windows.net/aks-engine/aks-engine-master-linux-amd64.tar.gz '--test_args=--ginkgo.flakeAttempts=2 --num-nodes=2 --ginkgo.focus=azure-disk --ginkgo.skip=\[Slow\]|\[Serial\]|\[Disruptive\]|\[Flaky\]|\[Feature:.+\]' --timeout=420m
W1126 16:57:06.317] starts with local mode
W1126 16:57:06.318] Environment:
W1126 16:57:06.318] ARTIFACTS=/workspace/_artifacts
W1126 16:57:06.318] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W1126 16:57:06.318] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W1126 16:57:06.318] AZURE_CREDENTIALS=/etc/azure-cred/credentials
W1126 16:57:06.318] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub
W1126 16:57:06.319] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer
W1126 16:57:06.319] BAZEL_REMOTE_CACHE_ENABLED=false
W1126 16:57:06.319] BAZEL_VERSION=0.23.2
W1126 16:57:06.319] BOOTSTRAP_MIGRATION=yes
W1126 16:57:06.319] BUILD_ID=1199371161150951424
W1126 16:57:06.319] BUILD_NUMBER=1199371161150951424
W1126 16:57:06.320] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W1126 16:57:06.320] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W1126 16:57:06.320] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W1126 16:57:06.320] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W1126 16:57:06.320] CLOUD_CONFIG=random
W1126 16:57:06.320] DECK_PORT=tcp://10.0.160.83:80
W1126 16:57:06.320] DECK_PORT_80_TCP=tcp://10.0.160.83:80
W1126 16:57:06.320] DECK_PORT_80_TCP_ADDR=10.0.160.83
W1126 16:57:06.321] DECK_PORT_80_TCP_PORT=80
W1126 16:57:06.321] DECK_PORT_80_TCP_PROTO=tcp
W1126 16:57:06.321] DECK_SERVICE_HOST=10.0.160.83
W1126 16:57:06.321] DECK_SERVICE_PORT=80
W1126 16:57:06.321] DOCKER_IN_DOCKER_ENABLED=true
W1126 16:57:06.321] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W1126 16:57:06.321] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424/artifacts
W1126 16:57:06.321] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1126 16:57:06.321] GOPATH=/go
W1126 16:57:06.322] GO_TARBALL=go1.12.12.linux-amd64.tar.gz
W1126 16:57:06.322] HOME=/workspace
W1126 16:57:06.322] HOOK_PORT=tcp://10.0.144.133:8888
W1126 16:57:06.322] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888
W1126 16:57:06.322] HOOK_PORT_8888_TCP_ADDR=10.0.144.133
W1126 16:57:06.322] HOOK_PORT_8888_TCP_PORT=8888
W1126 16:57:06.322] HOOK_PORT_8888_TCP_PROTO=tcp
W1126 16:57:06.322] HOOK_SERVICE_HOST=10.0.144.133
W1126 16:57:06.322] HOOK_SERVICE_PORT=8888
W1126 16:57:06.322] HOSTNAME=862f3c7e-106d-11ea-918b-16773c0c7aed
W1126 16:57:06.323] IMAGE=chewong/kubekins-e2e:4383e720281ef6701f5560a15d04fc7362552b00
W1126 16:57:06.323] INSTANCE_PREFIX=bootstrap-e2e
W1126 16:57:06.323] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine
W1126 16:57:06.323] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub
W1126 16:57:06.323] JOB_NAME=chewong-in-tree-azure-disk-e2e
W1126 16:57:06.323] JOB_SPEC={"type":"presubmit","job":"chewong-in-tree-azure-disk-e2e","buildid":"1199371161150951424","prowjobid":"862f3c7e-106d-11ea-918b-16773c0c7aed","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"e51caab73241b5c1598dfac5acbb2bde291a5e1f","base_link":"https://github.com/Azure/kubernetes/commit/e51caab73241b5c1598dfac5acbb2bde291a5e1f","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"}]}}
W1126 16:57:06.324] JOB_TYPE=presubmit
W1126 16:57:06.324] KUBERNETES_PORT=tcp://10.0.0.1:443
W1126 16:57:06.324] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443
W1126 16:57:06.324] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1
W1126 16:57:06.324] KUBERNETES_PORT_443_TCP_PORT=443
W1126 16:57:06.324] KUBERNETES_PORT_443_TCP_PROTO=tcp
W1126 16:57:06.324] KUBERNETES_SERVICE_HOST=10.0.0.1
W1126 16:57:06.324] KUBERNETES_SERVICE_PORT=443
W1126 16:57:06.324] KUBERNETES_SERVICE_PORT_HTTPS=443
W1126 16:57:06.324] KUBETEST_IN_DOCKER=true
W1126 16:57:06.325] KUBETEST_MANUAL_DUMP=y
W1126 16:57:06.325] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W1126 16:57:06.325] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W1126 16:57:06.325] KUBE_SSH_KEY_PATH=/etc/ssh-key-secret/ssh-private
W1126 16:57:06.325] KUBE_SSH_PUBLIC_KEY_PATH=/etc/ssh-key-secret/ssh-public
W1126 16:57:06.325] KUBE_SSH_USER=azureuser
W1126 16:57:06.325] KUBE_VERBOSE=0
W1126 16:57:06.325] NODE_NAME=862f3c7e-106d-11ea-918b-16773c0c7aed
W1126 16:57:06.325] 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
W1126 16:57:06.326] PROW_JOB_ID=862f3c7e-106d-11ea-918b-16773c0c7aed
W1126 16:57:06.326] PULL_BASE_REF=master
W1126 16:57:06.326] PULL_BASE_SHA=e51caab73241b5c1598dfac5acbb2bde291a5e1f
W1126 16:57:06.326] PULL_NUMBER=113
W1126 16:57:06.326] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822
W1126 16:57:06.326] PULL_REFS=master:e51caab73241b5c1598dfac5acbb2bde291a5e1f,113:879465972041635fd7bc8269da49153b67e63822
W1126 16:57:06.326] PWD=/workspace
W1126 16:57:06.326] REGISTRY=k8sprowinternal.azurecr.io
W1126 16:57:06.326] REPO_NAME=kubernetes
W1126 16:57:06.326] REPO_OWNER=Azure
W1126 16:57:06.327] SHLVL=2
W1126 16:57:06.327] SOURCE_DATE_EPOCH=1574729000
W1126 16:57:06.327] TERM=xterm
W1126 16:57:06.327] TIDE_PORT=tcp://10.0.180.53:80
W1126 16:57:06.327] TIDE_PORT_80_TCP=tcp://10.0.180.53:80
W1126 16:57:06.327] TIDE_PORT_80_TCP_ADDR=10.0.180.53
W1126 16:57:06.327] TIDE_PORT_80_TCP_PORT=80
W1126 16:57:06.327] TIDE_PORT_80_TCP_PROTO=tcp
W1126 16:57:06.327] TIDE_SERVICE_HOST=10.0.180.53
W1126 16:57:06.327] TIDE_SERVICE_PORT=80
W1126 16:57:06.328] WORKSPACE=/workspace
W1126 16:57:06.328] _=./test-infra/jenkins/bootstrap.py
W1126 16:57:06.328] Run: ('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=quick', '--up', '--down', '--test', '--deployment=acsengine', '--provider=azure', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--ginkgo-parallel=10', '--acsengine-agentpoolcount=2', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.17', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--aksengine-custom-k8s-comopnents=True', '--acsengine-location=westus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes-vmss/kubernetes.json', '--acsengine-download-url=https://chuwontest.blob.core.windows.net/aks-engine/aks-engine-master-linux-amd64.tar.gz', '--test_args=--ginkgo.flakeAttempts=2 --num-nodes=2 --ginkgo.focus=azure-disk --ginkgo.skip=\\[Slow\\]|\\[Serial\\]|\\[Disruptive\\]|\\[Flaky\\]|\\[Feature:.+\\]', '--timeout=420m')
W1126 16:57:06.473] 2019/11/26 16:57:06 main.go:332: Limiting testing to 7h0m0s
W1126 16:57:06.474] 2019/11/26 16:57:06 azure.go:256: Reading credentials file /etc/azure-cred/credentials
W1126 16:57:06.475] 2019/11/26 16:57:06 azure.go:735: CWD /go/src/k8s.io/kubernetes
W1126 16:57:06.475] 2019/11/26 16:57:06 azure.go:754: Attempting Docker login with azure cred.
W1126 16:57:07.371] 2019/11/26 16:57:07 azure.go:763: Docker login success.
W1126 16:57:07.372] 2019/11/26 16:57:07 process.go:153: Running: make -C /go/src/k8s.io/kubernetes quick-release
I1126 16:57:07.472] make: Entering directory '/go/src/k8s.io/kubernetes'
I1126 16:57:07.473] +++ [1126 16:57:07] Verifying Prerequisites....
I1126 16:57:08.118] +++ [1126 16:57:08] Building Docker image kube-build:build-3841feae9e-5-v1.13.4-1
I1126 16:59:53.123] +++ [1126 16:59:53] Creating data container kube-build-data-3841feae9e-5-v1.13.4-1
I1126 17:00:26.986] +++ [1126 17:00:26] Syncing sources to container
I1126 17:00:34.467] +++ [1126 17:00:34] Running build command...
I1126 17:00:42.745] +++ [1126 17:00:42] Building go targets for linux/amd64:
I1126 17:00:42.745]     ./vendor/k8s.io/code-generator/cmd/deepcopy-gen
I1126 17:00:49.763] +++ [1126 17:00:49] Building go targets for linux/amd64:
I1126 17:00:49.763]     ./vendor/k8s.io/code-generator/cmd/defaulter-gen
I1126 17:00:55.478] +++ [1126 17:00:55] Building go targets for linux/amd64:
I1126 17:00:55.478]     ./vendor/k8s.io/code-generator/cmd/conversion-gen
I1126 17:01:05.054] +++ [1126 17:01:05] Building go targets for linux/amd64:
I1126 17:01:05.054]     ./vendor/k8s.io/kube-openapi/cmd/openapi-gen
I1126 17:01:15.818] +++ [1126 17:01:15] Building go targets for linux/amd64:
I1126 17:01:15.819]     ./vendor/github.com/go-bindata/go-bindata/go-bindata
I1126 17:01:17.499] +++ [1126 17:01:17] Building go targets for linux/amd64:
I1126 17:01:17.499]     cmd/kube-proxy
I1126 17:01:17.499]     cmd/kube-apiserver
I1126 17:01:17.499]     cmd/kube-controller-manager
I1126 17:01:17.499]     cmd/kubelet
I1126 17:01:17.499]     cmd/kubeadm
I1126 17:01:17.500]     cmd/kube-scheduler
I1126 17:01:17.500]     vendor/k8s.io/apiextensions-apiserver
I1126 17:01:17.500]     cluster/gce/gci/mounter
I1126 17:04:17.272] +++ [1126 17:04:17] Building go targets for linux/amd64:
I1126 17:04:17.272]     cmd/kube-proxy
I1126 17:04:17.273]     cmd/kubeadm
I1126 17:04:17.273]     cmd/kubelet
I1126 17:04:25.687] +++ [1126 17:04:25] Building go targets for linux/amd64:
I1126 17:04:25.688]     cmd/kubectl
I1126 17:04:50.211] +++ [1126 17:04:50] Building go targets for linux/amd64:
I1126 17:04:50.212]     cmd/gendocs
I1126 17:04:50.212]     cmd/genkubedocs
I1126 17:04:50.212]     cmd/genman
I1126 17:04:50.212]     cmd/genyaml
I1126 17:04:50.212]     cmd/genswaggertypedocs
I1126 17:04:50.212]     cmd/linkcheck
I1126 17:04:50.212]     vendor/github.com/onsi/ginkgo/ginkgo
I1126 17:04:50.212]     test/e2e/e2e.test
I1126 17:04:50.212]     cluster/images/conformance/go-runner
I1126 17:06:55.281] +++ [1126 17:06:55] Building go targets for linux/amd64:
I1126 17:06:55.281]     cmd/kubemark
I1126 17:06:55.281]     vendor/github.com/onsi/ginkgo/ginkgo
I1126 17:06:55.281]     test/e2e_node/e2e_node.test
I1126 17:08:10.321] +++ [1126 17:08:10] Syncing out of container
I1126 17:08:42.977] +++ [1126 17:08:42] Building tarball: manifests
I1126 17:08:42.977] +++ [1126 17:08:42] Building tarball: src
I1126 17:08:42.980] +++ [1126 17:08:42] Starting tarball: client linux-amd64
I1126 17:08:42.987] +++ [1126 17:08:42] Waiting on tarballs
I1126 17:08:49.195] +++ [1126 17:08:49] Building tarball: node linux-amd64
I1126 17:08:49.197] +++ [1126 17:08:49] Building images: linux-amd64
I1126 17:08:49.554] +++ [1126 17:08:49] Starting docker build for image: kube-apiserver-amd64
I1126 17:08:49.559] +++ [1126 17:08:49] Starting docker build for image: kube-controller-manager-amd64
I1126 17:08:49.564] +++ [1126 17:08:49] Starting docker build for image: kube-scheduler-amd64
I1126 17:08:49.567] +++ [1126 17:08:49] Starting docker build for image: kube-proxy-amd64
I1126 17:08:49.571] +++ [1126 17:08:49] Building hyperkube image for arch: amd64
I1126 17:08:49.572] +++ [1126 17:08:49] Building conformance image for arch: amd64
I1126 17:09:21.947] +++ [1126 17:09:21] Tagging docker image k8s.gcr.io/kube-scheduler-amd64:v1.18.0-alpha.0.1190_cff90159704e47 as k8sprowinternal.azurecr.io/kube-scheduler-amd64:azure-e2e-1199371161150951424
I1126 17:09:22.388] +++ [1126 17:09:22] Tagging docker image k8s.gcr.io/kube-controller-manager-amd64:v1.18.0-alpha.0.1190_cff90159704e47 as k8sprowinternal.azurecr.io/kube-controller-manager-amd64:azure-e2e-1199371161150951424
I1126 17:09:22.390] +++ [1126 17:09:22] Tagging docker image k8s.gcr.io/kube-apiserver-amd64:v1.18.0-alpha.0.1190_cff90159704e47 as k8sprowinternal.azurecr.io/kube-apiserver-amd64:azure-e2e-1199371161150951424
I1126 17:09:34.490] +++ [1126 17:09:34] Deleting docker image k8s.gcr.io/kube-scheduler-amd64:v1.18.0-alpha.0.1190_cff90159704e47
I1126 17:09:34.640] +++ [1126 17:09:34] Deleting docker image k8s.gcr.io/kube-apiserver-amd64:v1.18.0-alpha.0.1190_cff90159704e47
I1126 17:09:34.837] +++ [1126 17:09:34] Deleting docker image k8s.gcr.io/kube-controller-manager-amd64:v1.18.0-alpha.0.1190_cff90159704e47
I1126 17:09:40.052] +++ [1126 17:09:40] Tagging docker image k8s.gcr.io/kube-proxy-amd64:v1.18.0-alpha.0.1190_cff90159704e47 as k8sprowinternal.azurecr.io/kube-proxy-amd64:azure-e2e-1199371161150951424
I1126 17:09:47.308] +++ [1126 17:09:47] Deleting docker image k8s.gcr.io/kube-proxy-amd64:v1.18.0-alpha.0.1190_cff90159704e47
I1126 17:10:15.297] +++ [1126 17:10:15] Deleting hyperkube image k8s.gcr.io/hyperkube-amd64:v1.18.0-alpha.0.1190_cff90159704e47
I1126 17:10:46.898] +++ [1126 17:10:46] Deleting conformance image k8s.gcr.io/conformance-amd64:v1.18.0-alpha.0.1190_cff90159704e47
I1126 17:10:47.109] +++ [1126 17:10:47] Docker builds done
I1126 17:10:47.113] +++ [1126 17:10:47] Building tarball: server linux-amd64
I1126 17:11:57.910] +++ [1126 17:11:57] Building tarball: final
I1126 17:11:58.191] +++ [1126 17:11:58] Waiting on test tarballs
I1126 17:11:58.191] +++ [1126 17:11:58] Starting tarball: test linux-amd64
I1126 17:12:47.488] +++ [1126 17:12:47] Building tarball: test portable
I1126 17:12:47.567] make: Leaving directory '/go/src/k8s.io/kubernetes'
W1126 17:12:47.667] 2019/11/26 17:12:47 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes quick-release' finished in 15m40.195004985s
W1126 17:12:47.667] 2019/11/26 17:12:47 azure.go:768: Pushing docker image k8sprowinternal.azurecr.io/kube-apiserver-amd64:azure-e2e-1199371161150951424
W1126 17:12:58.259] 2019/11/26 17:12:58 azure.go:768: Pushing docker image k8sprowinternal.azurecr.io/kube-controller-manager-amd64:azure-e2e-1199371161150951424
W1126 17:13:07.049] 2019/11/26 17:13:07 azure.go:768: Pushing docker image k8sprowinternal.azurecr.io/kube-proxy-amd64:azure-e2e-1199371161150951424
W1126 17:13:11.500] 2019/11/26 17:13:11 azure.go:768: Pushing docker image k8sprowinternal.azurecr.io/kube-scheduler-amd64:azure-e2e-1199371161150951424
W1126 17:13:16.143] 2019/11/26 17:13:16 azure.go:1035: Renaming /go/src/k8s.io/kubernetes/_output/release-tars/kubernetes-node-linux-amd64.tar.gz to /go/src/k8s.io/kubernetes/_output/release-tars/kubernetes-node-linux-amd64-1199371161150951424.tar.gz
W1126 17:13:18.956] 2019/11/26 17:13:18 azure.go:862: Uploaded /go/src/k8s.io/kubernetes/_output/release-tars/kubernetes-node-linux-amd64-1199371161150951424.tar.gz to https://upstreamprowstorageacct.blob.core.windows.net/mystoragecontainer/kubernetes-node-linux-amd64-1199371161150951424.tar.gz
W1126 17:13:18.956] 2019/11/26 17:13:18 util.go:265: Flushing memory.
W1126 17:13:21.757] 2019/11/26 17:13:21 azure.go:1047: Deleting resource group: kubetest-c705a8b3-106d-11ea-87cd-0242ff8797d4.
W1126 17:13:22.003] 2019/11/26 17:13:22 azure.go:875: downloading /workspace/aks459240767/kubernetes.json from https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes-vmss/kubernetes.json.
W1126 17:13:22.004] 2019/11/26 17:13:22 util.go:42: curl https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes-vmss/kubernetes.json
W1126 17:13:22.398] 2019/11/26 17:13:22 azure.go:1047: Deleting resource group: kubetest-c705a8b3-106d-11ea-87cd-0242ff8797d4.
W1126 17:13:22.411] 2019/11/26 17:13:22 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1126 17:13:22.411] 2019/11/26 17:13:22 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W1126 17:13:23.731] 2019/11/26 17:13:23 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.320856146s
W1126 17:13:23.734] panic: runtime error: invalid memory address or nil pointer dereference
W1126 17:13:23.734] [signal SIGSEGV: segmentation violation code=0x1 addr=0x8 pc=0x157e21d]
W1126 17:13:23.734] 
W1126 17:13:23.734] goroutine 1 [running]:
W1126 17:13:23.734] main.(*Cluster).populateApiModelTemplate(0xc00000a960, 0x62, 0xc0000d6750)
W1126 17:13:23.735] 	/Users/ernestwong/go/src/k8s.io/test-infra/kubetest/azure.go:530 +0x53d
W1126 17:13:23.735] main.(*Cluster).Up(0xc00000a960, 0xc0001a3395, 0x3fccd2420819cd)
W1126 17:13:23.735] 	/Users/ernestwong/go/src/k8s.io/test-infra/kubetest/azure.go:966 +0x7b
W1126 17:13:23.735] k8s.io/test-infra/kubetest/process.(*Control).XMLWrap(0xc0000e3880, 0x29f8260, 0x1a145b5, 0x2, 0xc0003c5450, 0x0, 0x0)
W1126 17:13:23.735] 	/Users/ernestwong/go/src/k8s.io/test-infra/kubetest/process/process.go:103 +0x7b
W1126 17:13:23.735] main.run(0x1d89520, 0xc00000a960, 0x7ffdad17bd46, 0x5, 0x10000, 0x7ffdad17bd91, 0xd, 0x0, 0x0, 0x7ffdad17bd6c, ...)
W1126 17:13:23.735] 	/Users/ernestwong/go/src/k8s.io/test-infra/kubetest/e2e.go:115 +0x491
W1126 17:13:23.735] main.complete(0xc0000a2700, 0x0, 0x0)
W1126 17:13:23.736] 	/Users/ernestwong/go/src/k8s.io/test-infra/kubetest/main.go:401 +0x461
W1126 17:13:23.736] main.main()
W1126 17:13:23.736] 	/Users/ernestwong/go/src/k8s.io/test-infra/kubetest/main.go:310 +0x3ef
W1126 17:13:23.736] Traceback (most recent call last):
W1126 17:13:23.736]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W1126 17:13:23.737]     main(parse_args())
W1126 17:13:23.737]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W1126 17:13:23.737]     mode.start(runner_args)
W1126 17:13:23.737]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W1126 17:13:23.737]     check_env(env, self.command, *args)
W1126 17:13:23.737]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1126 17:13:23.738]     subprocess.check_call(cmd, env=env)
W1126 17:13:23.738]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1126 17:13:23.738]     raise CalledProcessError(retcode, cmd)
W1126 17:13:23.739] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=quick', '--up', '--down', '--test', '--deployment=acsengine', '--provider=azure', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--ginkgo-parallel=10', '--acsengine-agentpoolcount=2', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.17', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--aksengine-custom-k8s-comopnents=True', '--acsengine-location=westus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes-vmss/kubernetes.json', '--acsengine-download-url=https://chuwontest.blob.core.windows.net/aks-engine/aks-engine-master-linux-amd64.tar.gz', '--test_args=--ginkgo.flakeAttempts=2 --num-nodes=2 --ginkgo.focus=azure-disk --ginkgo.skip=\\[Slow\\]|\\[Serial\\]|\\[Disruptive\\]|\\[Flaky\\]|\\[Feature:.+\\]', '--timeout=420m')' returned non-zero exit status 2
E1126 17:13:23.746] Command failed
I1126 17:13:23.746] process 716 exited with code 1 after 16.3m
E1126 17:13:23.747] FAIL: chewong-in-tree-azure-disk-e2e
I1126 17:13:23.747] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1126 17:13:24.669] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1126 17:13:24.704] process 24160 exited with code 0 after 0.0m
I1126 17:13:24.704] Call:  gcloud config get-value account
I1126 17:13:24.954] process 24173 exited with code 0 after 0.0m
I1126 17:13:24.954] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1126 17:13:24.954] Upload result and artifacts...
I1126 17:13:24.954] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424
I1126 17:13:24.955] Call:  gsutil ls gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424/artifacts
W1126 17:13:26.058] CommandException: One or more URLs matched no objects.
E1126 17:13:26.131] Command failed
I1126 17:13:26.131] process 24186 exited with code 1 after 0.0m
W1126 17:13:26.131] Remote dir gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424/artifacts not exist yet
I1126 17:13:26.131] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424/artifacts
I1126 17:13:27.720] process 24331 exited with code 0 after 0.0m
I1126 17:13:27.721] Call:  git rev-parse HEAD
I1126 17:13:27.723] process 24858 exited with code 0 after 0.0m
I1126 17:13:27.724] Call:  git rev-parse HEAD
I1126 17:13:27.729] process 24859 exited with code 0 after 0.0m
I1126 17:13:27.729] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 17:13:28.511] process 24860 exited with code 0 after 0.0m
I1126 17:13:28.511] Call:  gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/jobResultsCache.json#1574732801071964'
I1126 17:13:29.555] process 25005 exited with code 0 after 0.0m
I1126 17:13:29.556] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1574732801071964 cp /tmp/gsutil_eNEJOA gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 17:13:30.637] process 25152 exited with code 0 after 0.0m
I1126 17:13:30.637] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 17:13:31.416] process 25333 exited with code 0 after 0.0m
I1126 17:13:31.416] Call:  gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/jobResultsCache.json#1574732804141505'
I1126 17:13:32.524] process 25478 exited with code 0 after 0.0m
I1126 17:13:32.525] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1574732804141505 cp /tmp/gsutil_qN3BK4 gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 17:13:33.851] process 25625 exited with code 0 after 0.0m
I1126 17:13:33.852] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil__Ydi8R gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199371161150951424/finished.json
I1126 17:13:34.976] process 25806 exited with code 0 after 0.0m
I1126 17:13:34.977] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_SpkJQ_ gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/latest-build.txt
I1126 17:13:36.263] process 25987 exited with code 0 after 0.0m
I1126 17:13:36.264] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_yvKUf7 gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/latest-build.txt
I1126 17:13:37.550] process 26168 exited with code 0 after 0.0m