W1126 00:55:00.905] **************************************************************************
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 00:55:00.905] 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=bazel --provider=azure --ginkgo-parallel=10 --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-hyperkube=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://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-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 00:55:00.905] Bootstrap chewong-in-tree-azure-disk-e2e...
I1126 00:55:00.909] Builder: 5374b5e9-0fe7-11ea-b3d3-b20db476995a
I1126 00:55:00.910] Image: gcr.io/k8s-testimages/kubekins-e2e:v20191115-01e3204-master
I1126 00:55:00.910] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904
I1126 00:55:00.910] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1126 00:55:01.394] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1126 00:55:01.422] process 214 exited with code 0 after 0.0m
I1126 00:55:01.422] Call:  gcloud config get-value account
I1126 00:55:01.636] process 227 exited with code 0 after 0.0m
I1126 00:55:01.637] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1126 00:55:01.637] Root: /go/src
I1126 00:55:01.637] cd to /go/src
I1126 00:55:01.637] Checkout: /go/src/k8s.io/kubernetes master:1c5b6cb66e6ae85177e76d4fddf7d99473ab2aed,85621:dd76fdfce5554cc15ee7ed999bea1cffb0436d0a to /go/src/k8s.io/kubernetes
I1126 00:55:01.637] Call:  git init k8s.io/kubernetes
I1126 00:55:01.641] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1126 00:55:01.641] process 240 exited with code 0 after 0.0m
I1126 00:55:01.641] Call:  git config --local user.name 'K8S Bootstrap'
I1126 00:55:01.643] process 241 exited with code 0 after 0.0m
I1126 00:55:01.644] Call:  git config --local user.email k8s_bootstrap@localhost
I1126 00:55:01.646] process 242 exited with code 0 after 0.0m
I1126 00:55:01.646] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master +refs/pull/85621/head:refs/pr/85621
I1126 00:55:58.273] process 243 exited with code 0 after 0.9m
I1126 00:55:58.273] Call:  git checkout -B test 1c5b6cb66e6ae85177e76d4fddf7d99473ab2aed
W1126 00:56:00.265] Switched to a new branch 'test'
I1126 00:56:00.275] process 254 exited with code 0 after 0.0m
I1126 00:56:00.275] Call:  git show -s --format=format:%ct HEAD
I1126 00:56:00.302] process 255 exited with code 0 after 0.0m
I1126 00:56:00.302] Call:  git merge --no-ff -m 'Merge +refs/pull/85621/head:refs/pr/85621' dd76fdfce5554cc15ee7ed999bea1cffb0436d0a
I1126 00:56:00.942] Merge made by the 'recursive' strategy.
I1126 00:56:00.945]  test/e2e/storage/drivers/in_tree.go | 46 ++++++++++++++++++-------------------
I1126 00:56:00.945]  test/e2e/storage/in_tree_volumes.go |  2 +-
I1126 00:56:00.945]  2 files changed, 24 insertions(+), 24 deletions(-)
I1126 00:56:00.945] process 256 exited with code 0 after 0.0m
I1126 00:56:00.946] Configure environment...
I1126 00:56:00.946] Call:  git show -s --format=format:%ct HEAD
I1126 00:56:00.950] process 298 exited with code 0 after 0.0m
I1126 00:56:00.950] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1126 00:56:01.621] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1126 00:56:01.739] process 299 exited with code 0 after 0.0m
I1126 00:56:01.739] Call:  gcloud config get-value account
I1126 00:56:01.979] process 312 exited with code 0 after 0.0m
I1126 00:56:01.979] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1126 00:56:01.979] 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 00:56:02.386] process 325 exited with code 0 after 0.0m
I1126 00:56:02.387] Start 1199129317561339904 at v1.18.0-alpha.0.1190+cff90159704e47...
I1126 00:56:02.389] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil__aNI0d gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904/started.json
I1126 00:56:03.809] process 358 exited with code 0 after 0.0m
I1126 00:56:03.810] 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/1199129317561339904' cp /tmp/gsutil_YVdYQg gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/1199129317561339904.txt
I1126 00:56:04.921] process 539 exited with code 0 after 0.0m
I1126 00:56:04.922] Call:  /workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=bazel --provider=azure --ginkgo-parallel=10 --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-hyperkube=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://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-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 00:56:04.955] starts with local mode
W1126 00:56:04.955] Environment:
W1126 00:56:04.955] ARTIFACTS=/workspace/_artifacts
W1126 00:56:04.955] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W1126 00:56:04.956] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W1126 00:56:04.956] AZURE_CREDENTIALS=/etc/azure-cred/credentials
W1126 00:56:04.956] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub
W1126 00:56:04.956] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer
W1126 00:56:04.956] BAZEL_REMOTE_CACHE_ENABLED=false
W1126 00:56:04.956] BAZEL_VERSION=0.23.2
W1126 00:56:04.956] BOOTSTRAP_MIGRATION=yes
W1126 00:56:04.956] BUILD_ID=1199129317561339904
W1126 00:56:04.956] BUILD_NUMBER=1199129317561339904
W1126 00:56:04.957] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W1126 00:56:04.957] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W1126 00:56:04.957] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W1126 00:56:04.957] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W1126 00:56:04.957] CLOUD_CONFIG=random
W1126 00:56:04.957] DECK_PORT=tcp://10.0.160.83:80
W1126 00:56:04.957] DECK_PORT_80_TCP=tcp://10.0.160.83:80
W1126 00:56:04.957] DECK_PORT_80_TCP_ADDR=10.0.160.83
W1126 00:56:04.958] DECK_PORT_80_TCP_PORT=80
W1126 00:56:04.958] DECK_PORT_80_TCP_PROTO=tcp
W1126 00:56:04.958] DECK_SERVICE_HOST=10.0.160.83
W1126 00:56:04.958] DECK_SERVICE_PORT=80
W1126 00:56:04.958] DOCKER_IN_DOCKER_ENABLED=true
W1126 00:56:04.958] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W1126 00:56:04.958] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904/artifacts
W1126 00:56:04.958] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W1126 00:56:04.958] GOPATH=/go
W1126 00:56:04.959] GO_TARBALL=go1.12.12.linux-amd64.tar.gz
W1126 00:56:04.959] HOME=/workspace
W1126 00:56:04.959] HOOK_PORT=tcp://10.0.144.133:8888
W1126 00:56:04.959] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888
W1126 00:56:04.959] HOOK_PORT_8888_TCP_ADDR=10.0.144.133
W1126 00:56:04.959] HOOK_PORT_8888_TCP_PORT=8888
W1126 00:56:04.959] HOOK_PORT_8888_TCP_PROTO=tcp
W1126 00:56:04.959] HOOK_SERVICE_HOST=10.0.144.133
W1126 00:56:04.960] HOOK_SERVICE_PORT=8888
W1126 00:56:04.960] HOSTNAME=5374b5e9-0fe7-11ea-b3d3-b20db476995a
W1126 00:56:04.960] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20191115-01e3204-master
W1126 00:56:04.960] INSTANCE_PREFIX=bootstrap-e2e
W1126 00:56:04.960] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine
W1126 00:56:04.960] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub
W1126 00:56:04.960] JOB_NAME=chewong-in-tree-azure-disk-e2e
W1126 00:56:04.961] JOB_SPEC={"type":"presubmit","job":"chewong-in-tree-azure-disk-e2e","buildid":"1199129317561339904","prowjobid":"5374b5e9-0fe7-11ea-b3d3-b20db476995a","refs":{"org":"Azure","repo":"kubernetes","repo_link":"https://github.com/Azure/kubernetes","base_ref":"master","base_sha":"a3a2c4230d8cea0d192fb15835c47da3604bd95e","base_link":"https://github.com/Azure/kubernetes/commit/a3a2c4230d8cea0d192fb15835c47da3604bd95e","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 00:56:04.961] JOB_TYPE=presubmit
W1126 00:56:04.961] KUBERNETES_PORT=tcp://10.0.0.1:443
W1126 00:56:04.961] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443
W1126 00:56:04.961] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1
W1126 00:56:04.961] KUBERNETES_PORT_443_TCP_PORT=443
W1126 00:56:04.961] KUBERNETES_PORT_443_TCP_PROTO=tcp
W1126 00:56:04.962] KUBERNETES_SERVICE_HOST=10.0.0.1
W1126 00:56:04.962] KUBERNETES_SERVICE_PORT=443
W1126 00:56:04.962] KUBERNETES_SERVICE_PORT_HTTPS=443
W1126 00:56:04.962] KUBETEST_IN_DOCKER=true
W1126 00:56:04.962] KUBETEST_MANUAL_DUMP=y
W1126 00:56:04.962] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W1126 00:56:04.962] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W1126 00:56:04.962] KUBE_SSH_KEY_PATH=/etc/ssh-key-secret/ssh-private
W1126 00:56:04.963] KUBE_SSH_PUBLIC_KEY_PATH=/etc/ssh-key-secret/ssh-public
W1126 00:56:04.963] KUBE_SSH_USER=azureuser
W1126 00:56:04.963] KUBE_VERBOSE=0
W1126 00:56:04.963] NODE_NAME=5374b5e9-0fe7-11ea-b3d3-b20db476995a
W1126 00:56:04.963] 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 00:56:04.963] PROW_JOB_ID=5374b5e9-0fe7-11ea-b3d3-b20db476995a
W1126 00:56:04.963] PULL_BASE_REF=master
W1126 00:56:04.963] PULL_BASE_SHA=a3a2c4230d8cea0d192fb15835c47da3604bd95e
W1126 00:56:04.964] PULL_NUMBER=113
W1126 00:56:04.964] PULL_PULL_SHA=879465972041635fd7bc8269da49153b67e63822
W1126 00:56:04.964] PULL_REFS=master:a3a2c4230d8cea0d192fb15835c47da3604bd95e,113:879465972041635fd7bc8269da49153b67e63822
W1126 00:56:04.964] PWD=/workspace
W1126 00:56:04.964] REGISTRY=k8sprowinternal.azurecr.io
W1126 00:56:04.964] REPO_NAME=kubernetes
W1126 00:56:04.964] REPO_OWNER=Azure
W1126 00:56:04.964] SHLVL=2
W1126 00:56:04.964] SOURCE_DATE_EPOCH=1574729000
W1126 00:56:04.965] TERM=xterm
W1126 00:56:04.965] TIDE_PORT=tcp://10.0.180.53:80
W1126 00:56:04.965] TIDE_PORT_80_TCP=tcp://10.0.180.53:80
W1126 00:56:04.965] TIDE_PORT_80_TCP_ADDR=10.0.180.53
W1126 00:56:04.965] TIDE_PORT_80_TCP_PORT=80
W1126 00:56:04.965] TIDE_PORT_80_TCP_PROTO=tcp
W1126 00:56:04.965] TIDE_SERVICE_HOST=10.0.180.53
W1126 00:56:04.965] TIDE_SERVICE_PORT=80
W1126 00:56:04.965] WORKSPACE=/workspace
W1126 00:56:04.966] _=./test-infra/jenkins/bootstrap.py
W1126 00:56:04.966] Run: ('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=bazel', '--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.16', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-hyperkube=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://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-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 00:56:06.018] 2019/11/26 00:56:06 main.go:332: Limiting testing to 7h0m0s
W1126 00:56:06.024] 2019/11/26 00:56:06 azure.go:232: Reading credentials file /etc/azure-cred/credentials
W1126 00:56:06.035] 2019/11/26 00:56:06 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release
I1126 00:56:06.136] make: Entering directory '/go/src/k8s.io/kubernetes'
W1126 00:56:06.462] Extracting Bazel installation...
W1126 00:56:17.425] Starting local Bazel server and connecting to it...
W1126 00:56:18.992] Loading: 
W1126 00:56:18.995] Loading: 0 packages loaded
W1126 00:56:19.999] Loading: 0 packages loaded
W1126 00:56:21.628] Loading: 0 packages loaded
W1126 00:56:23.000] Loading: 0 packages loaded
W1126 00:56:24.986] Loading: 0 packages loaded
W1126 00:56:26.000] Loading: 0 packages loaded
W1126 00:56:26.000]     currently loading: build/release-tars
W1126 00:56:28.200] Loading: 0 packages loaded
W1126 00:56:28.201]     currently loading: build/release-tars
W1126 00:56:28.534] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured)
W1126 00:56:29.988] Analyzing: target //build/release-tars:release-tars (203 packages loaded, 1072 targets configured)
W1126 00:56:31.662] Analyzing: target //build/release-tars:release-tars (1133 packages loaded, 4353 targets configured)
W1126 00:56:33.586] Analyzing: target //build/release-tars:release-tars (1736 packages loaded, 12204 targets configured)
W1126 00:56:36.561] Analyzing: target //build/release-tars:release-tars (2450 packages loaded, 21758 targets configured)
W1126 00:56:39.783] Analyzing: target //build/release-tars:release-tars (3093 packages loaded, 27372 targets configured)
W1126 00:56:43.081] Analyzing: target //build/release-tars:release-tars (3132 packages loaded, 34168 targets configured)
W1126 00:56:46.714] Analyzing: target //build/release-tars:release-tars (3139 packages loaded, 34842 targets configured)
W1126 00:56:55.412] INFO: Analysed target //build/release-tars:release-tars (3139 packages loaded, 36905 targets configured).
W1126 00:56:55.414] Building: checking cached actions
W1126 00:56:55.416] INFO: Found 1 target...
W1126 00:56:55.585] [0 / 25] [-----] Writing file build/release-tars/kubernetes-manifests.args
W1126 00:57:01.277] [103 / 4,492] GoCompilePkg vendor/github.com/emicklei/go-restful/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/emicklei/go-restful.a [for host]; 0s linux-sandbox ... (8 actions, 7 running)
W1126 00:57:10.114] [122 / 4,492] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 9s linux-sandbox ... (7 actions, 6 running)
W1126 00:57:18.809] [161 / 4,492] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 18s linux-sandbox ... (8 actions, 7 running)
W1126 00:57:28.527] [205 / 4,492] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 27s linux-sandbox ... (8 actions, 7 running)
W1126 00:57:39.008] [275 / 4,492] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 38s linux-sandbox ... (8 actions, 7 running)
W1126 00:57:51.331] [339 / 4,492] GoCompilePkg vendor/gonum.org/v1/gonum/blas/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/gonum.org/v1/gonum/blas.a; 2s linux-sandbox ... (8 actions, 6 running)
W1126 00:58:05.336] [458 / 4,492] GoCompilePkg staging/src/k8s.io/apiserver/pkg/storage/names/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apiserver/pkg/storage/names.a; 0s linux-sandbox ... (8 actions, 7 running)
W1126 00:58:24.045] [560 / 4,492] GoCompilePkg 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)
W1126 00:58:43.334] [743 / 4,492] GoCompilePkg vendor/github.com/modern-go/reflect2/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/modern-go/reflect2.a; 1s linux-sandbox ... (8 actions running)
W1126 00:59:06.360] [799 / 4,492] GoCompilePkg vendor/github.com/emicklei/go-restful/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/emicklei/go-restful.a; 7s linux-sandbox ... (8 actions, 7 running)
W1126 00:59:32.453] [902 / 4,492] GoCompilePkg vendor/go.etcd.io/etcd/raft/raftpb/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/go.etcd.io/etcd/raft/raftpb.a; 4s linux-sandbox ... (8 actions, 7 running)
W1126 01:00:01.498] [1,130 / 4,492] GoCompilePkg vendor/github.com/vmware/govmomi/vim25/types/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vmware/govmomi/vim25/types.a; 12s linux-sandbox ... (8 actions, 7 running)
W1126 01:00:35.422] [1,400 / 4,492] GoCompilePkg vendor/github.com/aws/aws-sdk-go/service/autoscaling/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/aws/aws-sdk-go/service/autoscaling.a; 1s linux-sandbox ... (8 actions, 7 running)
W1126 01:01:14.516] [1,654 / 4,492] GoCompilePkg vendor/github.com/GoogleCloudPlatform/k8s-cloud-provider/pkg/cloud/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/GoogleCloudPlatform/k8s-cloud-provider/pkg/cloud.a; 2s linux-sandbox ... (8 actions, 7 running)
W1126 01:01:59.511] [1,845 / 4,492]  <rule context for //staging/src/k8s.io/apiextensions-apiserver/pkg/generated/openapi:zz_generated.openapi>; 8s linux-sandbox ... (8 actions, 7 running)
W1126 01:02:50.623] [2,002 / 4,492] PackageTar build/release-tars/kubernetes-src.tar.gz; 16s linux-sandbox ... (8 actions, 7 running)
W1126 01:03:49.396] [2,371 / 4,492] GoCompilePkg staging/src/k8s.io/kube-aggregator/pkg/registry/apiservice/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/kube-aggregator/pkg/registry/apiservice.a; 2s linux-sandbox ... (8 actions, 7 running)
W1126 01:04:57.315] [2,759 / 4,492] GoCompilePkg pkg/apis/apps/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/apps.a; 3s linux-sandbox ... (8 actions, 7 running)
W1126 01:06:16.394] [3,049 / 4,492] GoLink cmd/kube-proxy/linux_amd64_pure_stripped/kube-proxy; 20s linux-sandbox ... (8 actions, 7 running)
W1126 01:07:47.434] [3,457 / 4,492] GoCompilePkg staging/src/k8s.io/apiextensions-apiserver/pkg/apiserver/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apiextensions-apiserver/pkg/apiserver.a; 5s linux-sandbox ... (8 actions, 7 running)
W1126 01:09:33.529] [3,831 / 4,492] GoCompilePkg staging/src/k8s.io/client-go/informers/batch/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/informers/batch.a; 5s linux-sandbox ... (8 actions, 7 running)
W1126 01:11:35.086] [4,263 / 4,492] GoLink cmd/kubelet/kubelet; 30s linux-sandbox ... (8 actions, 7 running)
W1126 01:14:08.358] [4,484 / 4,492] GoLink cmd/genkubedocs/genkubedocs; 74s linux-sandbox ... (4 actions running)
W1126 01:15:45.623] Target //build/release-tars:release-tars up-to-date:
W1126 01:15:45.624]   bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz
W1126 01:15:45.624]   bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz
W1126 01:15:45.625]   bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz
W1126 01:15:45.625]   bazel-bin/build/release-tars/kubernetes-manifests.tar.gz
W1126 01:15:45.625]   bazel-bin/build/release-tars/kubernetes-test-portable.tar.gz
W1126 01:15:45.625]   bazel-bin/build/release-tars/kubernetes-test-linux-amd64.tar.gz
W1126 01:15:45.625]   bazel-bin/build/release-tars/kubernetes.tar.gz
W1126 01:15:45.626]   bazel-bin/build/release-tars/kubernetes-src.tar.gz
W1126 01:15:45.649] INFO: Elapsed time: 1179.471s, Critical Path: 319.14s
W1126 01:15:45.650] INFO: 4427 processes: 4427 linux-sandbox.
W1126 01:15:45.654] INFO: Build completed successfully, 4492 total actions
W1126 01:15:45.655] INFO: Build completed successfully, 4492 total actions
W1126 01:15:45.660] 2019/11/26 01:15:45 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 19m39.625391267s
W1126 01:15:45.661] 2019/11/26 01:15:45 util.go:265: Flushing memory.
I1126 01:15:45.761] make: Leaving directory '/go/src/k8s.io/kubernetes'
W1126 01:16:24.027] 2019/11/26 01:16:24 azure.go:916: Deleting resource group: kubetest-86b66497-0fe7-11ea-8a01-0242ad6015f1.
W1126 01:16:24.479] 2019/11/26 01:16:24 azure.go:654: CWD /go/src/k8s.io/kubernetes
W1126 01:16:24.479] 2019/11/26 01:16:24 azure.go:673: Attempting Docker login with azure cred.
W1126 01:16:25.860] 2019/11/26 01:16:25 azure.go:682: Docker login success.
W1126 01:16:25.861] 2019/11/26 01:16:25 azure.go:727: Building hyperkube.
W1126 01:16:25.861] 2019/11/26 01:16:25 util.go:56: Warning: Couldn't find directory src/k8s.io/kubernetes/cmd/hyperkube under any of GOPATH /go, defaulting to /go/src/k8s.io/kubernetes/cmd/hyperkube
W1126 01:16:25.861] 2019/11/26 01:16:25 azure.go:745: Pushing hyperkube.
W1126 01:16:25.862] 2019/11/26 01:16:25 process.go:153: Running: make -C /go/src/k8s.io/kubernetes/cluster/images/hyperkube push
W1126 01:16:25.897] tar: /go/src/k8s.io/kubernetes/cluster/images/hyperkube/../../../_output/dockerized/bin/linux/amd64: Cannot open: No such file or directory
W1126 01:16:25.897] tar: Error is not recoverable: exiting now
W1126 01:16:25.898] make: *** [Makefile:40: build] Error 2
W1126 01:16:25.898] 2019/11/26 01:16:25 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes/cluster/images/hyperkube push' finished in 36.695583ms
W1126 01:16:25.898] 2019/11/26 01:16:25 azure.go:922: Skippng DumpClusterLogs due to CCM not being enabled.
W1126 01:16:25.898] 2019/11/26 01:16:25 azure.go:916: Deleting resource group: kubetest-86b66497-0fe7-11ea-8a01-0242ad6015f1.
W1126 01:16:25.911] 2019/11/26 01:16:25 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1126 01:16:25.911] 2019/11/26 01:16:25 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
I1126 01:16:26.011] make: Entering directory '/go/src/k8s.io/kubernetes/cluster/images/hyperkube'
I1126 01:16:26.012] cp -r ./* /tmp/hyperkubeo506KZ
I1126 01:16:26.012] tar -cvzf /tmp/hyperkubeo506KZ/binaries.tgz  -C /go/src/k8s.io/kubernetes/cluster/images/hyperkube/../../../_output/dockerized/bin/linux/amd64 kube-apiserver kube-controller-manager \
I1126 01:16:26.012] 	kube-proxy kube-scheduler kubectl kubelet
I1126 01:16:26.012] make: Leaving directory '/go/src/k8s.io/kubernetes/cluster/images/hyperkube'
W1126 01:16:27.131] 2019/11/26 01:16:27 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.219838625s
W1126 01:16:27.131] 2019/11/26 01:16:27 main.go:319: Something went wrong: starting e2e cluster: error building hyperkube error during make -C /go/src/k8s.io/kubernetes/cluster/images/hyperkube push: exit status 2
W1126 01:16:27.134] Traceback (most recent call last):
W1126 01:16:27.134]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W1126 01:16:27.138]     main(parse_args())
W1126 01:16:27.139]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W1126 01:16:27.140]     mode.start(runner_args)
W1126 01:16:27.140]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W1126 01:16:27.140]     check_env(env, self.command, *args)
W1126 01:16:27.141]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1126 01:16:27.141]     subprocess.check_call(cmd, env=env)
W1126 01:16:27.141]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1126 01:16:27.142]     raise CalledProcessError(retcode, cmd)
W1126 01:16:27.142] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=bazel', '--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.16', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-hyperkube=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://github.com/Azure/aks-engine/releases/download/v0.44.0/aks-engine-v0.44.0-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 1
E1126 01:16:27.171] Command failed
I1126 01:16:27.171] process 720 exited with code 1 after 20.4m
E1126 01:16:27.171] FAIL: chewong-in-tree-azure-disk-e2e
I1126 01:16:27.172] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1126 01:16:27.968] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1126 01:16:28.001] process 3771 exited with code 0 after 0.0m
I1126 01:16:28.001] Call:  gcloud config get-value account
I1126 01:16:28.245] process 3784 exited with code 0 after 0.0m
I1126 01:16:28.246] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1126 01:16:28.246] Upload result and artifacts...
I1126 01:16:28.246] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904
I1126 01:16:28.246] Call:  gsutil ls gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904/artifacts
W1126 01:16:29.320] CommandException: One or more URLs matched no objects.
E1126 01:16:29.394] Command failed
I1126 01:16:29.395] process 3797 exited with code 1 after 0.0m
W1126 01:16:29.395] Remote dir gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904/artifacts not exist yet
I1126 01:16:29.395] 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/1199129317561339904/artifacts
I1126 01:16:30.919] process 3942 exited with code 0 after 0.0m
I1126 01:16:30.920] Call:  git rev-parse HEAD
I1126 01:16:30.922] process 4469 exited with code 0 after 0.0m
I1126 01:16:30.923] Call:  git rev-parse HEAD
I1126 01:16:30.927] process 4470 exited with code 0 after 0.0m
I1126 01:16:30.927] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
W1126 01:16:31.951] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 01:16:31.951] process 4471 exited with code 1 after 0.0m
I1126 01:16:31.951] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_988onb gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 01:16:33.461] process 4616 exited with code 0 after 0.0m
I1126 01:16:33.461] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
W1126 01:16:34.333] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 01:16:34.333] process 4797 exited with code 1 after 0.0m
I1126 01:16:34.334] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_VWOu3I gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/jobResultsCache.json
I1126 01:16:35.605] process 4942 exited with code 0 after 0.0m
I1126 01:16:35.606] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_ohsmHc gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/1199129317561339904/finished.json
I1126 01:16:36.766] process 5123 exited with code 0 after 0.0m
I1126 01:16:36.767] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_u8IbPz gs://kubernetes-upstream/pr-logs/directory/chewong-in-tree-azure-disk-e2e/latest-build.txt
I1126 01:16:38.073] process 5304 exited with code 0 after 0.0m
I1126 01:16:38.074] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_mFoHwh gs://kubernetes-upstream/pr-logs/pull/85621/chewong-in-tree-azure-disk-e2e/latest-build.txt
I1126 01:16:39.369] process 5485 exited with code 0 after 0.0m