W0314 04:26:10.129] bootstrap.py is deprecated!
Please migrate your job to podutils!
https://github.com/kubernetes/test-infra/blob/master/prow/pod-utilities.md
I0314 04:26:10.129] Args: --job=fei-e2e-ccm-autoscaling --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=fei-e2e-ccm-autoscaling --repo=k8s.io/kubernetes=v1.14.0-alpha.1 --repo=github.com/feiskyer/cloud-provider-azure=master:4c53be1a930a8929120745a3dfb072f51b12460b,4:6117870c016c425b1dfb244a7a2b7fa71566ab18 --root=/go/src --service-account=/etc/service-account/service-account.json --timeout=200 --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=30 --test-ccm=True --acsengine-agentpoolcount=3 --acsengine-admin-username=azureuser '--acsengine-creds=$AZURE_CREDENTIALS' --acsengine-orchestratorRelease=1.14 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-ccm=True --acsengine-location=eastus2 '--acsengine-public-key=$AZURE_SSH_PUBLIC_KEY_FILE' --acsengine-template-url=https://gist.githubusercontent.com/ritazh/ddab154ac18e22d776efb067da5ac058/raw/b5f284c759c9eabcc6576250d61f757f1436764a/ccm.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.31.2/aks-engine-v0.31.2-linux-amd64.tar.gz '--test_args=--ginkgo.skip=Cluster\ssize\sautoscaler' --timeout=420m
I0314 04:26:10.129] Bootstrap fei-e2e-ccm-autoscaling...
I0314 04:26:10.133] Builder: 3af77ab5-4611-11e9-918b-16773c0c7aed
I0314 04:26:10.133] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190313-3d10a416d-master
I0314 04:26:10.133] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177
I0314 04:26:10.133] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 04:26:10.616] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 04:26:10.651] process 220 exited with code 0 after 0.0m
I0314 04:26:10.651] Call:  gcloud config get-value account
I0314 04:26:10.906] process 232 exited with code 0 after 0.0m
I0314 04:26:10.906] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 04:26:10.907] Root: /go/src
I0314 04:26:10.907] cd to /go/src
I0314 04:26:10.907] Checkout: /go/src/k8s.io/kubernetes v1.14.0-alpha.1 to /go/src/k8s.io/kubernetes
I0314 04:26:10.907] Call:  git init k8s.io/kubernetes
I0314 04:26:10.912] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I0314 04:26:10.912] process 244 exited with code 0 after 0.0m
I0314 04:26:10.912] Call:  git config --local user.name 'K8S Bootstrap'
I0314 04:26:10.915] process 245 exited with code 0 after 0.0m
I0314 04:26:10.916] Call:  git config --local user.email k8s_bootstrap@localhost
I0314 04:26:10.919] process 246 exited with code 0 after 0.0m
I0314 04:26:10.920] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes v1.14.0-alpha.1
I0314 04:27:11.969] process 247 exited with code 0 after 1.0m
I0314 04:27:11.969] Call:  git checkout -B test FETCH_HEAD
W0314 04:27:14.268] Switched to a new branch 'test'
I0314 04:27:14.282] process 262 exited with code 0 after 0.0m
I0314 04:27:14.283] Call:  git show -s --format=format:%ct HEAD
I0314 04:27:14.314] process 263 exited with code 0 after 0.0m
I0314 04:27:14.315] Checkout: /go/src/github.com/feiskyer/cloud-provider-azure master:4c53be1a930a8929120745a3dfb072f51b12460b,4:6117870c016c425b1dfb244a7a2b7fa71566ab18 to /go/src/github.com/feiskyer/cloud-provider-azure
I0314 04:27:14.316] Call:  git init github.com/feiskyer/cloud-provider-azure
I0314 04:27:14.320] Initialized empty Git repository in /go/src/github.com/feiskyer/cloud-provider-azure/.git/
I0314 04:27:14.321] process 264 exited with code 0 after 0.0m
I0314 04:27:14.321] Call:  git config --local user.name 'K8S Bootstrap'
I0314 04:27:14.324] process 265 exited with code 0 after 0.0m
I0314 04:27:14.324] Call:  git config --local user.email k8s_bootstrap@localhost
I0314 04:27:14.327] process 266 exited with code 0 after 0.0m
I0314 04:27:14.328] Call:  git fetch --quiet --tags https://github.com/feiskyer/cloud-provider-azure master +refs/pull/4/head:refs/pr/4
I0314 04:27:15.784] process 267 exited with code 0 after 0.0m
I0314 04:27:15.784] Call:  git checkout -B test 4c53be1a930a8929120745a3dfb072f51b12460b
W0314 04:27:16.151] Switched to a new branch 'test'
I0314 04:27:16.154] process 279 exited with code 0 after 0.0m
I0314 04:27:16.154] Call:  git show -s --format=format:%ct HEAD
I0314 04:27:16.157] process 280 exited with code 0 after 0.0m
I0314 04:27:16.158] Call:  git merge --no-ff -m 'Merge +refs/pull/4/head:refs/pr/4' 6117870c016c425b1dfb244a7a2b7fa71566ab18
I0314 04:27:16.345] Merge made by the 'recursive' strategy.
I0314 04:27:16.347]  tests/e2e/utils/service_utils.go | 6 +++++-
I0314 04:27:16.348]  tests/e2e/utils/utils.go         | 2 +-
I0314 04:27:16.348]  2 files changed, 6 insertions(+), 2 deletions(-)
I0314 04:27:16.348] process 281 exited with code 0 after 0.0m
I0314 04:27:16.348] Configure environment...
I0314 04:27:16.349] Call:  git show -s --format=format:%ct HEAD
I0314 04:27:16.380] process 283 exited with code 0 after 0.0m
I0314 04:27:16.381] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 04:27:16.985] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 04:27:17.102] process 284 exited with code 0 after 0.0m
I0314 04:27:17.102] Call:  gcloud config get-value account
I0314 04:27:17.367] process 296 exited with code 0 after 0.0m
I0314 04:27:17.367] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 04:27:17.368] 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
'
I0314 04:27:17.627] process 308 exited with code 0 after 0.0m
I0314 04:27:17.628] Start 1106048774653874177 at v1.14.0-alpha.1...
I0314 04:27:17.630] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_7y54zd gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177/started.json
I0314 04:27:19.252] process 338 exited with code 0 after 0.0m
I0314 04:27:19.253] Call:  gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177' cp /tmp/gsutil_WkI4Mh gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/1106048774653874177.txt
I0314 04:27:20.661] process 516 exited with code 0 after 0.0m
I0314 04:27:20.662] 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=30 --test-ccm=True --acsengine-agentpoolcount=3 --acsengine-admin-username=azureuser --acsengine-creds=/etc/azure-cred/credentials --acsengine-orchestratorRelease=1.14 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-ccm=True --acsengine-location=eastus2 --acsengine-public-key=/etc/azure-ssh/azure-ssh-pub --acsengine-template-url=https://gist.githubusercontent.com/ritazh/ddab154ac18e22d776efb067da5ac058/raw/b5f284c759c9eabcc6576250d61f757f1436764a/ccm.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.31.2/aks-engine-v0.31.2-linux-amd64.tar.gz '--test_args=--ginkgo.skip=Cluster\ssize\sautoscaler' --timeout=420m
W0314 04:27:20.700] starts with local mode
W0314 04:27:20.701] Environment:
W0314 04:27:20.701] ARTIFACTS=/workspace/_artifacts
W0314 04:27:20.701] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W0314 04:27:20.702] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W0314 04:27:20.702] AZURE_CREDENTIALS=/etc/azure-cred/credentials
W0314 04:27:20.702] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub
W0314 04:27:20.702] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer
W0314 04:27:20.703] BAZEL_REMOTE_CACHE_ENABLED=false
W0314 04:27:20.703] BAZEL_VERSION=0.21.0
W0314 04:27:20.703] BOOTSTRAP_MIGRATION=yes
W0314 04:27:20.703] BUILD_ID=1106048774653874177
W0314 04:27:20.703] BUILD_NUMBER=1106048774653874177
W0314 04:27:20.703] CCM_E2E_ARGS=-ginkgo.focus=autoscaler
W0314 04:27:20.703] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W0314 04:27:20.704] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W0314 04:27:20.704] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W0314 04:27:20.704] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W0314 04:27:20.704] CLOUD_CONFIG=random
W0314 04:27:20.704] DECK_PORT=tcp://10.0.160.83:80
W0314 04:27:20.704] DECK_PORT_80_TCP=tcp://10.0.160.83:80
W0314 04:27:20.705] DECK_PORT_80_TCP_ADDR=10.0.160.83
W0314 04:27:20.705] DECK_PORT_80_TCP_PORT=80
W0314 04:27:20.705] DECK_PORT_80_TCP_PROTO=tcp
W0314 04:27:20.705] DECK_SERVICE_HOST=10.0.160.83
W0314 04:27:20.705] DECK_SERVICE_PORT=80
W0314 04:27:20.705] DOCKER_IN_DOCKER_ENABLED=true
W0314 04:27:20.705] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177/artifacts
W0314 04:27:20.706] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W0314 04:27:20.706] GOPATH=/go
W0314 04:27:20.706] GO_TARBALL=go1.12.linux-amd64.tar.gz
W0314 04:27:20.706] HOME=/workspace
W0314 04:27:20.706] HOOK_PORT=tcp://10.0.144.133:8888
W0314 04:27:20.706] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888
W0314 04:27:20.707] HOOK_PORT_8888_TCP_ADDR=10.0.144.133
W0314 04:27:20.707] HOOK_PORT_8888_TCP_PORT=8888
W0314 04:27:20.707] HOOK_PORT_8888_TCP_PROTO=tcp
W0314 04:27:20.707] HOOK_SERVICE_HOST=10.0.144.133
W0314 04:27:20.707] HOOK_SERVICE_PORT=8888
W0314 04:27:20.707] HOSTNAME=3af77ab5-4611-11e9-918b-16773c0c7aed
W0314 04:27:20.708] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20190313-3d10a416d-master
W0314 04:27:20.708] INSTANCE_PREFIX=bootstrap-e2e
W0314 04:27:20.708] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine
W0314 04:27:20.708] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub
W0314 04:27:20.708] JOB_NAME=fei-e2e-ccm-autoscaling
W0314 04:27:20.709] JOB_SPEC={"type":"presubmit","job":"fei-e2e-ccm-autoscaling","buildid":"1106048774653874177","prowjobid":"3af77ab5-4611-11e9-918b-16773c0c7aed","refs":{"org":"feiskyer","repo":"cloud-provider-azure","repo_link":"https://github.com/feiskyer/cloud-provider-azure","base_ref":"master","base_sha":"4c53be1a930a8929120745a3dfb072f51b12460b","base_link":"https://github.com/feiskyer/cloud-provider-azure/commit/4c53be1a930a8929120745a3dfb072f51b12460b","pulls":[{"number":4,"author":"feiskyer","sha":"6117870c016c425b1dfb244a7a2b7fa71566ab18","link":"https://github.com/feiskyer/cloud-provider-azure/pull/4","commit_link":"https://github.com/feiskyer/cloud-provider-azure/pull/4/commits/6117870c016c425b1dfb244a7a2b7fa71566ab18","author_link":"https://github.com/feiskyer"}]}}
W0314 04:27:20.709] JOB_TYPE=presubmit
W0314 04:27:20.709] KUBERNETES_PORT=tcp://10.0.0.1:443
W0314 04:27:20.709] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443
W0314 04:27:20.710] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1
W0314 04:27:20.710] KUBERNETES_PORT_443_TCP_PORT=443
W0314 04:27:20.710] KUBERNETES_PORT_443_TCP_PROTO=tcp
W0314 04:27:20.710] KUBERNETES_SERVICE_HOST=10.0.0.1
W0314 04:27:20.710] KUBERNETES_SERVICE_PORT=443
W0314 04:27:20.710] KUBERNETES_SERVICE_PORT_HTTPS=443
W0314 04:27:20.711] KUBETEST_IN_DOCKER=true
W0314 04:27:20.711] KUBETEST_MANUAL_DUMP=y
W0314 04:27:20.711] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W0314 04:27:20.711] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W0314 04:27:20.711] KUBE_VERBOSE=0
W0314 04:27:20.711] NODE_NAME=3af77ab5-4611-11e9-918b-16773c0c7aed
W0314 04:27:20.712] 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
W0314 04:27:20.712] PROW_JOB_ID=3af77ab5-4611-11e9-918b-16773c0c7aed
W0314 04:27:20.712] PULL_BASE_REF=master
W0314 04:27:20.712] PULL_BASE_SHA=4c53be1a930a8929120745a3dfb072f51b12460b
W0314 04:27:20.712] PULL_NUMBER=4
W0314 04:27:20.712] PULL_PULL_SHA=6117870c016c425b1dfb244a7a2b7fa71566ab18
W0314 04:27:20.713] PULL_REFS=master:4c53be1a930a8929120745a3dfb072f51b12460b,4:6117870c016c425b1dfb244a7a2b7fa71566ab18
W0314 04:27:20.713] PWD=/workspace
W0314 04:27:20.713] REGISTRY=k8sprowinternal.azurecr.io
W0314 04:27:20.713] REPO_NAME=cloud-provider-azure
W0314 04:27:20.713] REPO_OWNER=feiskyer
W0314 04:27:20.714] SHLVL=2
W0314 04:27:20.714] SOURCE_DATE_EPOCH=1547549196
W0314 04:27:20.714] TERM=xterm
W0314 04:27:20.714] TIDE_PORT=tcp://10.0.180.53:80
W0314 04:27:20.714] TIDE_PORT_80_TCP=tcp://10.0.180.53:80
W0314 04:27:20.715] TIDE_PORT_80_TCP_ADDR=10.0.180.53
W0314 04:27:20.715] TIDE_PORT_80_TCP_PORT=80
W0314 04:27:20.715] TIDE_PORT_80_TCP_PROTO=tcp
W0314 04:27:20.715] TIDE_SERVICE_HOST=10.0.180.53
W0314 04:27:20.715] TIDE_SERVICE_PORT=80
W0314 04:27:20.715] WORKSPACE=/workspace
W0314 04:27:20.715] _=./test-infra/jenkins/bootstrap.py
W0314 04:27:20.716] 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=30', '--test-ccm=True', '--acsengine-agentpoolcount=3', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.14', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-ccm=True', '--acsengine-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://gist.githubusercontent.com/ritazh/ddab154ac18e22d776efb067da5ac058/raw/b5f284c759c9eabcc6576250d61f757f1436764a/ccm.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.31.2/aks-engine-v0.31.2-linux-amd64.tar.gz', '--test_args=--ginkgo.skip=Cluster\\ssize\\sautoscaler', '--timeout=420m')
W0314 04:27:20.728] 2019/03/14 04:27:20 main.go:320: Limiting testing to 7h0m0s
W0314 04:27:20.729] 2019/03/14 04:27:20 azure.go:112: Reading credentials file /etc/azure-cred/credentials
W0314 04:27:20.729] 2019/03/14 04:27:20 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release
W0314 04:27:20.745] WARNING: The following rc files are no longer being read, please transfer their contents or import their path into one of the standard rc files:
W0314 04:27:20.746] /go/src/k8s.io/kubernetes/.bazelrc
W0314 04:27:20.776] Extracting Bazel installation...
I0314 04:27:20.877] make: Entering directory '/go/src/k8s.io/kubernetes'
W0314 04:27:37.618] Starting local Bazel server and connecting to it...
W0314 04:27:39.855] INFO: Invocation ID: 123373c3-d56e-4790-9596-d95786c92eb4
W0314 04:27:40.139] Loading: 
W0314 04:27:40.144] Loading: 0 packages loaded
W0314 04:27:41.153] Loading: 0 packages loaded
W0314 04:27:42.151] Loading: 0 packages loaded
W0314 04:27:43.746] Loading: 0 packages loaded
W0314 04:27:45.152] Loading: 0 packages loaded
W0314 04:27:45.152]     currently loading: build/release-tars
W0314 04:27:47.081] Loading: 0 packages loaded
W0314 04:27:47.081]     currently loading: build/release-tars
W0314 04:27:47.853] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured)
W0314 04:27:49.439] Analyzing: target //build/release-tars:release-tars (2 packages loaded, 16 targets configured)
W0314 04:27:51.281] Analyzing: target //build/release-tars:release-tars (2 packages loaded, 16 targets configured)
W0314 04:27:53.026] Analyzing: target //build/release-tars:release-tars (50 packages loaded, 809 targets configured)
W0314 04:27:55.034] Analyzing: target //build/release-tars:release-tars (58 packages loaded, 1198 targets configured)
W0314 04:27:58.952] Analyzing: target //build/release-tars:release-tars (58 packages loaded, 1198 targets configured)
W0314 04:28:02.296] Analyzing: target //build/release-tars:release-tars (59 packages loaded, 1198 targets configured)
W0314 04:28:04.113] INFO: SHA256 (https://codeload.github.com/golang/tools/zip/3e7aa9e59977626dc60433e9aeadf1bb63d28295) = 2384fa91351a7414b643c5230422ce45f5aa2be8a82727609afd4e64e6973a30
W0314 04:28:05.657] Analyzing: target //build/release-tars:release-tars (364 packages loaded, 7331 targets configured)
W0314 04:28:09.546] Analyzing: target //build/release-tars:release-tars (911 packages loaded, 8433 targets configured)
W0314 04:28:14.002] Analyzing: target //build/release-tars:release-tars (1394 packages loaded, 12771 targets configured)
W0314 04:28:19.130] Analyzing: target //build/release-tars:release-tars (1807 packages loaded, 18068 targets configured)
W0314 04:28:25.362] Analyzing: target //build/release-tars:release-tars (2251 packages loaded, 25090 targets configured)
W0314 04:28:30.131] INFO: Analysed target //build/release-tars:release-tars (2252 packages loaded, 25181 targets configured).
W0314 04:28:30.134] INFO: Found 1 target...
W0314 04:28:30.401] [0 / 3] [-----] BazelWorkspaceStatusAction stable-status.txt
W0314 04:28:37.995] [50 / 2,772] GoLink external/io_bazel_rules_go/go/tools/builders/linux_amd64_stripped/go_path [for host]; 2s linux-sandbox ... (7 actions, 6 running)
W0314 04:28:47.101] [77 / 3,210] CGoCodeGen //vendor/github.com/mindprince/gonvml:go_default_library%linux_amd64%cgo_codegen; 3s linux-sandbox ... (8 actions, 7 running)
W0314 04:28:58.324] [104 / 3,210] GoCompile vendor/k8s.io/klog/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/klog.a [for host]; 4s linux-sandbox ... (8 actions, 7 running)
W0314 04:29:11.362] [128 / 3,210] GoCompile vendor/golang.org/x/text/language/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/golang.org/x/text/language.a [for host]; 7s linux-sandbox ... (8 actions, 7 running)
W0314 04:29:25.117] [163 / 3,210] GoCompile vendor/github.com/vmware/govmomi/vim25/types/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vmware/govmomi/vim25/types.a; 10s linux-sandbox ... (8 actions, 7 running)
W0314 04:29:40.941] [217 / 3,210] GoCompile vendor/google.golang.org/api/compute/v0.beta/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/google.golang.org/api/compute/v0.beta.a; 10s linux-sandbox ... (8 actions, 7 running)
W0314 04:30:00.691] [261 / 3,210] GoCompile vendor/github.com/xanzy/go-cloudstack/cloudstack/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/xanzy/go-cloudstack/cloudstack.a; 12s linux-sandbox ... (8 actions, 7 running)
W0314 04:30:22.402] [320 / 3,210] GoCompile vendor/github.com/coreos/go-semver/semver/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/coreos/go-semver/semver.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 04:30:48.384] [360 / 3,210] GoCompile vendor/github.com/rancher/go-rancher/client/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/rancher/go-rancher/client.a; 6s linux-sandbox ... (8 actions running)
W0314 04:31:16.986] [410 / 3,210] GoCompile vendor/github.com/gophercloud/gophercloud/openstack/networking/v2/extensions/security/groups/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/gophercloud/gophercloud/openstack/networking/v2/extensions/security/groups.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 04:31:50.358] [488 / 3,210] GoCompile pkg/util/net/sets/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/util/net/sets.a; 3s linux-sandbox ... (8 actions running)
W0314 04:32:28.197] [569 / 3,210] GoCompile staging/src/k8s.io/client-go/util/cert/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/util/cert.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 04:33:12.398] [655 / 3,210] GoCompile staging/src/k8s.io/client-go/util/workqueue/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/util/workqueue.a; 6s linux-sandbox ... (8 actions running)
W0314 04:34:02.528] [780 / 3,210] GoCompile vendor/github.com/jteeuwen/go-bindata/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/jteeuwen/go-bindata.a [for host]; 7s linux-sandbox ... (8 actions running)
W0314 04:35:00.038] [863 / 3,210] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 51s linux-sandbox ... (8 actions, 7 running)
W0314 04:36:06.218] [1,136 / 4,661] Compiling vendor/github.com/opencontainers/runc/libcontainer/system/linux_amd64_stripped/go_default_library%linux_amd64%cgo_codegen%/sysconfig_notcgo.cgo2.c; 1s linux-sandbox ... (8 actions running)
W0314 04:37:24.459] [1,472 / 4,661] GoAsm vendor/golang.org/x/crypto/curve25519/linux_amd64_pure_stripped/go_default_library%/freeze_amd64.o; 8s linux-sandbox ... (8 actions, 7 running)
W0314 04:38:52.199] [1,624 / 4,661] 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; 7s linux-sandbox ... (8 actions, 7 running)
W0314 04:40:34.750] [1,781 / 4,661] GoCompile pkg/util/net/sets/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/util/net/sets.a; 2s linux-sandbox ... (8 actions, 7 running)
W0314 04:42:33.436] [1,977 / 4,661] GoCompile vendor/github.com/googleapis/gnostic/compiler/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/googleapis/gnostic/compiler.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 04:44:47.464] [2,245 / 4,661] GoCompile pkg/printers/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/printers.a; 3s linux-sandbox ... (8 actions, 7 running)
W0314 04:47:22.955] [2,565 / 4,661] GoCompile staging/src/k8s.io/kube-aggregator/pkg/apis/apiregistration/install/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/kube-aggregator/pkg/apis/apiregistration/install.a; 4s linux-sandbox ... (8 actions, 7 running)
W0314 04:50:21.274] [2,921 / 4,661] GoCompile cmd/kubeadm/app/apis/kubeadm/v1beta1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/cmd/kubeadm/app/apis/kubeadm/v1beta1.a; 2s linux-sandbox ... (8 actions, 7 running)
W0314 04:53:46.006] [3,280 / 4,661] GoCompile pkg/kubectl/cmd/set/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/kubectl/cmd/set.a; 3s linux-sandbox ... (8 actions, 7 running)
W0314 04:57:42.869] [3,603 / 4,661] GoLink cmd/kube-proxy/linux_amd64_pure_stripped/kube-proxy; 13s linux-sandbox ... (8 actions, 7 running)
W0314 05:02:13.887] [3,974 / 4,661] GoCompile pkg/kubectl/cmd/certificates/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/kubectl/cmd/certificates.a; 6s linux-sandbox ... (8 actions, 7 running)
W0314 05:07:27.320] [4,583 / 4,671]  <rule context for //pkg/generated/openapi:zz_generated.openapi>; 35s linux-sandbox ... (8 actions, 7 running)
W0314 05:07:36.377] INFO: From  <rule context for //pkg/generated/openapi:zz_generated.openapi>:
W0314 05:07:36.388] 2019/03/14 05:07:33 Code for OpenAPI definitions generated
W0314 05:13:28.789] Target //build/release-tars:release-tars up-to-date:
W0314 05:13:28.790]   bazel-bin/build/release-tars/kubernetes.tar.gz
W0314 05:13:28.793]   bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz
W0314 05:13:28.793]   bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz
W0314 05:13:28.794]   bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz
W0314 05:13:28.798]   bazel-bin/build/release-tars/kubernetes-manifests.tar.gz
W0314 05:13:28.883]   bazel-bin/build/release-tars/kubernetes-src.tar.gz
W0314 05:13:28.883]   bazel-bin/build/release-tars/kubernetes-test.tar.gz
W0314 05:13:29.100] INFO: Elapsed time: 2768.019s, Critical Path: 655.37s
W0314 05:13:29.110] INFO: 4601 processes: 4601 linux-sandbox.
W0314 05:13:29.192] INFO: Build completed successfully, 4694 total actions
W0314 05:13:29.245] INFO: Build completed successfully, 4694 total actions
I0314 05:13:29.377] make: Leaving directory '/go/src/k8s.io/kubernetes'
W0314 05:13:29.478] 2019/03/14 05:13:29 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 46m8.661158124s
W0314 05:13:29.489] 2019/03/14 05:13:29 util.go:255: Flushing memory.
W0314 05:14:05.097] 2019/03/14 05:14:05 azure.go:666: Deleting resource group: kubetest-7543f854-4611-11e9-8a69-024235a78867.
W0314 05:14:05.530] 2019/03/14 05:14:05 azure.go:422: CWD /go/src/k8s.io/kubernetes
W0314 05:14:05.531] 2019/03/14 05:14:05 azure.go:441: Attempting Docker login with azure cred.
W0314 05:14:06.613] 2019/03/14 05:14:06 azure.go:450: Docker login success.
W0314 05:14:06.613] 2019/03/14 05:14:06 azure.go:465: Building ccm.
W0314 05:14:06.614] 2019/03/14 05:14:06 util.go:46: Warning: Couldn't find directory src/k8s.io/cloud-provider-azure under any of GOPATH /go, defaulting to /go/src/k8s.io/cloud-provider-azure
W0314 05:14:06.614] 2019/03/14 05:14:06 azure.go:467: projectPath /go/src/k8s.io/cloud-provider-azure
W0314 05:14:06.614] 2019/03/14 05:14:06 process.go:153: Running: docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106048774653874177-fd0ec481 .
W0314 05:14:06.615] 2019/03/14 05:14:06 process.go:155: Step 'docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106048774653874177-fd0ec481 .' finished in 209.502µs
W0314 05:14:06.615] 2019/03/14 05:14:06 azure.go:666: Deleting resource group: kubetest-7543f854-4611-11e9-8a69-024235a78867.
W0314 05:14:06.624] 2019/03/14 05:14:06 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0314 05:14:06.624] 2019/03/14 05:14:06 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0314 05:14:07.516] 2019/03/14 05:14:07 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 891.30089ms
W0314 05:14:07.516] 2019/03/14 05:14:07 main.go:307: Something went wrong: starting e2e cluster: error building cloud controller manager error starting docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106048774653874177-fd0ec481 .: fork/exec /usr/bin/docker: no such file or directory
W0314 05:14:07.519] Traceback (most recent call last):
W0314 05:14:07.520]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 764, in <module>
W0314 05:14:07.520]     main(parse_args())
W0314 05:14:07.520]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 615, in main
W0314 05:14:07.520]     mode.start(runner_args)
W0314 05:14:07.521]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0314 05:14:07.521]     check_env(env, self.command, *args)
W0314 05:14:07.521]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0314 05:14:07.521]     subprocess.check_call(cmd, env=env)
W0314 05:14:07.521]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0314 05:14:07.522]     raise CalledProcessError(retcode, cmd)
W0314 05:14:07.522] 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=30', '--test-ccm=True', '--acsengine-agentpoolcount=3', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.14', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-ccm=True', '--acsengine-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://gist.githubusercontent.com/ritazh/ddab154ac18e22d776efb067da5ac058/raw/b5f284c759c9eabcc6576250d61f757f1436764a/ccm.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.31.2/aks-engine-v0.31.2-linux-amd64.tar.gz', '--test_args=--ginkgo.skip=Cluster\\ssize\\sautoscaler', '--timeout=420m')' returned non-zero exit status 1
E0314 05:14:07.527] Command failed
I0314 05:14:07.527] process 694 exited with code 1 after 46.8m
E0314 05:14:07.527] FAIL: fei-e2e-ccm-autoscaling
I0314 05:14:07.528] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 05:14:08.283] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 05:14:08.316] process 7931 exited with code 0 after 0.0m
I0314 05:14:08.316] Call:  gcloud config get-value account
I0314 05:14:08.580] process 7943 exited with code 0 after 0.0m
I0314 05:14:08.580] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 05:14:08.580] Upload result and artifacts...
I0314 05:14:08.581] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177
I0314 05:14:08.581] Call:  gsutil ls gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177/artifacts
W0314 05:14:09.874] CommandException: One or more URLs matched no objects.
E0314 05:14:09.986] Command failed
I0314 05:14:09.986] process 7955 exited with code 1 after 0.0m
W0314 05:14:09.986] Remote dir gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177/artifacts not exist yet
I0314 05:14:09.987] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177/artifacts
I0314 05:14:11.965] process 8097 exited with code 0 after 0.0m
I0314 05:14:11.965] Call:  git rev-parse HEAD
I0314 05:14:11.969] process 8621 exited with code 0 after 0.0m
I0314 05:14:11.970] Call:  git rev-parse HEAD
I0314 05:14:11.974] process 8622 exited with code 0 after 0.0m
I0314 05:14:11.974] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 05:14:13.062] process 8623 exited with code 0 after 0.0m
I0314 05:14:13.063] Call:  gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/jobResultsCache.json#1552534165114976'
I0314 05:14:14.283] process 8765 exited with code 0 after 0.0m
I0314 05:14:14.284] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1552534165114976 cp /tmp/gsutil_9RWMo9 gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 05:14:15.847] process 8909 exited with code 0 after 0.0m
I0314 05:14:15.848] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 05:14:16.951] process 9087 exited with code 0 after 0.0m
I0314 05:14:16.951] Call:  gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/jobResultsCache.json#1552534167636035'
I0314 05:14:18.147] process 9229 exited with code 0 after 0.0m
I0314 05:14:18.148] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1552534167636035 cp /tmp/gsutil_UQqlEA gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 05:14:19.548] process 9373 exited with code 0 after 0.0m
I0314 05:14:19.549] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_Mrf7dd gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106048774653874177/finished.json
I0314 05:14:20.958] process 9551 exited with code 0 after 0.0m
I0314 05:14:20.959] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_zxsM6u gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/latest-build.txt
I0314 05:14:22.524] process 9729 exited with code 0 after 0.0m
I0314 05:14:22.525] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_ppsNWY gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/latest-build.txt
I0314 05:14:23.948] process 9907 exited with code 0 after 0.0m