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 --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=fei-e2e-ccm --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...
I0314 02:42:08.963] Builder: b382f22e-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/1106022602167554048
I0314 02:42:08.964] 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.531] process 205 exited with code 0 after 0.0m
I0314 02:42:09.531] Call:  gcloud config get-value account
I0314 02:42:09.815] process 217 exited with code 0 after 0.0m
I0314 02:42:09.816] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 02:42:09.816] Root: /go/src
I0314 02:42:09.816] cd to /go/src
I0314 02:42:09.817] Checkout: /go/src/k8s.io/kubernetes v1.14.0-alpha.1 to /go/src/k8s.io/kubernetes
I0314 02:42:09.817] Call:  git init k8s.io/kubernetes
I0314 02:42:09.821] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I0314 02:42:09.822] process 229 exited with code 0 after 0.0m
I0314 02:42:09.822] Call:  git config --local user.name 'K8S Bootstrap'
I0314 02:42:09.826] process 230 exited with code 0 after 0.0m
I0314 02:42:09.826] Call:  git config --local user.email k8s_bootstrap@localhost
I0314 02:42:09.829] process 231 exited with code 0 after 0.0m
I0314 02:42:09.830] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes v1.14.0-alpha.1
I0314 02:43:10.162] process 232 exited with code 0 after 1.0m
I0314 02:43:10.162] Call:  git checkout -B test FETCH_HEAD
W0314 02:43:12.223] Switched to a new branch 'test'
I0314 02:43:12.235] process 249 exited with code 0 after 0.0m
I0314 02:43:12.236] Call:  git show -s --format=format:%ct HEAD
I0314 02:43:12.268] process 250 exited with code 0 after 0.0m
I0314 02:43:12.268] 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.268] Call:  git init github.com/feiskyer/cloud-provider-azure
I0314 02:43:12.273] Initialized empty Git repository in /go/src/github.com/feiskyer/cloud-provider-azure/.git/
I0314 02:43:12.273] process 251 exited with code 0 after 0.0m
I0314 02:43:12.273] Call:  git config --local user.name 'K8S Bootstrap'
I0314 02:43:12.276] process 252 exited with code 0 after 0.0m
I0314 02:43:12.276] Call:  git config --local user.email k8s_bootstrap@localhost
I0314 02:43:12.278] process 253 exited with code 0 after 0.0m
I0314 02:43:12.279] Call:  git fetch --quiet --tags https://github.com/feiskyer/cloud-provider-azure master +refs/pull/4/head:refs/pr/4
I0314 02:43:14.189] process 254 exited with code 0 after 0.0m
I0314 02:43:14.189] Call:  git checkout -B test 4c53be1a930a8929120745a3dfb072f51b12460b
W0314 02:43:14.528] Switched to a new branch 'test'
I0314 02:43:14.531] process 266 exited with code 0 after 0.0m
I0314 02:43:14.531] Call:  git show -s --format=format:%ct HEAD
I0314 02:43:14.535] process 267 exited with code 0 after 0.0m
I0314 02:43:14.536] Call:  git merge --no-ff -m 'Merge +refs/pull/4/head:refs/pr/4' 6117870c016c425b1dfb244a7a2b7fa71566ab18
I0314 02:43:14.923] Merge made by the 'recursive' strategy.
I0314 02:43:14.925]  tests/e2e/utils/service_utils.go | 6 +++++-
I0314 02:43:14.925]  tests/e2e/utils/utils.go         | 2 +-
I0314 02:43:14.925]  2 files changed, 6 insertions(+), 2 deletions(-)
I0314 02:43:14.926] process 268 exited with code 0 after 0.0m
I0314 02:43:14.926] Configure environment...
I0314 02:43:14.926] Call:  git show -s --format=format:%ct HEAD
I0314 02:43:14.956] process 270 exited with code 0 after 0.0m
I0314 02:43:14.956] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 02:43:15.513] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 02:43:15.635] process 271 exited with code 0 after 0.0m
I0314 02:43:15.636] Call:  gcloud config get-value account
I0314 02:43:15.956] process 283 exited with code 0 after 0.0m
I0314 02:43:15.957] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 02:43:15.957] 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:16.193] process 295 exited with code 0 after 0.0m
I0314 02:43:16.193] Start 1106022602167554048 at v1.14.0-alpha.1...
I0314 02:43:16.195] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_x8qr_g gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/1106022602167554048/started.json
I0314 02:43:17.786] process 325 exited with code 0 after 0.0m
I0314 02:43:17.787] 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/1106022602167554048' cp /tmp/gsutil_l6wj6o gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm/1106022602167554048.txt
I0314 02:43:19.373] process 503 exited with code 0 after 0.0m
I0314 02:43:19.374] 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:19.414] starts with local mode
W0314 02:43:19.415] Environment:
W0314 02:43:19.415] ARTIFACTS=/workspace/_artifacts
W0314 02:43:19.415] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W0314 02:43:19.416] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W0314 02:43:19.416] AZURE_CREDENTIALS=/etc/azure-cred/credentials
W0314 02:43:19.416] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub
W0314 02:43:19.416] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer
W0314 02:43:19.416] BAZEL_REMOTE_CACHE_ENABLED=false
W0314 02:43:19.417] BAZEL_VERSION=0.21.0
W0314 02:43:19.417] BOOTSTRAP_MIGRATION=yes
W0314 02:43:19.417] BUILD_ID=1106022602167554048
W0314 02:43:19.417] BUILD_NUMBER=1106022602167554048
W0314 02:43:19.417] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W0314 02:43:19.417] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W0314 02:43:19.418] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W0314 02:43:19.418] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W0314 02:43:19.418] CLOUD_CONFIG=random
W0314 02:43:19.418] DECK_PORT=tcp://10.0.160.83:80
W0314 02:43:19.418] DECK_PORT_80_TCP=tcp://10.0.160.83:80
W0314 02:43:19.418] DECK_PORT_80_TCP_ADDR=10.0.160.83
W0314 02:43:19.418] DECK_PORT_80_TCP_PORT=80
W0314 02:43:19.419] DECK_PORT_80_TCP_PROTO=tcp
W0314 02:43:19.419] DECK_SERVICE_HOST=10.0.160.83
W0314 02:43:19.419] DECK_SERVICE_PORT=80
W0314 02:43:19.419] DOCKER_IN_DOCKER_ENABLED=true
W0314 02:43:19.419] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/1106022602167554048/artifacts
W0314 02:43:19.419] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W0314 02:43:19.419] GOPATH=/go
W0314 02:43:19.420] GO_TARBALL=go1.12.linux-amd64.tar.gz
W0314 02:43:19.420] HOME=/workspace
W0314 02:43:19.420] HOOK_PORT=tcp://10.0.144.133:8888
W0314 02:43:19.420] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888
W0314 02:43:19.420] HOOK_PORT_8888_TCP_ADDR=10.0.144.133
W0314 02:43:19.420] HOOK_PORT_8888_TCP_PORT=8888
W0314 02:43:19.421] HOOK_PORT_8888_TCP_PROTO=tcp
W0314 02:43:19.421] HOOK_SERVICE_HOST=10.0.144.133
W0314 02:43:19.421] HOOK_SERVICE_PORT=8888
W0314 02:43:19.421] HOSTNAME=b382f22e-4602-11e9-9200-d2da7cd645f2
W0314 02:43:19.421] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20190313-3d10a416d-master
W0314 02:43:19.421] INSTANCE_PREFIX=bootstrap-e2e
W0314 02:43:19.421] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine
W0314 02:43:19.422] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub
W0314 02:43:19.422] JOB_NAME=fei-e2e-ccm
W0314 02:43:19.422] JOB_SPEC={"type":"presubmit","job":"fei-e2e-ccm","buildid":"1106022602167554048","prowjobid":"b382f22e-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:19.422] JOB_TYPE=presubmit
W0314 02:43:19.423] KUBERNETES_PORT=tcp://10.0.0.1:443
W0314 02:43:19.423] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443
W0314 02:43:19.423] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1
W0314 02:43:19.423] KUBERNETES_PORT_443_TCP_PORT=443
W0314 02:43:19.423] KUBERNETES_PORT_443_TCP_PROTO=tcp
W0314 02:43:19.423] KUBERNETES_SERVICE_HOST=10.0.0.1
W0314 02:43:19.424] KUBERNETES_SERVICE_PORT=443
W0314 02:43:19.424] KUBERNETES_SERVICE_PORT_HTTPS=443
W0314 02:43:19.424] KUBETEST_IN_DOCKER=true
W0314 02:43:19.424] KUBETEST_MANUAL_DUMP=y
W0314 02:43:19.424] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W0314 02:43:19.424] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W0314 02:43:19.424] KUBE_VERBOSE=0
W0314 02:43:19.425] NODE_NAME=b382f22e-4602-11e9-9200-d2da7cd645f2
W0314 02:43:19.425] 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:19.425] PROW_JOB_ID=b382f22e-4602-11e9-9200-d2da7cd645f2
W0314 02:43:19.425] PULL_BASE_REF=master
W0314 02:43:19.425] PULL_BASE_SHA=4c53be1a930a8929120745a3dfb072f51b12460b
W0314 02:43:19.425] PULL_NUMBER=4
W0314 02:43:19.426] PULL_PULL_SHA=6117870c016c425b1dfb244a7a2b7fa71566ab18
W0314 02:43:19.426] PULL_REFS=master:4c53be1a930a8929120745a3dfb072f51b12460b,4:6117870c016c425b1dfb244a7a2b7fa71566ab18
W0314 02:43:19.426] PWD=/workspace
W0314 02:43:19.426] REGISTRY=k8sprowinternal.azurecr.io
W0314 02:43:19.426] REPO_NAME=cloud-provider-azure
W0314 02:43:19.426] REPO_OWNER=feiskyer
W0314 02:43:19.426] SHLVL=2
W0314 02:43:19.427] SOURCE_DATE_EPOCH=1547549196
W0314 02:43:19.427] TERM=xterm
W0314 02:43:19.427] TIDE_PORT=tcp://10.0.180.53:80
W0314 02:43:19.427] TIDE_PORT_80_TCP=tcp://10.0.180.53:80
W0314 02:43:19.427] TIDE_PORT_80_TCP_ADDR=10.0.180.53
W0314 02:43:19.427] TIDE_PORT_80_TCP_PORT=80
W0314 02:43:19.427] TIDE_PORT_80_TCP_PROTO=tcp
W0314 02:43:19.428] TIDE_SERVICE_HOST=10.0.180.53
W0314 02:43:19.428] TIDE_SERVICE_PORT=80
W0314 02:43:19.428] WORKSPACE=/workspace
W0314 02:43:19.428] _=./test-infra/jenkins/bootstrap.py
W0314 02:43:19.429] 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.431] 2019/03/14 02:43:19 main.go:320: Limiting testing to 7h0m0s
W0314 02:43:19.432] 2019/03/14 02:43:19 azure.go:112: Reading credentials file /etc/azure-cred/credentials
W0314 02:43:19.433] 2019/03/14 02:43:19 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release
W0314 02:43:19.444] 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.444] /go/src/k8s.io/kubernetes/.bazelrc
W0314 02:43:19.445] Extracting Bazel installation...
I0314 02:43:19.546] make: Entering directory '/go/src/k8s.io/kubernetes'
W0314 02:43:34.834] Starting local Bazel server and connecting to it...
W0314 02:43:37.223] INFO: Invocation ID: af2b32f3-ae9b-4d6b-b5f0-53b3dc70d8ae
W0314 02:43:37.457] Loading: 
W0314 02:43:37.466] Loading: 0 packages loaded
W0314 02:43:38.471] Loading: 0 packages loaded
W0314 02:43:39.472] Loading: 0 packages loaded
W0314 02:43:41.335] Loading: 0 packages loaded
W0314 02:43:42.473] Loading: 0 packages loaded
W0314 02:43:42.473]     currently loading: build/release-tars
W0314 02:43:44.747] Loading: 0 packages loaded
W0314 02:43:44.748]     currently loading: build/release-tars
W0314 02:43:45.618] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured)
W0314 02:43:46.891] Analyzing: target //build/release-tars:release-tars (24 packages loaded, 279 targets configured)
W0314 02:43:49.317] Analyzing: target //build/release-tars:release-tars (50 packages loaded, 1189 targets configured)
W0314 02:43:51.591] Analyzing: target //build/release-tars:release-tars (51 packages loaded, 1189 targets configured)
W0314 02:43:53.790] Analyzing: target //build/release-tars:release-tars (58 packages loaded, 1198 targets configured)
W0314 02:43:58.157] Analyzing: target //build/release-tars:release-tars (58 packages loaded, 1198 targets configured)
W0314 02:44:01.300] Analyzing: target //build/release-tars:release-tars (88 packages loaded, 1691 targets configured)
W0314 02:44:02.015] INFO: SHA256 (https://codeload.github.com/golang/tools/zip/3e7aa9e59977626dc60433e9aeadf1bb63d28295) = 2384fa91351a7414b643c5230422ce45f5aa2be8a82727609afd4e64e6973a30
W0314 02:44:04.918] Analyzing: target //build/release-tars:release-tars (700 packages loaded, 7948 targets configured)
W0314 02:44:09.100] Analyzing: target //build/release-tars:release-tars (1062 packages loaded, 10783 targets configured)
W0314 02:44:13.880] Analyzing: target //build/release-tars:release-tars (1476 packages loaded, 14628 targets configured)
W0314 02:44:19.406] Analyzing: target //build/release-tars:release-tars (2198 packages loaded, 21629 targets configured)
W0314 02:44:26.372] Analyzing: target //build/release-tars:release-tars (2251 packages loaded, 25058 targets configured)
W0314 02:44:29.898] INFO: Analysed target //build/release-tars:release-tars (2252 packages loaded, 25181 targets configured).
W0314 02:44:29.902] INFO: Found 1 target...
W0314 02:44:31.460] [0 / 21] [-----] Writing file build/release-tars/kubernetes-server-linux-amd64.args
W0314 02:44:39.601] [114 / 4,694] GoCompile external/io_bazel_rules_go/go/tools/builders/linux_amd64_stripped/cgo%/go/tools/builders/cgo.a [for host]; 2s linux-sandbox ... (7 actions, 6 running)
W0314 02:44:49.292] [169 / 4,694] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 8s linux-sandbox ... (7 actions running)
W0314 02:45:05.653] [268 / 4,694] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 25s linux-sandbox ... (8 actions running)
W0314 02:45:18.924] [295 / 4,694] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 38s linux-sandbox ... (8 actions running)
W0314 02:45:34.220] [336 / 4,694] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 53s linux-sandbox ... (8 actions, 7 running)
W0314 02:45:52.018] [431 / 4,694] GoCompile vendor/github.com/daviddengcn/go-colortext/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/daviddengcn/go-colortext.a; 1s linux-sandbox ... (8 actions running)
W0314 02:46:12.715] [480 / 4,694] GoCompile vendor/k8s.io/klog/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/klog.a; 3s linux-sandbox ... (8 actions running)
W0314 02:46:36.862] [528 / 4,694] GoCompile vendor/github.com/GoogleCloudPlatform/k8s-cloud-provider/pkg/cloud/filter/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/GoogleCloudPlatform/k8s-cloud-provider/pkg/cloud/filter.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 02:47:05.924] [568 / 4,694] GoCompile vendor/github.com/modern-go/reflect2/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/modern-go/reflect2.a~partial.a; 13s linux-sandbox ... (8 actions, 7 running)
W0314 02:47:37.531] [628 / 4,694] GoCompile staging/src/k8s.io/apimachinery/pkg/util/clock/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apimachinery/pkg/util/clock.a; 9s linux-sandbox ... (8 actions running)
W0314 02:48:13.579] [723 / 4,694] GoCompile staging/src/k8s.io/apimachinery/pkg/api/resource/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apimachinery/pkg/api/resource.a; 2s linux-sandbox ... (8 actions running)
W0314 02:48:55.033] [908 / 4,694] GoCompile vendor/golang.org/x/crypto/pkcs12/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/golang.org/x/crypto/pkcs12.a; 5s linux-sandbox ... (8 actions running)
W0314 02:49:43.038] [1,024 / 4,694] GoCompile vendor/github.com/rubiojr/go-vhd/vhd/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/rubiojr/go-vhd/vhd.a; 4s linux-sandbox ... (8 actions running)
W0314 02:50:38.512] [1,162 / 4,694] GoCompile vendor/github.com/vmware/govmomi/find/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vmware/govmomi/find.a; 5s linux-sandbox ... (8 actions running)
W0314 02:51:42.386] [1,323 / 4,694] GoCompile vendor/github.com/vmware/govmomi/vim25/xml/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vmware/govmomi/vim25/xml.a; 4s linux-sandbox ... (8 actions running)
W0314 02:52:55.281] [1,456 / 4,694] GoCompile vendor/github.com/emicklei/go-restful/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/emicklei/go-restful.a; 6s linux-sandbox ... (8 actions, 7 running)
W0314 02:54:19.022] [1,642 / 4,694] GoCompile vendor/github.com/aws/aws-sdk-go/aws/corehandlers/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/aws/aws-sdk-go/aws/corehandlers.a; 5s linux-sandbox ... (8 actions, 7 running)
W0314 02:55:55.891] [1,844 / 4,694] 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; 9s linux-sandbox ... (8 actions, 7 running)
W0314 02:57:46.811] [2,006 / 4,694] GoLink cmd/genswaggertypedocs/linux_amd64_stripped/genswaggertypedocs; 3s linux-sandbox ... (7 actions, 6 running)
W0314 02:59:54.278] [2,208 / 4,694] GoCompile pkg/apis/storage/v1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/storage/v1.a; 6s linux-sandbox ... (8 actions running)
W0314 03:01:32.137] INFO: From  <rule context for //pkg/generated/openapi:zz_generated.openapi>:
W0314 03:01:32.140] 2019/03/14 03:01:20 Code for OpenAPI definitions generated
W0314 03:02:21.634] [2,455 / 4,694] GoCompile staging/src/k8s.io/client-go/dynamic/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/dynamic.a; 4s linux-sandbox ... (8 actions running)
W0314 03:05:10.481] [2,736 / 4,694] GoLink cmd/kube-proxy/linux_amd64_pure_stripped/kube-proxy; 36s linux-sandbox ... (8 actions, 7 running)
W0314 03:08:24.883] [3,112 / 4,694] GoCompile staging/src/k8s.io/api/core/v1/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/api/core/v1.a; 8s linux-sandbox ... (8 actions, 7 running)
W0314 03:08:46.533] Slow read: a 303752-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/__main__/bazel-out/k8-fastbuild/bin/cmd/kubeadm/app/cmd/phases/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/cmd/kubeadm/app/cmd/phases.a took 14332 ms.
W0314 03:12:08.655] [3,450 / 4,694] GoLink cmd/cloud-controller-manager/linux_amd64_pure_stripped/cloud-controller-manager; 11s linux-sandbox ... (8 actions running)
W0314 03:16:26.658] [4,053 / 4,694] GoCompile pkg/scheduler/util/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/scheduler/util.a; 2s linux-sandbox ... (8 actions running)
W0314 03:21:22.748] [4,473 / 4,694] GoCompile pkg/volume/scaleio/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/volume/scaleio.a; 6s linux-sandbox ... (8 actions, 7 running)
W0314 03:27:09.586] [4,687 / 4,694] GoLink test/e2e_node/linux_amd64_stripped/go_default_test; 170s linux-sandbox ... (3 actions, 2 running)
W0314 03:28:35.643] Target //build/release-tars:release-tars up-to-date:
W0314 03:28:35.653]   bazel-bin/build/release-tars/kubernetes.tar.gz
W0314 03:28:35.654]   bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz
W0314 03:28:35.654]   bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz
W0314 03:28:35.656]   bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz
W0314 03:28:35.656]   bazel-bin/build/release-tars/kubernetes-manifests.tar.gz
W0314 03:28:35.657]   bazel-bin/build/release-tars/kubernetes-src.tar.gz
W0314 03:28:35.657]   bazel-bin/build/release-tars/kubernetes-test.tar.gz
W0314 03:28:35.908] INFO: Elapsed time: 2716.169s, Critical Path: 606.35s
W0314 03:28:35.921] INFO: 4601 processes: 4601 linux-sandbox.
W0314 03:28:35.948] INFO: Build completed successfully, 4694 total actions
W0314 03:28:35.980] INFO: Build completed successfully, 4694 total actions
W0314 03:28:36.033] 2019/03/14 03:28:36 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 45m16.569061657s
W0314 03:28:36.033] 2019/03/14 03:28:36 util.go:255: Flushing memory.
I0314 03:28:36.134] make: Leaving directory '/go/src/k8s.io/kubernetes'
W0314 03:29:15.295] 2019/03/14 03:29:15 azure.go:666: Deleting resource group: kubetest-ed299cb6-4602-11e9-987a-0242260ab22b.
W0314 03:29:15.766] 2019/03/14 03:29:15 azure.go:422: CWD /go/src/k8s.io/kubernetes
W0314 03:29:15.767] 2019/03/14 03:29:15 azure.go:441: Attempting Docker login with azure cred.
W0314 03:29:17.042] 2019/03/14 03:29:17 azure.go:450: Docker login success.
W0314 03:29:17.042] 2019/03/14 03:29:17 azure.go:465: Building ccm.
W0314 03:29:17.043] 2019/03/14 03:29:17 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:17.043] 2019/03/14 03:29:17 azure.go:467: projectPath /go/src/k8s.io/cloud-provider-azure
W0314 03:29:17.043] 2019/03/14 03:29:17 process.go:153: Running: docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106022602167554048-5810ecb7 .
W0314 03:29:17.044] 2019/03/14 03:29:17 process.go:155: Step 'docker build -t k8sprowinternal.azurecr.io/azure-cloud-controller-manager:1106022602167554048-5810ecb7 .' finished in 485.304µs
W0314 03:29:17.044] 2019/03/14 03:29:17 azure.go:666: Deleting resource group: kubetest-ed299cb6-4602-11e9-987a-0242260ab22b.
W0314 03:29:17.087] 2019/03/14 03:29:17 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0314 03:29:17.088] 2019/03/14 03:29:17 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.111508845s
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:1106022602167554048-5810ecb7 .: 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.205]     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.206]     mode.start(runner_args)
W0314 03:29:18.206]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0314 03:29:18.206]     check_env(env, self.command, *args)
W0314 03:29:18.206]   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.207]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0314 03:29:18.207]     raise CalledProcessError(retcode, cmd)
W0314 03:29:18.208] 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 681 exited with code 1 after 46.0m
E0314 03:29:18.212] FAIL: fei-e2e-ccm
I0314 03:29:18.213] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0314 03:29:19.022] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0314 03:29:19.064] process 8036 exited with code 0 after 0.0m
I0314 03:29:19.064] Call:  gcloud config get-value account
I0314 03:29:19.324] process 8048 exited with code 0 after 0.0m
I0314 03:29:19.325] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0314 03:29:19.325] Upload result and artifacts...
I0314 03:29:19.325] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/1106022602167554048
I0314 03:29:19.325] Call:  gsutil ls gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/1106022602167554048/artifacts
W0314 03:29:20.763] CommandException: One or more URLs matched no objects.
E0314 03:29:20.856] Command failed
I0314 03:29:20.857] process 8060 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/1106022602167554048/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/1106022602167554048/artifacts
I0314 03:29:22.759] process 8202 exited with code 0 after 0.0m
I0314 03:29:22.760] Call:  git rev-parse HEAD
I0314 03:29:22.768] process 8726 exited with code 0 after 0.0m
I0314 03:29:22.768] Call:  git rev-parse HEAD
I0314 03:29:22.777] process 8727 exited with code 0 after 0.0m
I0314 03:29:22.777] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm/jobResultsCache.json
W0314 03:29:23.804] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm/jobResultsCache.json
I0314 03:29:23.805] process 8728 exited with code 1 after 0.0m
I0314 03:29:23.805] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_EnOOKd gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm/jobResultsCache.json
I0314 03:29:25.331] process 8870 exited with code 0 after 0.0m
I0314 03:29:25.331] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/jobResultsCache.json
W0314 03:29:26.363] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/jobResultsCache.json
I0314 03:29:26.364] process 9048 exited with code 1 after 0.0m
I0314 03:29:26.364] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_5BLB1U gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/jobResultsCache.json
I0314 03:29:27.774] process 9190 exited with code 0 after 0.0m
I0314 03:29:27.776] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_d1Foly gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/1106022602167554048/finished.json
I0314 03:29:29.293] process 9368 exited with code 0 after 0.0m
I0314 03:29:29.294] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_rWygd9 gs://kubernetes-upstream/pr-logs/directory/fei-e2e-ccm/latest-build.txt
I0314 03:29:30.753] process 9546 exited with code 0 after 0.0m
I0314 03:29:30.753] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_1mIiTs gs://kubernetes-upstream/pr-logs/pull/feiskyer_cloud-provider-azure/4/fei-e2e-ccm/latest-build.txt
I0314 03:29:32.307] process 9724 exited with code 0 after 0.0m