W0314 02:42:08.957] bootstrap.py is deprecated!
Please migrate your job to podutils!
https://github.com/kubernetes/test-infra/blob/master/prow/pod-utilities.md
I0314 02:42:08.958] 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-hyperkube=True --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 02:42:08.958] Bootstrap fei-e2e-ccm-autoscaling...
I0314 02:42:08.962] Builder: b38452bd-4602-11e9-9200-d2da7cd645f2
I0314 02:42:08.963] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190313-3d10a416d-master
I0314 02:42:08.963] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049
I0314 02:42:08.963] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 02:42:09.493] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 02:42:09.530] process 205 exited with code 0 after 0.0m
I0314 02:42:09.531] Call:  gcloud config get-value account
I0314 02:42:09.813] process 217 exited with code 0 after 0.0m
I0314 02:42:09.814] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 02:42:09.814] Root: /go/src
I0314 02:42:09.815] cd to /go/src
I0314 02:42:09.815] Checkout: /go/src/k8s.io/kubernetes v1.14.0-alpha.1 to /go/src/k8s.io/kubernetes
I0314 02:42:09.815] Call:  git init k8s.io/kubernetes
I0314 02:42:09.819] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I0314 02:42:09.820] process 229 exited with code 0 after 0.0m
I0314 02:42:09.820] Call:  git config --local user.name 'K8S Bootstrap'
I0314 02:42:09.823] process 230 exited with code 0 after 0.0m
I0314 02:42:09.823] Call:  git config --local user.email k8s_bootstrap@localhost
I0314 02:42:09.826] process 231 exited with code 0 after 0.0m
I0314 02:42:09.826] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes v1.14.0-alpha.1
I0314 02:43:10.170] process 232 exited with code 0 after 1.0m
I0314 02:43:10.170] Call:  git checkout -B test FETCH_HEAD
W0314 02:43:12.191] Switched to a new branch 'test'
I0314 02:43:12.203] process 247 exited with code 0 after 0.0m
I0314 02:43:12.204] Call:  git show -s --format=format:%ct HEAD
I0314 02:43:12.234] process 248 exited with code 0 after 0.0m
I0314 02:43:12.235] Checkout: /go/src/github.com/feiskyer/cloud-provider-azure master:4c53be1a930a8929120745a3dfb072f51b12460b,4:6117870c016c425b1dfb244a7a2b7fa71566ab18 to /go/src/github.com/feiskyer/cloud-provider-azure
I0314 02:43:12.235] Call:  git init github.com/feiskyer/cloud-provider-azure
I0314 02:43:12.239] Initialized empty Git repository in /go/src/github.com/feiskyer/cloud-provider-azure/.git/
I0314 02:43:12.239] process 249 exited with code 0 after 0.0m
I0314 02:43:12.240] Call:  git config --local user.name 'K8S Bootstrap'
I0314 02:43:12.244] process 250 exited with code 0 after 0.0m
I0314 02:43:12.244] Call:  git config --local user.email k8s_bootstrap@localhost
I0314 02:43:12.247] process 251 exited with code 0 after 0.0m
I0314 02:43:12.248] Call:  git fetch --quiet --tags https://github.com/feiskyer/cloud-provider-azure master +refs/pull/4/head:refs/pr/4
I0314 02:43:13.812] process 252 exited with code 0 after 0.0m
I0314 02:43:13.813] Call:  git checkout -B test 4c53be1a930a8929120745a3dfb072f51b12460b
W0314 02:43:14.161] Switched to a new branch 'test'
I0314 02:43:14.164] process 264 exited with code 0 after 0.0m
I0314 02:43:14.164] Call:  git show -s --format=format:%ct HEAD
I0314 02:43:14.168] process 265 exited with code 0 after 0.0m
I0314 02:43:14.168] Call:  git merge --no-ff -m 'Merge +refs/pull/4/head:refs/pr/4' 6117870c016c425b1dfb244a7a2b7fa71566ab18
I0314 02:43:14.366] Merge made by the 'recursive' strategy.
I0314 02:43:14.368]  tests/e2e/utils/service_utils.go | 6 +++++-
I0314 02:43:14.368]  tests/e2e/utils/utils.go         | 2 +-
I0314 02:43:14.368]  2 files changed, 6 insertions(+), 2 deletions(-)
I0314 02:43:14.369] process 266 exited with code 0 after 0.0m
I0314 02:43:14.369] Configure environment...
I0314 02:43:14.369] Call:  git show -s --format=format:%ct HEAD
I0314 02:43:14.400] process 268 exited with code 0 after 0.0m
I0314 02:43:14.401] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 02:43:15.040] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 02:43:15.167] process 269 exited with code 0 after 0.0m
I0314 02:43:15.167] Call:  gcloud config get-value account
I0314 02:43:15.422] process 281 exited with code 0 after 0.0m
I0314 02:43:15.423] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 02:43:15.423] 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 02:43:15.665] process 293 exited with code 0 after 0.0m
I0314 02:43:15.665] Start 1106022602167554049 at v1.14.0-alpha.1...
I0314 02:43:15.667] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_hdW_rr gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049/started.json
I0314 02:43:17.377] process 323 exited with code 0 after 0.0m
I0314 02:43:17.378] 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/1106022602167554049' cp /tmp/gsutil_K92Itc gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/1106022602167554049.txt
I0314 02:43:18.948] process 501 exited with code 0 after 0.0m
I0314 02:43:18.949] 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-hyperkube=True --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 02:43:18.988] starts with local mode
W0314 02:43:18.989] Environment:
W0314 02:43:18.989] ARTIFACTS=/workspace/_artifacts
W0314 02:43:18.989] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W0314 02:43:18.989] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W0314 02:43:18.990] AZURE_CREDENTIALS=/etc/azure-cred/credentials
W0314 02:43:18.990] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub
W0314 02:43:18.990] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer
W0314 02:43:18.990] BAZEL_REMOTE_CACHE_ENABLED=false
W0314 02:43:18.990] BAZEL_VERSION=0.21.0
W0314 02:43:18.990] BOOTSTRAP_MIGRATION=yes
W0314 02:43:18.991] BUILD_ID=1106022602167554049
W0314 02:43:18.991] BUILD_NUMBER=1106022602167554049
W0314 02:43:18.991] CCM_E2E_ARGS=-ginkgo.focus=autoscaler
W0314 02:43:18.991] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W0314 02:43:18.991] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W0314 02:43:18.991] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W0314 02:43:18.991] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W0314 02:43:18.992] CLOUD_CONFIG=random
W0314 02:43:18.992] DECK_PORT=tcp://10.0.160.83:80
W0314 02:43:18.992] DECK_PORT_80_TCP=tcp://10.0.160.83:80
W0314 02:43:18.992] DECK_PORT_80_TCP_ADDR=10.0.160.83
W0314 02:43:18.992] DECK_PORT_80_TCP_PORT=80
W0314 02:43:18.992] DECK_PORT_80_TCP_PROTO=tcp
W0314 02:43:18.992] DECK_SERVICE_HOST=10.0.160.83
W0314 02:43:18.993] DECK_SERVICE_PORT=80
W0314 02:43:18.993] DOCKER_IN_DOCKER_ENABLED=true
W0314 02:43:18.993] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049/artifacts
W0314 02:43:18.993] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W0314 02:43:18.993] GOPATH=/go
W0314 02:43:18.993] GO_TARBALL=go1.12.linux-amd64.tar.gz
W0314 02:43:18.993] HOME=/workspace
W0314 02:43:18.994] HOOK_PORT=tcp://10.0.144.133:8888
W0314 02:43:18.994] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888
W0314 02:43:18.994] HOOK_PORT_8888_TCP_ADDR=10.0.144.133
W0314 02:43:18.994] HOOK_PORT_8888_TCP_PORT=8888
W0314 02:43:18.995] HOOK_PORT_8888_TCP_PROTO=tcp
W0314 02:43:18.995] HOOK_SERVICE_HOST=10.0.144.133
W0314 02:43:18.995] HOOK_SERVICE_PORT=8888
W0314 02:43:18.995] HOSTNAME=b38452bd-4602-11e9-9200-d2da7cd645f2
W0314 02:43:18.995] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20190313-3d10a416d-master
W0314 02:43:18.996] INSTANCE_PREFIX=bootstrap-e2e
W0314 02:43:18.996] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine
W0314 02:43:18.996] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub
W0314 02:43:18.996] JOB_NAME=fei-e2e-ccm-autoscaling
W0314 02:43:18.997] JOB_SPEC={"type":"presubmit","job":"fei-e2e-ccm-autoscaling","buildid":"1106022602167554049","prowjobid":"b38452bd-4602-11e9-9200-d2da7cd645f2","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 02:43:18.997] JOB_TYPE=presubmit
W0314 02:43:18.997] KUBERNETES_PORT=tcp://10.0.0.1:443
W0314 02:43:18.997] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443
W0314 02:43:18.997] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1
W0314 02:43:18.997] KUBERNETES_PORT_443_TCP_PORT=443
W0314 02:43:18.997] KUBERNETES_PORT_443_TCP_PROTO=tcp
W0314 02:43:18.998] KUBERNETES_SERVICE_HOST=10.0.0.1
W0314 02:43:18.998] KUBERNETES_SERVICE_PORT=443
W0314 02:43:18.998] KUBERNETES_SERVICE_PORT_HTTPS=443
W0314 02:43:18.998] KUBETEST_IN_DOCKER=true
W0314 02:43:18.998] KUBETEST_MANUAL_DUMP=y
W0314 02:43:18.998] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W0314 02:43:18.998] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W0314 02:43:18.998] KUBE_VERBOSE=0
W0314 02:43:18.999] NODE_NAME=b38452bd-4602-11e9-9200-d2da7cd645f2
W0314 02:43:18.999] 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 02:43:18.999] PROW_JOB_ID=b38452bd-4602-11e9-9200-d2da7cd645f2
W0314 02:43:18.999] PULL_BASE_REF=master
W0314 02:43:18.999] PULL_BASE_SHA=4c53be1a930a8929120745a3dfb072f51b12460b
W0314 02:43:18.999] PULL_NUMBER=4
W0314 02:43:19.000] PULL_PULL_SHA=6117870c016c425b1dfb244a7a2b7fa71566ab18
W0314 02:43:19.000] PULL_REFS=master:4c53be1a930a8929120745a3dfb072f51b12460b,4:6117870c016c425b1dfb244a7a2b7fa71566ab18
W0314 02:43:19.000] PWD=/workspace
W0314 02:43:19.000] REGISTRY=k8sprowinternal.azurecr.io
W0314 02:43:19.000] REPO_NAME=cloud-provider-azure
W0314 02:43:19.000] REPO_OWNER=feiskyer
W0314 02:43:19.000] SHLVL=2
W0314 02:43:19.000] SOURCE_DATE_EPOCH=1547549196
W0314 02:43:19.001] TERM=xterm
W0314 02:43:19.001] TIDE_PORT=tcp://10.0.180.53:80
W0314 02:43:19.001] TIDE_PORT_80_TCP=tcp://10.0.180.53:80
W0314 02:43:19.001] TIDE_PORT_80_TCP_ADDR=10.0.180.53
W0314 02:43:19.001] TIDE_PORT_80_TCP_PORT=80
W0314 02:43:19.001] TIDE_PORT_80_TCP_PROTO=tcp
W0314 02:43:19.001] TIDE_SERVICE_HOST=10.0.180.53
W0314 02:43:19.002] TIDE_SERVICE_PORT=80
W0314 02:43:19.002] WORKSPACE=/workspace
W0314 02:43:19.002] _=./test-infra/jenkins/bootstrap.py
W0314 02:43:19.002] 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-hyperkube=True', '--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 02:43:19.004] 2019/03/14 02:43:19 main.go:320: Limiting testing to 7h0m0s
W0314 02:43:19.005] 2019/03/14 02:43:19 azure.go:112: Reading credentials file /etc/azure-cred/credentials
W0314 02:43:19.005] 2019/03/14 02:43:19 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release
W0314 02:43:19.020] 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 02:43:19.021] /go/src/k8s.io/kubernetes/.bazelrc
W0314 02:43:19.054] Extracting Bazel installation...
I0314 02:43:19.154] make: Entering directory '/go/src/k8s.io/kubernetes'
W0314 02:43:35.615] Starting local Bazel server and connecting to it...
W0314 02:43:38.058] INFO: Invocation ID: 74c9370b-b78c-4953-aa61-cb33cf0c2f13
W0314 02:43:38.297] Loading: 
W0314 02:43:38.303] Loading: 0 packages loaded
W0314 02:43:39.309] Loading: 0 packages loaded
W0314 02:43:41.310] Loading: 0 packages loaded
W0314 02:43:42.393] Loading: 0 packages loaded
W0314 02:43:44.311] Loading: 0 packages loaded
W0314 02:43:44.311]     currently loading: build/release-tars
W0314 02:43:45.530] Loading: 0 packages loaded
W0314 02:43:45.530]     currently loading: build/release-tars
W0314 02:43:46.498] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured)
W0314 02:43:47.854] Analyzing: target //build/release-tars:release-tars (28 packages loaded, 32 targets configured)
W0314 02:43:51.394] Analyzing: target //build/release-tars:release-tars (28 packages loaded, 32 targets configured)
W0314 02:43:53.462] Analyzing: target //build/release-tars:release-tars (52 packages loaded, 1189 targets configured)
W0314 02:43:57.702] Analyzing: target //build/release-tars:release-tars (58 packages loaded, 1198 targets configured)
W0314 02:44:00.762] Analyzing: target //build/release-tars:release-tars (58 packages loaded, 1198 targets configured)
W0314 02:44:02.658] INFO: SHA256 (https://codeload.github.com/golang/tools/zip/3e7aa9e59977626dc60433e9aeadf1bb63d28295) = 2384fa91351a7414b643c5230422ce45f5aa2be8a82727609afd4e64e6973a30
W0314 02:44:04.121] Analyzing: target //build/release-tars:release-tars (283 packages loaded, 6988 targets configured)
W0314 02:44:08.062] Analyzing: target //build/release-tars:release-tars (1022 packages loaded, 9735 targets configured)
W0314 02:44:12.570] Analyzing: target //build/release-tars:release-tars (1776 packages loaded, 14948 targets configured)
W0314 02:44:17.727] Analyzing: target //build/release-tars:release-tars (2072 packages loaded, 19189 targets configured)
W0314 02:44:27.682] Analyzing: target //build/release-tars:release-tars (2251 packages loaded, 25049 targets configured)
W0314 02:44:34.388] INFO: Analysed target //build/release-tars:release-tars (2252 packages loaded, 25181 targets configured).
W0314 02:44:34.398] INFO: Found 1 target...
W0314 02:44:34.766] [0 / 2] [-----] BazelWorkspaceStatusAction stable-status.txt
W0314 02:44:43.634] [61 / 2,803] GoCompile external/io_bazel_rules_go/go/tools/builders/linux_amd64_stripped/asm%/go/tools/builders/asm.a [for host]; 3s linux-sandbox ... (8 actions, 7 running)
W0314 02:44:54.020] [115 / 2,842] SHA256 external/debian-base-amd64/image/000.tar.gz.nogz.sha256; 1s linux-sandbox ... (8 actions running)
W0314 02:45:05.449] [169 / 2,842] GoCompile vendor/golang.org/x/crypto/internal/chacha20/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/golang.org/x/crypto/internal/chacha20.a~partial.a; 6s linux-sandbox ... (8 actions running)
W0314 02:45:18.552] [209 / 2,842] GoCompile vendor/gopkg.in/yaml.v2/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/gopkg.in/yaml.v2.a; 5s linux-sandbox ... (8 actions running)
W0314 02:45:34.186] [260 / 2,842] GoCompile vendor/google.golang.org/grpc/codes/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/google.golang.org/grpc/codes.a; 1s linux-sandbox ... (7 actions, 6 running)
W0314 02:45:51.761] [331 / 2,842] GoCompile vendor/github.com/aws/aws-sdk-go/aws/endpoints/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/aws/aws-sdk-go/aws/endpoints.a; 3s linux-sandbox ... (8 actions running)
W0314 02:46:12.591] [379 / 2,842] GoCompile vendor/github.com/fatih/camelcase/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/fatih/camelcase.a; 3s linux-sandbox ... (8 actions running)
W0314 02:46:36.868] [421 / 2,842] GoCompile vendor/github.com/prometheus/common/model/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/prometheus/common/model.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 02:47:05.982] [465 / 2,842] GoCompile vendor/github.com/json-iterator/go/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/json-iterator/go.a; 13s linux-sandbox ... (8 actions running)
W0314 02:47:37.170] [520 / 2,842] GoCompile vendor/github.com/onsi/ginkgo/internal/leafnodes/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/onsi/ginkgo/internal/leafnodes.a; 9s linux-sandbox ... (7 actions running)
W0314 02:48:13.374] [642 / 2,842] GoLink vendor/github.com/onsi/ginkgo/ginkgo/linux_amd64_stripped/ginkgo; 4s linux-sandbox ... (8 actions running)
W0314 02:48:54.892] [1,088 / 2,842] GoCompile vendor/github.com/mailru/easyjson/buffer/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/mailru/easyjson/buffer.a [for host]; 5s linux-sandbox ... (8 actions running)
W0314 02:49:42.912] [1,183 / 2,842] GoCompile staging/src/k8s.io/apimachinery/pkg/util/net/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apimachinery/pkg/util/net.a; 6s linux-sandbox ... (8 actions running)
W0314 02:50:38.387] [1,333 / 2,842] GoCompile 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; 7s linux-sandbox ... (8 actions running)
W0314 02:51:42.261] [1,477 / 2,842] GoCompile pkg/cloudprovider/providers/vsphere/vclib/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/cloudprovider/providers/vsphere/vclib.a; 8s linux-sandbox ... (8 actions running)
W0314 02:52:55.310] [1,582 / 2,842]  <rule context for //pkg/generated/openapi:zz_generated.openapi>; 36s linux-sandbox ... (8 actions, 7 running)
W0314 02:53:25.035] INFO: From  <rule context for //pkg/generated/openapi:zz_generated.openapi>:
W0314 02:53:25.036] 2019/03/14 02:53:13 Code for OpenAPI definitions generated
W0314 02:54:19.038] [1,742 / 2,842] GoCompile staging/src/k8s.io/metrics/pkg/apis/metrics/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/metrics/pkg/apis/metrics.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 02:55:55.661] [1,937 / 2,842] GoCompile vendor/github.com/coreos/etcd/embed/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/coreos/etcd/embed.a; 7s linux-sandbox ... (8 actions running)
W0314 02:57:46.553] [2,115 / 2,842] GoCompile staging/src/k8s.io/client-go/kubernetes/typed/admissionregistration/v1beta1/fake/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/kubernetes/typed/admissionregistration/v1beta1/fake.a; 3s linux-sandbox ... (8 actions running)
W0314 02:59:53.931] [2,330 / 2,842] GoCompile pkg/client/clientset_generated/internalclientset/typed/apps/internalversion/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/client/clientset_generated/internalclientset/typed/apps/internalversion.a; 6s linux-sandbox ... (8 actions running)
W0314 03:02:20.698] [2,533 / 2,842] GoCompile staging/src/k8s.io/client-go/informers/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/informers.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 03:04:17.902] Slow read: a 582518-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/__main__/bazel-out/k8-fastbuild/bin/pkg/kubelet/volumemanager/populator/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/kubelet/volumemanager/populator.a took 5820 ms.
W0314 03:05:09.903] [2,751 / 4,667] GoCompile test/e2e/framework/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/test/e2e/framework.a; 19s linux-sandbox ... (8 actions running)
W0314 03:08:23.866] [2,910 / 4,694] GoLink cmd/genman/linux_amd64_stripped/genman; 140s linux-sandbox ... (8 actions, 7 running)
W0314 03:12:07.220] [3,174 / 4,694] PackageTar build/release-tars/kubernetes-test.tar.gz; 84s linux-sandbox ... (8 actions running)
W0314 03:16:23.819] [3,779 / 4,694] GoCompile staging/src/k8s.io/api/storage/v1beta1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/api/storage/v1beta1.a; 7s linux-sandbox ... (8 actions running)
W0314 03:21:18.726] [4,294 / 4,694] GoCompile pkg/kubectl/cmd/exec/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/kubectl/cmd/exec.a; 3s linux-sandbox ... (8 actions running)
W0314 03:23:20.561] Slow read: a 598022-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/__main__/bazel-out/k8-fastbuild/bin/pkg/volume/host_path/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/volume/host_path.a took 7673 ms.
W0314 03:26:58.242] [4,670 / 4,694] GoLink cmd/kube-apiserver/linux_amd64_pure_stripped/kube-apiserver; 88s linux-sandbox ... (3 actions, 2 running)
W0314 03:29:05.891] Target //build/release-tars:release-tars up-to-date:
W0314 03:29:05.894]   bazel-bin/build/release-tars/kubernetes.tar.gz
W0314 03:29:05.895]   bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz
W0314 03:29:05.896]   bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz
W0314 03:29:05.896]   bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz
W0314 03:29:05.896]   bazel-bin/build/release-tars/kubernetes-manifests.tar.gz
W0314 03:29:05.896]   bazel-bin/build/release-tars/kubernetes-src.tar.gz
W0314 03:29:05.896]   bazel-bin/build/release-tars/kubernetes-test.tar.gz
W0314 03:29:05.953] INFO: Elapsed time: 2746.835s, Critical Path: 655.78s
W0314 03:29:05.953] INFO: 4601 processes: 4601 linux-sandbox.
W0314 03:29:05.963] INFO: Build completed successfully, 4694 total actions
W0314 03:29:05.968] INFO: Build completed successfully, 4694 total actions
W0314 03:29:06.003] 2019/03/14 03:29:06 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 45m46.99711878s
W0314 03:29:06.003] 2019/03/14 03:29:06 util.go:255: Flushing memory.
I0314 03:29:06.104] make: Leaving directory '/go/src/k8s.io/kubernetes'
W0314 03:29:15.288] 2019/03/14 03:29:15 azure.go:666: Deleting resource group: kubetest-ece87b94-4602-11e9-9c3c-024222747162.
W0314 03:29:15.690] 2019/03/14 03:29:15 azure.go:422: CWD /go/src/k8s.io/kubernetes
W0314 03:29:15.690] 2019/03/14 03:29:15 azure.go:441: Attempting Docker login with azure cred.
W0314 03:29:16.923] 2019/03/14 03:29:16 azure.go:450: Docker login success.
W0314 03:29:16.923] 2019/03/14 03:29:16 azure.go:465: Building ccm.
W0314 03:29:16.923] 2019/03/14 03:29:16 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 03:29:16.923] 2019/03/14 03:29:16 azure.go:467: projectPath /go/src/k8s.io/cloud-provider-azure
W0314 03:29:16.924] 2019/03/14 03:29:16 process.go:153: Running: docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106022602167554049-58054a56 .
W0314 03:29:16.924] 2019/03/14 03:29:16 process.go:155: Step 'docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106022602167554049-58054a56 .' finished in 901.608µs
W0314 03:29:16.924] 2019/03/14 03:29:16 azure.go:666: Deleting resource group: kubetest-ece87b94-4602-11e9-9c3c-024222747162.
W0314 03:29:16.933] 2019/03/14 03:29:16 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0314 03:29:16.934] 2019/03/14 03:29:16 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0314 03:29:18.199] 2019/03/14 03:29:18 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.26516225s
W0314 03:29:18.200] 2019/03/14 03:29:18 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:1106022602167554049-58054a56 .: fork/exec /usr/bin/docker: no such file or directory
W0314 03:29:18.202] Traceback (most recent call last):
W0314 03:29:18.202]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 764, in <module>
W0314 03:29:18.204]     main(parse_args())
W0314 03:29:18.205]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 615, in main
W0314 03:29:18.205]     mode.start(runner_args)
W0314 03:29:18.205]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0314 03:29:18.205]     check_env(env, self.command, *args)
W0314 03:29:18.205]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0314 03:29:18.206]     subprocess.check_call(cmd, env=env)
W0314 03:29:18.206]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0314 03:29:18.206]     raise CalledProcessError(retcode, cmd)
W0314 03:29:18.207] 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-hyperkube=True', '--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 03:29:18.212] Command failed
I0314 03:29:18.212] process 679 exited with code 1 after 46.0m
E0314 03:29:18.212] FAIL: fei-e2e-ccm-autoscaling
I0314 03:29:18.212] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 03:29:19.023] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 03:29:19.066] process 7943 exited with code 0 after 0.0m
I0314 03:29:19.066] Call:  gcloud config get-value account
I0314 03:29:19.331] process 7955 exited with code 0 after 0.0m
I0314 03:29:19.331] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 03:29:19.331] Upload result and artifacts...
I0314 03:29:19.331] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049
I0314 03:29:19.332] Call:  gsutil ls gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049/artifacts
W0314 03:29:20.762] CommandException: One or more URLs matched no objects.
E0314 03:29:20.857] Command failed
I0314 03:29:20.857] process 7967 exited with code 1 after 0.0m
W0314 03:29:20.857] Remote dir gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049/artifacts not exist yet
I0314 03:29:20.857] 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/1106022602167554049/artifacts
I0314 03:29:22.882] process 8109 exited with code 0 after 0.0m
I0314 03:29:22.883] Call:  git rev-parse HEAD
I0314 03:29:22.886] process 8633 exited with code 0 after 0.0m
I0314 03:29:22.886] Call:  git rev-parse HEAD
I0314 03:29:22.891] process 8634 exited with code 0 after 0.0m
I0314 03:29:22.892] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/jobResultsCache.json
W0314 03:29:23.937] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 03:29:23.938] process 8635 exited with code 1 after 0.0m
I0314 03:29:23.938] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_UExMaG gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 03:29:25.434] process 8777 exited with code 0 after 0.0m
I0314 03:29:25.435] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/jobResultsCache.json
W0314 03:29:26.457] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 03:29:26.457] process 8955 exited with code 1 after 0.0m
I0314 03:29:26.458] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_6mA7zh gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/jobResultsCache.json
I0314 03:29:27.857] process 9097 exited with code 0 after 0.0m
I0314 03:29:27.858] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_SXUMRk gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/1106022602167554049/finished.json
I0314 03:29:29.334] process 9275 exited with code 0 after 0.0m
I0314 03:29:29.335] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_va6IEg gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm-autoscaling/latest-build.txt
I0314 03:29:30.765] process 9453 exited with code 0 after 0.0m
I0314 03:29:30.766] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_iRtfHJ gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm-autoscaling/latest-build.txt
I0314 03:29:32.202] process 9631 exited with code 0 after 0.0m