W1106 18:30:57.907] **************************************************************************
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
**************************************************************************
I1106 18:30:57.907] Args: --job=chewong-pull-azuredisk-csi-driver-e2e-multi-az --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=chewong-pull-azuredisk-csi-driver-e2e-multi-az --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes=master --repo=sigs.k8s.io/azuredisk-csi-driver=master:b7c03af8eae7388e84115ace30cf2ff3285b0dc9,180:3d974a552d3f3a8ed615de3a6a819edc818404e4 --upload=gs://kubernetes-upstream/pr-logs --timeout=460 --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-sigs/cloud-provider-azure/da4b9f5e9696ab00a5b1ac08606218f9f352d324/tests/k8s-azure/manifest/linux-vmss-multi-zones.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz --test-azure-disk-csi-driver=True '--test_args=--ginkgo.focus="\[multi\-az\]"' --timeout=420m
I1106 18:30:57.908] Bootstrap chewong-pull-azuredisk-csi-driver-e2e-multi-az...
I1106 18:30:57.911] Builder: 7abf24b3-00c3-11ea-918b-16773c0c7aed
I1106 18:30:57.911] Image: chewong/kubekins-e2e:v20191105-test-2
I1106 18:30:57.911] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/1192147311321419776
I1106 18:30:57.912] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1106 18:30:58.398] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1106 18:30:58.428] process 194 exited with code 0 after 0.0m
I1106 18:30:58.429] Call:  gcloud config get-value account
I1106 18:30:58.663] process 206 exited with code 0 after 0.0m
I1106 18:30:58.664] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1106 18:30:58.664] Root: /go/src
I1106 18:30:58.664] cd to /go/src
I1106 18:30:58.664] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes
I1106 18:30:58.664] Call:  git init k8s.io/kubernetes
I1106 18:30:58.668] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I1106 18:30:58.668] process 218 exited with code 0 after 0.0m
I1106 18:30:58.668] Call:  git config --local user.name 'K8S Bootstrap'
I1106 18:30:58.671] process 219 exited with code 0 after 0.0m
I1106 18:30:58.671] Call:  git config --local user.email k8s_bootstrap@localhost
I1106 18:30:58.673] process 220 exited with code 0 after 0.0m
I1106 18:30:58.673] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master
I1106 18:31:57.109] process 221 exited with code 0 after 1.0m
I1106 18:31:57.110] Call:  git checkout -B test FETCH_HEAD
W1106 18:31:59.249] Switched to a new branch 'test'
I1106 18:31:59.257] process 232 exited with code 0 after 0.0m
I1106 18:31:59.257] Call:  git show -s --format=format:%ct HEAD
I1106 18:31:59.316] process 233 exited with code 0 after 0.0m
I1106 18:31:59.316] Checkout: /go/src/sigs.k8s.io/azuredisk-csi-driver master:b7c03af8eae7388e84115ace30cf2ff3285b0dc9,180:3d974a552d3f3a8ed615de3a6a819edc818404e4 to /go/src/sigs.k8s.io/azuredisk-csi-driver
I1106 18:31:59.316] Call:  git init sigs.k8s.io/azuredisk-csi-driver
I1106 18:31:59.321] Initialized empty Git repository in /go/src/sigs.k8s.io/azuredisk-csi-driver/.git/
I1106 18:31:59.322] process 234 exited with code 0 after 0.0m
I1106 18:31:59.322] Call:  git config --local user.name 'K8S Bootstrap'
I1106 18:31:59.324] process 235 exited with code 0 after 0.0m
I1106 18:31:59.324] Call:  git config --local user.email k8s_bootstrap@localhost
I1106 18:31:59.327] process 236 exited with code 0 after 0.0m
I1106 18:31:59.327] Call:  git fetch --quiet --tags https://github.com/kubernetes-sigs/azuredisk-csi-driver master +refs/pull/180/head:refs/pr/180
I1106 18:32:05.513] process 237 exited with code 0 after 0.1m
I1106 18:32:05.513] Call:  git checkout -B test b7c03af8eae7388e84115ace30cf2ff3285b0dc9
W1106 18:32:06.070] Switched to a new branch 'test'
I1106 18:32:06.072] process 248 exited with code 0 after 0.0m
I1106 18:32:06.073] Call:  git show -s --format=format:%ct HEAD
I1106 18:32:06.076] process 249 exited with code 0 after 0.0m
I1106 18:32:06.076] Call:  git merge --no-ff -m 'Merge +refs/pull/180/head:refs/pr/180' 3d974a552d3f3a8ed615de3a6a819edc818404e4
W1106 18:32:06.080] merge: 3d974a552d3f3a8ed615de3a6a819edc818404e4 - not something we can merge
E1106 18:32:06.080] Command failed
I1106 18:32:06.080] process 250 exited with code 1 after 0.0m
E1106 18:32:06.080] unexpected error
Traceback (most recent call last):
  File "./test-infra/jenkins/bootstrap.py", line 1096, in bootstrap
  File "./test-infra/jenkins/bootstrap.py", line 981, in setup_root
  File "./test-infra/jenkins/bootstrap.py", line 307, in checkout
  File "./test-infra/jenkins/bootstrap.py", line 1054, in <lambda>
  File "./test-infra/jenkins/bootstrap.py", line 149, in _call
CalledProcessError: Command '['git', 'merge', '--no-ff', '-m', 'Merge +refs/pull/180/head:refs/pr/180', '3d974a552d3f3a8ed615de3a6a819edc818404e4']' returned non-zero exit status 1
I1106 18:32:06.081] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1106 18:32:06.620] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I1106 18:32:06.651] process 251 exited with code 0 after 0.0m
I1106 18:32:06.652] Call:  gcloud config get-value account
I1106 18:32:06.896] process 263 exited with code 0 after 0.0m
I1106 18:32:06.896] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I1106 18:32:06.896] Upload result and artifacts...
I1106 18:32:06.896] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/1192147311321419776
W1106 18:32:06.896] Missing local artifacts : /workspace/_artifacts
W1106 18:32:06.897] metadata path /workspace/_artifacts/metadata.json does not exist
W1106 18:32:06.897] metadata not found or invalid, init with empty metadata
I1106 18:32:06.897] Call:  git rev-parse HEAD
I1106 18:32:06.899] process 275 exited with code 0 after 0.0m
I1106 18:32:06.900] Call:  git rev-parse HEAD
I1106 18:32:06.902] process 276 exited with code 0 after 0.0m
I1106 18:32:06.902] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-pull-azuredisk-csi-driver-e2e-multi-az/jobResultsCache.json
I1106 18:32:07.792] process 277 exited with code 0 after 0.0m
I1106 18:32:07.793] Call:  gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-pull-azuredisk-csi-driver-e2e-multi-az/jobResultsCache.json#1573064801196637'
I1106 18:32:08.766] process 421 exited with code 0 after 0.0m
I1106 18:32:08.768] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1573064801196637 cp /tmp/gsutil_kUe9cw gs://kubernetes-upstream/pr-logs/directory/chewong-pull-azuredisk-csi-driver-e2e-multi-az/jobResultsCache.json
I1106 18:32:09.907] process 567 exited with code 0 after 0.0m
I1106 18:32:09.907] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/jobResultsCache.json
I1106 18:32:10.642] process 747 exited with code 0 after 0.0m
I1106 18:32:10.643] Call:  gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/jobResultsCache.json#1573064804130524'
I1106 18:32:11.672] process 891 exited with code 0 after 0.0m
I1106 18:32:11.673] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1573064804130524 cp /tmp/gsutil_m5m5zW gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/jobResultsCache.json
I1106 18:32:12.779] process 1037 exited with code 0 after 0.0m
I1106 18:32:12.779] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_9NLKcC gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/1192147311321419776/finished.json
I1106 18:32:13.891] process 1217 exited with code 0 after 0.0m
I1106 18:32:13.892] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_pdVgcO gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-multi-az/latest-build.txt
I1106 18:32:14.983] process 1397 exited with code 0 after 0.0m
I1106 18:32:14.984] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_ECDAvh gs://kubernetes-upstream/pr-logs/directory/chewong-pull-azuredisk-csi-driver-e2e-multi-az/latest-build.txt
I1106 18:32:16.288] process 1577 exited with code 0 after 0.0m