W0524 03:54:26.298] bootstrap.py is deprecated!
Please migrate your job to podutils!
https://github.com/kubernetes/test-infra/blob/master/prow/pod-utilities.md
I0524 03:54:26.298] Args: --job=pull-kubernetes-e2e --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-kubernetes-e2e --root=/go/src --service-account=/etc/service-account/service-account.json --repo=k8s.io/kubernetes --repo=github.com/feiskyer/kubernetes=master:78254d555a2958483780818ac83f899ff9aa6296,7:0c166abaf14ad16d124b0781965acc962b92b2d0 --upload=gs://kubernetes-upstream/pr-logs --timeout=460 --scenario=kubernetes_e2e -- --gce-ssh= --test=true --up=true --down=true --deployment=acsengine --build=bazel --provider=skeleton --ginkgo-parallel=30 --acsengine-agentpoolcount=3 --acsengine-admin-username=azureuser '--acsengine-creds=$AZURE_CREDENTIALS' --acsengine-orchestratorRelease=1.15 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-hyperkube=True --acsengine-location=eastus2 '--acsengine-public-key=$AZURE_SSH_PUBLIC_KEY_FILE' --acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.36.1/aks-engine-v0.36.1-linux-amd64.tar.gz '--test_args= --ginkgo.skip=\[sig-node\]\sMount\spropagation|\[sig-network\]\sNetwork\sshould\sset\sTCP\sCLOSE_WAIT\stimeout|\[sig-storage\]\sPersistentVolumes-local\sStress\swith\slocal\svolume\sprovisioner\s\[Serial\]\sshould\suse\sbe\sable\sto\sprocess\smany\spods\sand\sreuse\slocal\svolumes|should\sunmount\sif\spod\sis\sgracefully\sdeleted\swhile\skubelet\sis\sdown\s\[Disruptive\]\[Slow\]|should\sunmount\sif\spod\sis\sforce\sdeleted\swhile\skubelet\sis\sdown\s\[Disruptive\]\[Slow\]|\[sig-network\]\sServices\sshould\sbe\sable\sto\screate\sa\sfunctioning\sNodePort\sservice|\[sig-scheduling\]\sSchedulerPredicates\s\[Serial\]\svalidates\sMaxPods\slimit\snumber\sof\spods\sthat\sare\sallowed\sto\srun\s\[Slow\]|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sDefault\sshould\screate\sand\sdelete\sdefault\spersistent\svolumes\s\[Slow\]|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sshould\sprovision\sstorage\swith\sdifferent\sparameters|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sshould\stest\sthat\sdeleting\sa\sclaim\sbefore\sthe\svolume\sis\sprovisioned\sdeletes\sthe\svolume.|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\sadopt\smatching\sorphans\sand\srelease\snon-matching\spods|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\snot\sdeadlock\swhen\sa\spod.s\spredecessor\sfails|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\sperform\srolling\supdates\sand\sroll\sbacks\sof\stemplate\smodifications\swith\sPVCs|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\sprovide\sbasic\sidentity|\[sig-storage\]\sPersistentVolumes\sDefault\sStorageClass\spods\sthat\suse\smultiple\svolumes\sshould\sbe\sreschedulable|\[sig-storage\]\sPVC\sProtection|\[sig-storage\]\sDynamic\sProvisioning\s\[k8s.io\]\sGlusterDynamicProvisioner|\[sig-storage\]\sVolumes\sAzure\sDisk\sshould\sbe\smountable\s\[Slow\]|\[sig-apps\]\sNetwork\sPartition\s\[Disruptive\]\s\[Slow\]|\[sig-network\]\sDNS\sconfigMap|\[k8s.io\]\s\[sig-node\]\sKubelet\s\[Serial\]\s\[Slow\]\s\[k8s.io\]\s\[sig-node\]\sregular\sresource\susage\stracking\sresource\stracking\sfor\s0\spods\sper\snode|\[k8s.io\]\s\[sig-node\]\sKubelet\s\[Serial\]\s\[Slow\]\s\[k8s.io\]\s\[sig-node\]\sregular\sresource\susage\stracking\sresource\stracking\sfor\s100\spods\sper\snode|Horizontal\spod\sautoscaling\s\(scale\sresource:\sCPU\)|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sExternal\sshould\slet\san\sexternal\sdynamic\sprovisioner\screate\sand\sdelete\spersistent\svolumes\s\[Slow\]|ESIPP|\[sig-network\]\sServices\sshould\spreserve\ssource\spod\sIP\sfor\straffic\sthru\sservice\scluster\sIP|In-tree\sVolumes|PersistentVolumes-local|CSI\sVolumes|should\swrite\sentries\sto\s/etc/hosts|\[Slow\]|\[Serial\]|\[Disruptive\]|\[Flaky\]|\[Feature:.+\]|\[NodeFeature:.+\]' --timeout=420m
I0524 03:54:26.298] Bootstrap pull-kubernetes-e2e...
I0524 03:54:26.302] Builder: 63c988ff-7dd7-11e9-b3d3-b20db476995a
I0524 03:54:26.302] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190522-9bf3ab0-master
I0524 03:54:26.302] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608
I0524 03:54:26.302] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0524 03:54:26.832] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0524 03:54:26.860] process 216 exited with code 0 after 0.0m
I0524 03:54:26.861] Call:  gcloud config get-value account
I0524 03:54:27.089] process 228 exited with code 0 after 0.0m
I0524 03:54:27.089] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0524 03:54:27.089] Root: /go/src
I0524 03:54:27.090] cd to /go/src
I0524 03:54:27.090] Checkout: /go/src/k8s.io/kubernetes master to /go/src/k8s.io/kubernetes
I0524 03:54:27.090] Call:  git init k8s.io/kubernetes
I0524 03:54:27.093] Initialized empty Git repository in /go/src/k8s.io/kubernetes/.git/
I0524 03:54:27.094] process 240 exited with code 0 after 0.0m
I0524 03:54:27.094] Call:  git config --local user.name 'K8S Bootstrap'
I0524 03:54:27.096] process 241 exited with code 0 after 0.0m
I0524 03:54:27.096] Call:  git config --local user.email k8s_bootstrap@localhost
I0524 03:54:27.099] process 242 exited with code 0 after 0.0m
I0524 03:54:27.099] Call:  git fetch --quiet --tags https://github.com/kubernetes/kubernetes master
I0524 03:55:13.082] process 243 exited with code 0 after 0.8m
I0524 03:55:13.082] Call:  git checkout -B test FETCH_HEAD
W0524 03:55:14.847] Switched to a new branch 'test'
I0524 03:55:14.853] process 255 exited with code 0 after 0.0m
I0524 03:55:14.854] Call:  git show -s --format=format:%ct HEAD
I0524 03:55:14.857] process 256 exited with code 0 after 0.0m
I0524 03:55:14.858] Checkout: /go/src/github.com/feiskyer/kubernetes master:78254d555a2958483780818ac83f899ff9aa6296,7:0c166abaf14ad16d124b0781965acc962b92b2d0 to /go/src/github.com/feiskyer/kubernetes
I0524 03:55:14.858] Call:  git init github.com/feiskyer/kubernetes
I0524 03:55:14.862] Initialized empty Git repository in /go/src/github.com/feiskyer/kubernetes/.git/
I0524 03:55:14.862] process 257 exited with code 0 after 0.0m
I0524 03:55:14.862] Call:  git config --local user.name 'K8S Bootstrap'
I0524 03:55:14.864] process 258 exited with code 0 after 0.0m
I0524 03:55:14.865] Call:  git config --local user.email k8s_bootstrap@localhost
I0524 03:55:14.867] process 259 exited with code 0 after 0.0m
I0524 03:55:14.867] Call:  git fetch --quiet --tags https://github.com/feiskyer/kubernetes master +refs/pull/7/head:refs/pr/7
I0524 03:56:01.408] process 260 exited with code 0 after 0.8m
I0524 03:56:01.409] Call:  git checkout -B test 78254d555a2958483780818ac83f899ff9aa6296
W0524 03:56:03.294] Switched to a new branch 'test'
I0524 03:56:03.301] process 274 exited with code 0 after 0.0m
I0524 03:56:03.301] Call:  git show -s --format=format:%ct HEAD
I0524 03:56:03.305] process 275 exited with code 0 after 0.0m
I0524 03:56:03.305] Call:  git merge --no-ff -m 'Merge +refs/pull/7/head:refs/pr/7' 0c166abaf14ad16d124b0781965acc962b92b2d0
I0524 03:56:03.851] Merge made by the 'recursive' strategy.
I0524 03:56:03.853]  README.md | 2 +-
I0524 03:56:03.854]  1 file changed, 1 insertion(+), 1 deletion(-)
I0524 03:56:03.854] process 276 exited with code 0 after 0.0m
I0524 03:56:03.854] Configure environment...
I0524 03:56:03.854] Call:  git show -s --format=format:%ct HEAD
I0524 03:56:03.858] process 278 exited with code 0 after 0.0m
I0524 03:56:03.858] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0524 03:56:04.448] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0524 03:56:04.553] process 279 exited with code 0 after 0.0m
I0524 03:56:04.553] Call:  gcloud config get-value account
I0524 03:56:04.780] process 291 exited with code 0 after 0.0m
I0524 03:56:04.780] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0524 03:56:04.780] 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
'
I0524 03:56:05.293] process 303 exited with code 0 after 0.0m
I0524 03:56:05.294] Start 1131770095454916608 at v1.16.0-alpha.0.365+78254d555a2958...
I0524 03:56:05.296] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_pQcNAC gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608/started.json
I0524 03:56:06.843] process 336 exited with code 0 after 0.0m
I0524 03:56:06.844] Call:  gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608' cp /tmp/gsutil_O_mV5Q gs://kubernetes-upstream/pr-logs/directory/pull-kubernetes-e2e/1131770095454916608.txt
I0524 03:56:08.173] process 514 exited with code 0 after 0.0m
I0524 03:56:08.174] 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 --acsengine-agentpoolcount=3 --acsengine-admin-username=azureuser --acsengine-creds=/etc/azure-cred/credentials --acsengine-orchestratorRelease=1.15 --acsengine-mastervmsize=Standard_DS2_v2 --acsengine-agentvmsize=Standard_D4s_v3 --acsengine-hyperkube=True --acsengine-location=eastus2 --acsengine-public-key=/etc/azure-ssh/azure-ssh-pub --acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes.json --acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.36.1/aks-engine-v0.36.1-linux-amd64.tar.gz '--test_args= --ginkgo.skip=\[sig-node\]\sMount\spropagation|\[sig-network\]\sNetwork\sshould\sset\sTCP\sCLOSE_WAIT\stimeout|\[sig-storage\]\sPersistentVolumes-local\sStress\swith\slocal\svolume\sprovisioner\s\[Serial\]\sshould\suse\sbe\sable\sto\sprocess\smany\spods\sand\sreuse\slocal\svolumes|should\sunmount\sif\spod\sis\sgracefully\sdeleted\swhile\skubelet\sis\sdown\s\[Disruptive\]\[Slow\]|should\sunmount\sif\spod\sis\sforce\sdeleted\swhile\skubelet\sis\sdown\s\[Disruptive\]\[Slow\]|\[sig-network\]\sServices\sshould\sbe\sable\sto\screate\sa\sfunctioning\sNodePort\sservice|\[sig-scheduling\]\sSchedulerPredicates\s\[Serial\]\svalidates\sMaxPods\slimit\snumber\sof\spods\sthat\sare\sallowed\sto\srun\s\[Slow\]|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sDefault\sshould\screate\sand\sdelete\sdefault\spersistent\svolumes\s\[Slow\]|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sshould\sprovision\sstorage\swith\sdifferent\sparameters|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sshould\stest\sthat\sdeleting\sa\sclaim\sbefore\sthe\svolume\sis\sprovisioned\sdeletes\sthe\svolume.|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\sadopt\smatching\sorphans\sand\srelease\snon-matching\spods|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\snot\sdeadlock\swhen\sa\spod.s\spredecessor\sfails|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\sperform\srolling\supdates\sand\sroll\sbacks\sof\stemplate\smodifications\swith\sPVCs|\[sig-apps\]\sStatefulSet\s\[k8s.io\]\sBasic\sStatefulSet\sfunctionality\s\[StatefulSetBasic\]\sshould\sprovide\sbasic\sidentity|\[sig-storage\]\sPersistentVolumes\sDefault\sStorageClass\spods\sthat\suse\smultiple\svolumes\sshould\sbe\sreschedulable|\[sig-storage\]\sPVC\sProtection|\[sig-storage\]\sDynamic\sProvisioning\s\[k8s.io\]\sGlusterDynamicProvisioner|\[sig-storage\]\sVolumes\sAzure\sDisk\sshould\sbe\smountable\s\[Slow\]|\[sig-apps\]\sNetwork\sPartition\s\[Disruptive\]\s\[Slow\]|\[sig-network\]\sDNS\sconfigMap|\[k8s.io\]\s\[sig-node\]\sKubelet\s\[Serial\]\s\[Slow\]\s\[k8s.io\]\s\[sig-node\]\sregular\sresource\susage\stracking\sresource\stracking\sfor\s0\spods\sper\snode|\[k8s.io\]\s\[sig-node\]\sKubelet\s\[Serial\]\s\[Slow\]\s\[k8s.io\]\s\[sig-node\]\sregular\sresource\susage\stracking\sresource\stracking\sfor\s100\spods\sper\snode|Horizontal\spod\sautoscaling\s\(scale\sresource:\sCPU\)|\[sig-storage\]\sDynamic\sProvisioning\sDynamicProvisioner\sExternal\sshould\slet\san\sexternal\sdynamic\sprovisioner\screate\sand\sdelete\spersistent\svolumes\s\[Slow\]|ESIPP|\[sig-network\]\sServices\sshould\spreserve\ssource\spod\sIP\sfor\straffic\sthru\sservice\scluster\sIP|In-tree\sVolumes|PersistentVolumes-local|CSI\sVolumes|should\swrite\sentries\sto\s/etc/hosts|\[Slow\]|\[Serial\]|\[Disruptive\]|\[Flaky\]|\[Feature:.+\]|\[NodeFeature:.+\]' --timeout=420m
W0524 03:56:08.211] starts with local mode
W0524 03:56:08.211] Environment:
W0524 03:56:08.211] ARTIFACTS=/workspace/_artifacts
W0524 03:56:08.211] AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa
W0524 03:56:08.211] AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub
W0524 03:56:08.212] AZURE_CREDENTIALS=/etc/azure-cred/credentials
W0524 03:56:08.212] AZURE_SSH_PUBLIC_KEY_FILE=/etc/azure-ssh/azure-ssh-pub
W0524 03:56:08.212] AZ_STORAGE_CONTAINER_NAME=mystoragecontainer
W0524 03:56:08.212] BAZEL_REMOTE_CACHE_ENABLED=false
W0524 03:56:08.212] BAZEL_VERSION=0.23.2
W0524 03:56:08.212] BOOTSTRAP_MIGRATION=yes
W0524 03:56:08.212] BUILD_ID=1131770095454916608
W0524 03:56:08.212] BUILD_NUMBER=1131770095454916608
W0524 03:56:08.213] CLOUDSDK_COMPONENT_MANAGER_DISABLE_UPDATE_CHECK=true
W0524 03:56:08.213] CLOUDSDK_CONFIG=/go/src/k8s.io/kubernetes/.config/gcloud
W0524 03:56:08.213] CLOUDSDK_CORE_DISABLE_PROMPTS=1
W0524 03:56:08.213] CLOUDSDK_EXPERIMENTAL_FAST_COMPONENT_UPDATE=false
W0524 03:56:08.213] CLOUD_CONFIG=random
W0524 03:56:08.213] DECK_PORT=tcp://10.0.160.83:80
W0524 03:56:08.213] DECK_PORT_80_TCP=tcp://10.0.160.83:80
W0524 03:56:08.214] DECK_PORT_80_TCP_ADDR=10.0.160.83
W0524 03:56:08.214] DECK_PORT_80_TCP_PORT=80
W0524 03:56:08.214] DECK_PORT_80_TCP_PROTO=tcp
W0524 03:56:08.214] DECK_SERVICE_HOST=10.0.160.83
W0524 03:56:08.214] DECK_SERVICE_PORT=80
W0524 03:56:08.214] DOCKER_IN_DOCKER_ENABLED=true
W0524 03:56:08.214] DOCKER_IN_DOCKER_IPV6_ENABLED=false
W0524 03:56:08.215] GCS_ARTIFACTS_DIR=gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608/artifacts
W0524 03:56:08.215] GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json
W0524 03:56:08.215] GOPATH=/go
W0524 03:56:08.215] GO_TARBALL=go1.12.1.linux-amd64.tar.gz
W0524 03:56:08.215] HOME=/workspace
W0524 03:56:08.215] HOOK_PORT=tcp://10.0.144.133:8888
W0524 03:56:08.215] HOOK_PORT_8888_TCP=tcp://10.0.144.133:8888
W0524 03:56:08.216] HOOK_PORT_8888_TCP_ADDR=10.0.144.133
W0524 03:56:08.216] HOOK_PORT_8888_TCP_PORT=8888
W0524 03:56:08.216] HOOK_PORT_8888_TCP_PROTO=tcp
W0524 03:56:08.216] HOOK_SERVICE_HOST=10.0.144.133
W0524 03:56:08.216] HOOK_SERVICE_PORT=8888
W0524 03:56:08.216] HOSTNAME=63c988ff-7dd7-11e9-b3d3-b20db476995a
W0524 03:56:08.216] IMAGE=gcr.io/k8s-testimages/kubekins-e2e:v20190522-9bf3ab0-master
W0524 03:56:08.216] INSTANCE_PREFIX=bootstrap-e2e
W0524 03:56:08.217] JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/root/.ssh/google_compute_engine
W0524 03:56:08.217] JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/root/.ssh/google_compute_engine.pub
W0524 03:56:08.217] JOB_NAME=pull-kubernetes-e2e
W0524 03:56:08.217] JOB_SPEC={"type":"presubmit","job":"pull-kubernetes-e2e","buildid":"1131770095454916608","prowjobid":"63c988ff-7dd7-11e9-b3d3-b20db476995a","refs":{"org":"feiskyer","repo":"kubernetes","repo_link":"https://github.com/feiskyer/kubernetes","base_ref":"master","base_sha":"78254d555a2958483780818ac83f899ff9aa6296","base_link":"https://github.com/feiskyer/kubernetes/commit/78254d555a2958483780818ac83f899ff9aa6296","pulls":[{"number":7,"author":"feiskyer","sha":"0c166abaf14ad16d124b0781965acc962b92b2d0","link":"https://github.com/feiskyer/kubernetes/pull/7","commit_link":"https://github.com/feiskyer/kubernetes/pull/7/commits/0c166abaf14ad16d124b0781965acc962b92b2d0","author_link":"https://github.com/feiskyer"}]}}
W0524 03:56:08.217] JOB_TYPE=presubmit
W0524 03:56:08.218] KUBERNETES_PORT=tcp://10.0.0.1:443
W0524 03:56:08.218] KUBERNETES_PORT_443_TCP=tcp://10.0.0.1:443
W0524 03:56:08.218] KUBERNETES_PORT_443_TCP_ADDR=10.0.0.1
W0524 03:56:08.218] KUBERNETES_PORT_443_TCP_PORT=443
W0524 03:56:08.218] KUBERNETES_PORT_443_TCP_PROTO=tcp
W0524 03:56:08.218] KUBERNETES_SERVICE_HOST=10.0.0.1
W0524 03:56:08.218] KUBERNETES_SERVICE_PORT=443
W0524 03:56:08.219] KUBERNETES_SERVICE_PORT_HTTPS=443
W0524 03:56:08.219] KUBETEST_IN_DOCKER=true
W0524 03:56:08.219] KUBETEST_MANUAL_DUMP=y
W0524 03:56:08.219] KUBE_AWS_INSTANCE_PREFIX=bootstrap-e2e
W0524 03:56:08.219] KUBE_GCE_INSTANCE_PREFIX=bootstrap-e2e
W0524 03:56:08.219] KUBE_VERBOSE=0
W0524 03:56:08.219] NODE_NAME=63c988ff-7dd7-11e9-b3d3-b20db476995a
W0524 03:56:08.219] 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
W0524 03:56:08.220] PROW_JOB_ID=63c988ff-7dd7-11e9-b3d3-b20db476995a
W0524 03:56:08.220] PULL_BASE_REF=master
W0524 03:56:08.220] PULL_BASE_SHA=78254d555a2958483780818ac83f899ff9aa6296
W0524 03:56:08.220] PULL_NUMBER=7
W0524 03:56:08.220] PULL_PULL_SHA=0c166abaf14ad16d124b0781965acc962b92b2d0
W0524 03:56:08.220] PULL_REFS=master:78254d555a2958483780818ac83f899ff9aa6296,7:0c166abaf14ad16d124b0781965acc962b92b2d0
W0524 03:56:08.220] PWD=/workspace
W0524 03:56:08.220] REGISTRY=k8sprowinternal.azurecr.io
W0524 03:56:08.220] REPO_NAME=kubernetes
W0524 03:56:08.220] REPO_OWNER=feiskyer
W0524 03:56:08.221] SHLVL=2
W0524 03:56:08.221] SOURCE_DATE_EPOCH=1558665032
W0524 03:56:08.221] TERM=xterm
W0524 03:56:08.221] TIDE_PORT=tcp://10.0.180.53:80
W0524 03:56:08.221] TIDE_PORT_80_TCP=tcp://10.0.180.53:80
W0524 03:56:08.221] TIDE_PORT_80_TCP_ADDR=10.0.180.53
W0524 03:56:08.221] TIDE_PORT_80_TCP_PORT=80
W0524 03:56:08.221] TIDE_PORT_80_TCP_PROTO=tcp
W0524 03:56:08.221] TIDE_SERVICE_HOST=10.0.180.53
W0524 03:56:08.222] TIDE_SERVICE_PORT=80
W0524 03:56:08.222] WORKSPACE=/workspace
W0524 03:56:08.222] _=./test-infra/jenkins/bootstrap.py
W0524 03:56:08.224] 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', '--acsengine-agentpoolcount=3', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.15', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-hyperkube=True', '--acsengine-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.36.1/aks-engine-v0.36.1-linux-amd64.tar.gz', '--test_args= --ginkgo.skip=\\[sig-node\\]\\sMount\\spropagation|\\[sig-network\\]\\sNetwork\\sshould\\sset\\sTCP\\sCLOSE_WAIT\\stimeout|\\[sig-storage\\]\\sPersistentVolumes-local\\sStress\\swith\\slocal\\svolume\\sprovisioner\\s\\[Serial\\]\\sshould\\suse\\sbe\\sable\\sto\\sprocess\\smany\\spods\\sand\\sreuse\\slocal\\svolumes|should\\sunmount\\sif\\spod\\sis\\sgracefully\\sdeleted\\swhile\\skubelet\\sis\\sdown\\s\\[Disruptive\\]\\[Slow\\]|should\\sunmount\\sif\\spod\\sis\\sforce\\sdeleted\\swhile\\skubelet\\sis\\sdown\\s\\[Disruptive\\]\\[Slow\\]|\\[sig-network\\]\\sServices\\sshould\\sbe\\sable\\sto\\screate\\sa\\sfunctioning\\sNodePort\\sservice|\\[sig-scheduling\\]\\sSchedulerPredicates\\s\\[Serial\\]\\svalidates\\sMaxPods\\slimit\\snumber\\sof\\spods\\sthat\\sare\\sallowed\\sto\\srun\\s\\[Slow\\]|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sDefault\\sshould\\screate\\sand\\sdelete\\sdefault\\spersistent\\svolumes\\s\\[Slow\\]|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sshould\\sprovision\\sstorage\\swith\\sdifferent\\sparameters|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sshould\\stest\\sthat\\sdeleting\\sa\\sclaim\\sbefore\\sthe\\svolume\\sis\\sprovisioned\\sdeletes\\sthe\\svolume.|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\sadopt\\smatching\\sorphans\\sand\\srelease\\snon-matching\\spods|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\snot\\sdeadlock\\swhen\\sa\\spod.s\\spredecessor\\sfails|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\sperform\\srolling\\supdates\\sand\\sroll\\sbacks\\sof\\stemplate\\smodifications\\swith\\sPVCs|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\sprovide\\sbasic\\sidentity|\\[sig-storage\\]\\sPersistentVolumes\\sDefault\\sStorageClass\\spods\\sthat\\suse\\smultiple\\svolumes\\sshould\\sbe\\sreschedulable|\\[sig-storage\\]\\sPVC\\sProtection|\\[sig-storage\\]\\sDynamic\\sProvisioning\\s\\[k8s.io\\]\\sGlusterDynamicProvisioner|\\[sig-storage\\]\\sVolumes\\sAzure\\sDisk\\sshould\\sbe\\smountable\\s\\[Slow\\]|\\[sig-apps\\]\\sNetwork\\sPartition\\s\\[Disruptive\\]\\s\\[Slow\\]|\\[sig-network\\]\\sDNS\\sconfigMap|\\[k8s.io\\]\\s\\[sig-node\\]\\sKubelet\\s\\[Serial\\]\\s\\[Slow\\]\\s\\[k8s.io\\]\\s\\[sig-node\\]\\sregular\\sresource\\susage\\stracking\\sresource\\stracking\\sfor\\s0\\spods\\sper\\snode|\\[k8s.io\\]\\s\\[sig-node\\]\\sKubelet\\s\\[Serial\\]\\s\\[Slow\\]\\s\\[k8s.io\\]\\s\\[sig-node\\]\\sregular\\sresource\\susage\\stracking\\sresource\\stracking\\sfor\\s100\\spods\\sper\\snode|Horizontal\\spod\\sautoscaling\\s\\(scale\\sresource:\\sCPU\\)|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sExternal\\sshould\\slet\\san\\sexternal\\sdynamic\\sprovisioner\\screate\\sand\\sdelete\\spersistent\\svolumes\\s\\[Slow\\]|ESIPP|\\[sig-network\\]\\sServices\\sshould\\spreserve\\ssource\\spod\\sIP\\sfor\\straffic\\sthru\\sservice\\scluster\\sIP|In-tree\\sVolumes|PersistentVolumes-local|CSI\\sVolumes|should\\swrite\\sentries\\sto\\s/etc/hosts|\\[Slow\\]|\\[Serial\\]|\\[Disruptive\\]|\\[Flaky\\]|\\[Feature:.+\\]|\\[NodeFeature:.+\\]', '--timeout=420m')
W0524 03:56:08.231] 2019/05/24 03:56:08 main.go:327: Limiting testing to 7h0m0s
W0524 03:56:08.232] 2019/05/24 03:56:08 azure.go:112: Reading credentials file /etc/azure-cred/credentials
W0524 03:56:08.232] 2019/05/24 03:56:08 process.go:153: Running: make -C /go/src/k8s.io/kubernetes bazel-release
W0524 03:56:08.245] Extracting Bazel installation...
I0524 03:56:08.345] make: Entering directory '/go/src/k8s.io/kubernetes'
W0524 03:56:16.649] Starting local Bazel server and connecting to it...
W0524 03:56:18.312] Loading: 
W0524 03:56:18.316] Loading: 0 packages loaded
W0524 03:56:19.318] Loading: 0 packages loaded
W0524 03:56:20.944] Loading: 0 packages loaded
W0524 03:56:22.319] Loading: 0 packages loaded
W0524 03:56:24.250] Loading: 0 packages loaded
W0524 03:56:25.319] Loading: 0 packages loaded
W0524 03:56:25.319]     currently loading: build/release-tars
W0524 03:56:27.478] Loading: 0 packages loaded
W0524 03:56:27.479]     currently loading: build/release-tars
W0524 03:56:27.841] Analyzing: target //build/release-tars:release-tars (1 packages loaded, 0 targets configured)
W0524 03:56:29.291] Analyzing: target //build/release-tars:release-tars (218 packages loaded, 1142 targets configured)
W0524 03:56:30.958] Analyzing: target //build/release-tars:release-tars (1072 packages loaded, 3889 targets configured)
W0524 03:56:32.876] Analyzing: target //build/release-tars:release-tars (1924 packages loaded, 11606 targets configured)
W0524 03:56:35.191] Analyzing: target //build/release-tars:release-tars (2704 packages loaded, 20883 targets configured)
W0524 03:56:38.315] Analyzing: target //build/release-tars:release-tars (2884 packages loaded, 24228 targets configured)
W0524 03:56:40.283] INFO: SHA256 (https://codeload.github.com/golang/tools/zip/bf090417da8b6150dcfe96795325f5aa78fff718) = 11629171a39a1cb4d426760005be6f7cb9b4182e4cb2756b7f1c5c2b6ae869fe
W0524 03:56:40.857] DEBUG: Rule 'org_golang_x_tools' modified arguments {"sha256": "11629171a39a1cb4d426760005be6f7cb9b4182e4cb2756b7f1c5c2b6ae869fe"}
W0524 03:56:42.088] Analyzing: target //build/release-tars:release-tars (2926 packages loaded, 26916 targets configured)
W0524 03:56:47.939] Analyzing: target //build/release-tars:release-tars (2929 packages loaded, 32720 targets configured)
W0524 03:57:01.629] INFO: Analysed target //build/release-tars:release-tars (2929 packages loaded, 38840 targets configured).
W0524 03:57:01.631] Building: checking cached actions
W0524 03:57:01.632] INFO: Found 1 target...
W0524 03:57:01.787] [0 / 14] [-----] BazelWorkspaceStatusAction stable-status.txt
W0524 03:57:10.455] [88 / 3,490] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 5s linux-sandbox ... (8 actions, 7 running)
W0524 03:57:18.306] [216 / 3,490] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 13s linux-sandbox ... (7 actions running)
W0524 03:57:27.640] [416 / 3,490] GoStdlib external/io_bazel_rules_go/linux_amd64_pure_stripped/stdlib%/pkg; 22s linux-sandbox ... (8 actions, 7 running)
W0524 03:57:39.609] [474 / 3,490] GoCompile vendor/golang.org/x/text/encoding/traditionalchinese/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/golang.org/x/text/encoding/traditionalchinese.a; 2s linux-sandbox ... (8 actions running)
W0524 03:57:51.822] [561 / 3,490] GoCompile staging/src/k8s.io/apimachinery/pkg/util/runtime/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/apimachinery/pkg/util/runtime.a; 1s linux-sandbox ... (8 actions running)
W0524 03:58:06.144] [698 / 3,490] GoCompile vendor/golang.org/x/net/idna/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/golang.org/x/net/idna.a; 0s linux-sandbox ... (8 actions, 7 running)
W0524 03:58:22.926] [968 / 3,490] GoLink vendor/github.com/jteeuwen/go-bindata/go-bindata/linux_amd64_stripped/go-bindata [for host]; 0s linux-sandbox ... (8 actions, 7 running)
W0524 03:58:41.711] [1,159 / 3,490] 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; 12s linux-sandbox ... (8 actions, 7 running)
W0524 03:59:04.120] [1,247 / 3,490] GoCompile vendor/github.com/vishvananda/netlink/nl/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/vishvananda/netlink/nl.a; 1s linux-sandbox ... (8 actions running)
W0524 03:59:29.108] [1,335 / 3,490] GoCompile staging/src/k8s.io/api/rbac/v1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/api/rbac/v1.a; 3s linux-sandbox ... (8 actions, 7 running)
W0524 03:59:57.771] [1,499 / 3,490] GoCompile pkg/apis/apps/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/pkg/apis/apps.a; 5s linux-sandbox ... (8 actions, 7 running)
W0524 04:00:31.177] [1,729 / 3,490] GoLink cmd/kubectl/linux_amd64_pure_stripped/kubectl; 4s linux-sandbox ... (8 actions, 7 running)
W0524 04:01:12.880] [2,063 / 3,490]  <rule context for //staging/src/k8s.io/apiextensions-apiserver/pkg/generated/openapi:zz_generated.openapi>; 12s linux-sandbox ... (8 actions, 7 running)
W0524 04:01:57.562] [2,340 / 3,490] GoCompile vendor/github.com/docker/docker/client/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/github.com/docker/docker/client.a; 1s linux-sandbox ... (8 actions, 7 running)
W0524 04:02:48.842] [2,726 / 3,490] GoCompile staging/src/k8s.io/client-go/kubernetes/typed/scheduling/v1alpha1/fake/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/kubernetes/typed/scheduling/v1alpha1/fake.a; 1s linux-sandbox ... (8 actions, 7 running)
W0524 04:03:48.185] [3,106 / 3,490] GoCompile pkg/kubectl/describe/versioned/linux_amd64_stripped/go_default_library%/k8s.io/kubernetes/pkg/kubectl/describe/versioned.a; 5s linux-sandbox ... (8 actions, 7 running)
W0524 04:04:56.078] [3,506 / 3,656] GoLink cmd/genyaml/genyaml; 16s linux-sandbox ... (8 actions, 7 running)
W0524 04:06:13.848] [3,627 / 3,782] GoLink cmd/genkubedocs/genkubedocs; 58s linux-sandbox ... (8 actions, 7 running)
W0524 04:07:43.377] [3,739 / 3,916] GoLink cmd/genkubedocs/genkubedocs; 148s linux-sandbox ... (8 actions, 7 running)
W0524 04:08:31.265] Slow read: a 936354-byte read from /workspace/.cache/bazel/_bazel_root/48d5366022b4e3197674c8d6e2bee219/execroot/io_k8s_kubernetes/bazel-out/k8-fastbuild/bin/staging/src/k8s.io/client-go/informers/extensions/v1beta1/linux_amd64_pure_stripped/go_default_library%/k8s.io/kubernetes/vendor/k8s.io/client-go/informers/extensions/v1beta1.a took 10385 ms.
W0524 04:09:26.484] [4,054 / 4,195] PackageTar build/release-tars/kubernetes-test.tar.gz; 36s linux-sandbox ... (8 actions, 7 running)
W0524 04:11:25.587] [4,681 / 4,731] Executing genrule //build:gen_cloud-controller-manager.tar; 1s linux-sandbox ... (8 actions, 7 running)
W0524 04:14:02.047] [4,945 / 4,946] PackageTar build/release-tars/kubernetes-server-linux-amd64.tar.gz; 11s linux-sandbox
W0524 04:14:15.389] Target //build/release-tars:release-tars up-to-date:
W0524 04:14:15.391]   bazel-bin/build/release-tars/kubernetes-client-linux-amd64.tar.gz
W0524 04:14:15.391]   bazel-bin/build/release-tars/kubernetes-node-linux-amd64.tar.gz
W0524 04:14:15.391]   bazel-bin/build/release-tars/kubernetes-server-linux-amd64.tar.gz
W0524 04:14:15.391]   bazel-bin/build/release-tars/kubernetes-manifests.tar.gz
W0524 04:14:15.392]   bazel-bin/build/release-tars/kubernetes-test-portable.tar.gz
W0524 04:14:15.394]   bazel-bin/build/release-tars/kubernetes-test-linux-amd64.tar.gz
W0524 04:14:15.394]   bazel-bin/build/release-tars/kubernetes-test.tar.gz
W0524 04:14:15.395]   bazel-bin/build/release-tars/kubernetes.tar.gz
W0524 04:14:15.395]   bazel-bin/build/release-tars/kubernetes-src.tar.gz
W0524 04:14:15.427] INFO: Elapsed time: 1087.132s, Critical Path: 364.34s
W0524 04:14:15.427] INFO: 4853 processes: 4853 linux-sandbox.
W0524 04:14:15.432] INFO: Build completed successfully, 4946 total actions
W0524 04:14:15.436] INFO: Build completed successfully, 4946 total actions
W0524 04:14:15.443] 2019/05/24 04:14:15 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 18m7.210134832s
W0524 04:14:15.443] 2019/05/24 04:14:15 util.go:255: Flushing memory.
I0524 04:14:15.543] make: Leaving directory '/go/src/k8s.io/kubernetes'
W0524 04:14:40.618] 2019/05/24 04:14:40 azure.go:674: Deleting resource group: kubetest-dc7fe2c6-7dd7-11e9-9dd9-02421b77f3be.
W0524 04:14:40.892] 2019/05/24 04:14:40 azure.go:427: CWD /go/src/k8s.io/kubernetes
W0524 04:14:40.893] 2019/05/24 04:14:40 azure.go:446: Attempting Docker login with azure cred.
W0524 04:14:41.712] 2019/05/24 04:14:41 azure.go:674: Deleting resource group: kubetest-dc7fe2c6-7dd7-11e9-9dd9-02421b77f3be.
W0524 04:14:41.841] 2019/05/24 04:14:41 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0524 04:14:41.841] 2019/05/24 04:14:41 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"
W0524 04:14:42.917] 2019/05/24 04:14:42 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 1.075984209s
W0524 04:14:42.918] 2019/05/24 04:14:42 main.go:314: Something went wrong: starting e2e cluster: error building hyperkube failed Docker login with error: exit status 1
W0524 04:14:42.920] Traceback (most recent call last):
W0524 04:14:42.920]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0524 04:14:42.920]     main(parse_args())
W0524 04:14:42.920]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0524 04:14:42.921]     mode.start(runner_args)
W0524 04:14:42.921]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0524 04:14:42.921]     check_env(env, self.command, *args)
W0524 04:14:42.921]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0524 04:14:42.921]     subprocess.check_call(cmd, env=env)
W0524 04:14:42.921]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0524 04:14:42.922]     raise CalledProcessError(retcode, cmd)
W0524 04:14:42.924] 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', '--acsengine-agentpoolcount=3', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.15', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-hyperkube=True', '--acsengine-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/Azure/aks-engine/master/examples/kubernetes.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.36.1/aks-engine-v0.36.1-linux-amd64.tar.gz', '--test_args= --ginkgo.skip=\\[sig-node\\]\\sMount\\spropagation|\\[sig-network\\]\\sNetwork\\sshould\\sset\\sTCP\\sCLOSE_WAIT\\stimeout|\\[sig-storage\\]\\sPersistentVolumes-local\\sStress\\swith\\slocal\\svolume\\sprovisioner\\s\\[Serial\\]\\sshould\\suse\\sbe\\sable\\sto\\sprocess\\smany\\spods\\sand\\sreuse\\slocal\\svolumes|should\\sunmount\\sif\\spod\\sis\\sgracefully\\sdeleted\\swhile\\skubelet\\sis\\sdown\\s\\[Disruptive\\]\\[Slow\\]|should\\sunmount\\sif\\spod\\sis\\sforce\\sdeleted\\swhile\\skubelet\\sis\\sdown\\s\\[Disruptive\\]\\[Slow\\]|\\[sig-network\\]\\sServices\\sshould\\sbe\\sable\\sto\\screate\\sa\\sfunctioning\\sNodePort\\sservice|\\[sig-scheduling\\]\\sSchedulerPredicates\\s\\[Serial\\]\\svalidates\\sMaxPods\\slimit\\snumber\\sof\\spods\\sthat\\sare\\sallowed\\sto\\srun\\s\\[Slow\\]|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sDefault\\sshould\\screate\\sand\\sdelete\\sdefault\\spersistent\\svolumes\\s\\[Slow\\]|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sshould\\sprovision\\sstorage\\swith\\sdifferent\\sparameters|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sshould\\stest\\sthat\\sdeleting\\sa\\sclaim\\sbefore\\sthe\\svolume\\sis\\sprovisioned\\sdeletes\\sthe\\svolume.|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\sadopt\\smatching\\sorphans\\sand\\srelease\\snon-matching\\spods|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\snot\\sdeadlock\\swhen\\sa\\spod.s\\spredecessor\\sfails|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\sperform\\srolling\\supdates\\sand\\sroll\\sbacks\\sof\\stemplate\\smodifications\\swith\\sPVCs|\\[sig-apps\\]\\sStatefulSet\\s\\[k8s.io\\]\\sBasic\\sStatefulSet\\sfunctionality\\s\\[StatefulSetBasic\\]\\sshould\\sprovide\\sbasic\\sidentity|\\[sig-storage\\]\\sPersistentVolumes\\sDefault\\sStorageClass\\spods\\sthat\\suse\\smultiple\\svolumes\\sshould\\sbe\\sreschedulable|\\[sig-storage\\]\\sPVC\\sProtection|\\[sig-storage\\]\\sDynamic\\sProvisioning\\s\\[k8s.io\\]\\sGlusterDynamicProvisioner|\\[sig-storage\\]\\sVolumes\\sAzure\\sDisk\\sshould\\sbe\\smountable\\s\\[Slow\\]|\\[sig-apps\\]\\sNetwork\\sPartition\\s\\[Disruptive\\]\\s\\[Slow\\]|\\[sig-network\\]\\sDNS\\sconfigMap|\\[k8s.io\\]\\s\\[sig-node\\]\\sKubelet\\s\\[Serial\\]\\s\\[Slow\\]\\s\\[k8s.io\\]\\s\\[sig-node\\]\\sregular\\sresource\\susage\\stracking\\sresource\\stracking\\sfor\\s0\\spods\\sper\\snode|\\[k8s.io\\]\\s\\[sig-node\\]\\sKubelet\\s\\[Serial\\]\\s\\[Slow\\]\\s\\[k8s.io\\]\\s\\[sig-node\\]\\sregular\\sresource\\susage\\stracking\\sresource\\stracking\\sfor\\s100\\spods\\sper\\snode|Horizontal\\spod\\sautoscaling\\s\\(scale\\sresource:\\sCPU\\)|\\[sig-storage\\]\\sDynamic\\sProvisioning\\sDynamicProvisioner\\sExternal\\sshould\\slet\\san\\sexternal\\sdynamic\\sprovisioner\\screate\\sand\\sdelete\\spersistent\\svolumes\\s\\[Slow\\]|ESIPP|\\[sig-network\\]\\sServices\\sshould\\spreserve\\ssource\\spod\\sIP\\sfor\\straffic\\sthru\\sservice\\scluster\\sIP|In-tree\\sVolumes|PersistentVolumes-local|CSI\\sVolumes|should\\swrite\\sentries\\sto\\s/etc/hosts|\\[Slow\\]|\\[Serial\\]|\\[Disruptive\\]|\\[Flaky\\]|\\[Feature:.+\\]|\\[NodeFeature:.+\\]', '--timeout=420m')' returned non-zero exit status 1
E0524 04:14:42.928] Command failed
I0524 04:14:42.928] process 692 exited with code 1 after 18.6m
E0524 04:14:42.928] FAIL: pull-kubernetes-e2e
I0524 04:14:42.928] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0524 04:14:43.704] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com]
I0524 04:14:43.734] process 3681 exited with code 0 after 0.0m
I0524 04:14:43.735] Call:  gcloud config get-value account
I0524 04:14:43.977] process 3693 exited with code 0 after 0.0m
I0524 04:14:43.977] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com
I0524 04:14:43.978] Upload result and artifacts...
I0524 04:14:43.978] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608
I0524 04:14:43.978] Call:  gsutil ls gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608/artifacts
W0524 04:14:45.158] CommandException: One or more URLs matched no objects.
E0524 04:14:45.236] Command failed
I0524 04:14:45.236] process 3705 exited with code 1 after 0.0m
W0524 04:14:45.236] Remote dir gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608/artifacts not exist yet
I0524 04:14:45.236] 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_kubernetes/7/pull-kubernetes-e2e/1131770095454916608/artifacts
I0524 04:14:46.749] process 3847 exited with code 0 after 0.0m
I0524 04:14:46.750] Call:  git rev-parse HEAD
I0524 04:14:46.753] process 4371 exited with code 0 after 0.0m
I0524 04:14:46.753] Call:  git rev-parse HEAD
I0524 04:14:46.757] process 4372 exited with code 0 after 0.0m
I0524 04:14:46.758] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-kubernetes-e2e/jobResultsCache.json
W0524 04:14:47.703] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/pull-kubernetes-e2e/jobResultsCache.json
I0524 04:14:47.703] process 4373 exited with code 1 after 0.0m
I0524 04:14:47.704] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_FASMUC gs://kubernetes-upstream/pr-logs/directory/pull-kubernetes-e2e/jobResultsCache.json
I0524 04:14:49.122] process 4515 exited with code 0 after 0.0m
I0524 04:14:49.122] Call:  gsutil stat gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/jobResultsCache.json
W0524 04:14:50.046] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/jobResultsCache.json
I0524 04:14:50.046] process 4693 exited with code 1 after 0.0m
I0524 04:14:50.046] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_1lwfLH gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/jobResultsCache.json
I0524 04:14:51.412] process 4835 exited with code 0 after 0.0m
I0524 04:14:51.413] Call:  gsutil -q -h Content-Type:application/json cp /tmp/gsutil_xuxslK gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/1131770095454916608/finished.json
I0524 04:14:52.698] process 5013 exited with code 0 after 0.0m
I0524 04:14:52.699] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_gw2Mmu gs://kubernetes-upstream/pr-logs/pull/feiskyer_kubernetes/7/pull-kubernetes-e2e/latest-build.txt
I0524 04:14:54.105] process 5191 exited with code 0 after 0.0m
I0524 04:14:54.106] Call:  gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_qkqrMn gs://kubernetes-upstream/pr-logs/directory/pull-kubernetes-e2e/latest-build.txt
I0524 04:14:55.488] process 5369 exited with code 0 after 0.0m