This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 766 succeeded
Started2019-09-10 19:01
Elapsed42m11s
Revision
Buildergke-prow-ssd-pool-1a225945-mhkp
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/86e74728-e243-4c1e-9312-731fd67f545b/targets/test'}}
pod43ef8596-d3fd-11e9-9d26-329cee23a2e0
resultstorehttps://source.cloud.google.com/results/invocations/86e74728-e243-4c1e-9312-731fd67f545b/targets/test
infra-commit0deffe524
job-versionv1.17.0-alpha.0.1179+1888745c3db243
master_os_imagecos-73-11647-163-0
node_os_imagecos-73-11647-163-0
pod43ef8596-d3fd-11e9-9d26-329cee23a2e0
revisionv1.17.0-alpha.0.1179+1888745c3db243

No Test Failures!


Show 766 Passed Tests

Show 4150 Skipped Tests

Error lines from build-log.txt

... skipping 12 lines ...
I0910 19:01:22.128] process 45 exited with code 0 after 0.0m
I0910 19:01:22.128] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0910 19:01:22.128] Root: /workspace
I0910 19:01:22.128] cd to /workspace
I0910 19:01:22.129] Configure environment...
I0910 19:01:22.129] Call:  git show -s --format=format:%ct HEAD
W0910 19:01:22.134] fatal: Not a git repository (or any of the parent directories): .git
I0910 19:01:22.135] process 57 exited with code 128 after 0.0m
W0910 19:01:22.135] Unable to print commit date for HEAD
I0910 19:01:22.136] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0910 19:01:22.895] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0910 19:01:23.323] process 58 exited with code 0 after 0.0m
I0910 19:01:23.326] Call:  gcloud config get-value account
... skipping 317 lines ...
W0910 19:05:32.564] NODE_NAMES=bootstrap-e2e-minion-group-2f3r bootstrap-e2e-minion-group-6r01 bootstrap-e2e-minion-group-l4p4 bootstrap-e2e-minion-group-w1r1
W0910 19:05:32.564] Trying to find master named 'bootstrap-e2e-master'
W0910 19:05:32.564] Looking for address 'bootstrap-e2e-master-ip'
I0910 19:05:33.665] Waiting up to 300 seconds for cluster initialization.
I0910 19:05:33.665] 
I0910 19:05:33.665]   This will continually check to see if the API for kubernetes is reachable.
I0910 19:05:33.666]   This may time out if there was some uncaught error during start up.
I0910 19:05:33.666] 
W0910 19:05:33.766] Using master: bootstrap-e2e-master (external IP: 34.82.46.129; internal IP: (not set))
I0910 19:06:54.640] .................Kubernetes cluster created.
W0910 19:06:54.741] 2019/09/10 19:06:46 main.go:754: [Boskos] Update of k8s-boskos-gce-project-04 failed with status 401 Unauthorized, status code 401 updating k8s-boskos-gce-project-04
I0910 19:06:54.857] Cluster "k8s-boskos-gce-project-04_bootstrap-e2e" set.
I0910 19:06:55.073] User "k8s-boskos-gce-project-04_bootstrap-e2e" set.
I0910 19:06:55.306] Context "k8s-boskos-gce-project-04_bootstrap-e2e" created.
I0910 19:06:55.520] Switched to context "k8s-boskos-gce-project-04_bootstrap-e2e".
I0910 19:06:55.759] User "k8s-boskos-gce-project-04_bootstrap-e2e-basic-auth" set.
I0910 19:06:55.767] Wrote config for k8s-boskos-gce-project-04_bootstrap-e2e to /workspace/.kube/config
... skipping 2856 lines ...
I0910 19:09:48.883] Sep 10 19:09:20.923: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"sample-webhook-deployment-86d95b659d\" is progressing."}}, CollisionCount:(*int32)(nil)}
I0910 19:09:48.883] Sep 10 19:09:22.924: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"sample-webhook-deployment-86d95b659d\" is progressing."}}, CollisionCount:(*int32)(nil)}
I0910 19:09:48.884] Sep 10 19:09:24.932: INFO: deployment status: v1.DeploymentStatus{ObservedGeneration:1, Replicas:1, UpdatedReplicas:1, ReadyReplicas:0, AvailableReplicas:0, UnavailableReplicas:1, Conditions:[]v1.DeploymentCondition{v1.DeploymentCondition{Type:"Available", Status:"False", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, Reason:"MinimumReplicasUnavailable", Message:"Deployment does not have minimum availability."}, v1.DeploymentCondition{Type:"Progressing", Status:"True", LastUpdateTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63703739352, loc:(*time.Location)(0x84992c0)}}, Reason:"ReplicaSetUpdated", Message:"ReplicaSet \"sample-webhook-deployment-86d95b659d\" is progressing."}}, CollisionCount:(*int32)(nil)}
I0910 19:09:48.884] STEP: Deploying the webhook service
I0910 19:09:48.885] STEP: Verifying the service has paired with the endpoint
I0910 19:09:48.885] Sep 10 19:09:28.071: INFO: Waiting for amount of service:e2e-test-webhook endpoints to be 1
I0910 19:09:48.885] [It] should unconditionally reject operations on fail closed webhook [Conformance]
I0910 19:09:48.885]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0910 19:09:48.885] STEP: Registering a webhook that server cannot talk to, with fail closed policy, via the AdmissionRegistration API
I0910 19:09:48.885] STEP: create a namespace for the webhook
I0910 19:09:48.885] STEP: create a configmap should be unconditionally rejected by the webhook
I0910 19:09:48.885] [AfterEach] [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
I0910 19:09:48.886]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:152
I0910 19:09:48.886] Sep 10 19:09:29.207: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
I0910 19:09:48.886] STEP: Destroying namespace "webhook-4127" for this suite.
... skipping 6 lines ...
I0910 19:09:48.887]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/webhook.go:103
I0910 19:09:48.887] 
I0910 19:09:48.887] 
I0910 19:09:48.887] • [SLOW TEST:40.646 seconds]
I0910 19:09:48.887] [sig-api-machinery] AdmissionWebhook [Privileged:ClusterAdmin]
I0910 19:09:48.887] /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/apimachinery/framework.go:23
I0910 19:09:48.887]   should unconditionally reject operations on fail closed webhook [Conformance]
I0910 19:09:48.887]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:698
I0910 19:09:48.888] ------------------------------
I0910 19:09:50.268] SS
I0910 19:09:50.269] ------------------------------
I0910 19:09:50.269] [BeforeEach] [sig-apps] Deployment
I0910 19:09:50.269]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:151
... skipping 632 lines ...
I0910 19:10:04.492] STEP: Scaling down replication controller to zero
I0910 19:10:04.492] STEP: Scaling ReplicationController slow-terminating-unready-pod in namespace services-2604 to 0
I0910 19:10:04.492] STEP: Update service to not tolerate unready services
I0910 19:10:04.492] STEP: Check if pod is unreachable
I0910 19:10:04.493] Sep 10 19:09:39.546: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://34.82.46.129 --kubeconfig=/workspace/.kube/config exec --namespace=services-2604 execpod-zntkh -- /bin/sh -x -c curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/; test "$?" -ne "0"'
I0910 19:10:04.493] Sep 10 19:09:40.622: INFO: rc: 1
I0910 19:10:04.494] Sep 10 19:09:40.622: INFO: expected un-ready endpoint for Service slow-terminating-unready-pod, stdout: , err error running &{/workspace/kubernetes/platforms/linux/amd64/kubectl [kubectl --server=https://34.82.46.129 --kubeconfig=/workspace/.kube/config exec --namespace=services-2604 execpod-zntkh -- /bin/sh -x -c curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/; test "$?" -ne "0"] []  <nil> NOW: 2019-09-10 19:09:40.488470162 +0000 UTC m=+8.850913881 + curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/
I0910 19:10:04.494] + test 0 -ne 0
I0910 19:10:04.494] command terminated with exit code 1
I0910 19:10:04.495]  [] <nil> 0xc0033376e0 exit status 1 <nil> <nil> true [0xc002c2dd18 0xc002c2dd30 0xc002c2dd48] [0xc002c2dd18 0xc002c2dd30 0xc002c2dd48] [0xc002c2dd28 0xc002c2dd40] [0x10ef2c0 0x10ef2c0] 0xc002deb9e0 <nil>}:
I0910 19:10:04.495] Command stdout:
I0910 19:10:04.495] NOW: 2019-09-10 19:09:40.488470162 +0000 UTC m=+8.850913881
I0910 19:10:04.495] stderr:
I0910 19:10:04.495] + curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/
I0910 19:10:04.495] + test 0 -ne 0
I0910 19:10:04.496] command terminated with exit code 1
I0910 19:10:04.496] 
I0910 19:10:04.496] error:
I0910 19:10:04.496] exit status 1
I0910 19:10:04.496] Sep 10 19:09:42.623: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://34.82.46.129 --kubeconfig=/workspace/.kube/config exec --namespace=services-2604 execpod-zntkh -- /bin/sh -x -c curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/; test "$?" -ne "0"'
I0910 19:10:04.497] Sep 10 19:09:43.442: INFO: rc: 1
I0910 19:10:04.497] Sep 10 19:09:43.442: INFO: expected un-ready endpoint for Service slow-terminating-unready-pod, stdout: , err error running &{/workspace/kubernetes/platforms/linux/amd64/kubectl [kubectl --server=https://34.82.46.129 --kubeconfig=/workspace/.kube/config exec --namespace=services-2604 execpod-zntkh -- /bin/sh -x -c curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/; test "$?" -ne "0"] []  <nil> NOW: 2019-09-10 19:09:43.365286126 +0000 UTC m=+11.727729804 + curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/
I0910 19:10:04.498] + test 0 -ne 0
I0910 19:10:04.498] command terminated with exit code 1
I0910 19:10:04.499]  [] <nil> 0xc00337e0f0 exit status 1 <nil> <nil> true [0xc0024db7a0 0xc0024db7b8 0xc0024db7d8] [0xc0024db7a0 0xc0024db7b8 0xc0024db7d8] [0xc0024db7b0 0xc0024db7c8] [0x10ef2c0 0x10ef2c0] 0xc0022f2960 <nil>}:
I0910 19:10:04.499] Command stdout:
I0910 19:10:04.499] NOW: 2019-09-10 19:09:43.365286126 +0000 UTC m=+11.727729804
I0910 19:10:04.499] stderr:
I0910 19:10:04.499] + curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/
I0910 19:10:04.500] + test 0 -ne 0
I0910 19:10:04.500] command terminated with exit code 1
I0910 19:10:04.500] 
I0910 19:10:04.501] error:
I0910 19:10:04.501] exit status 1
I0910 19:10:04.501] Sep 10 19:09:44.622: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://34.82.46.129 --kubeconfig=/workspace/.kube/config exec --namespace=services-2604 execpod-zntkh -- /bin/sh -x -c curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/; test "$?" -ne "0"'
I0910 19:10:04.502] Sep 10 19:09:46.444: INFO: stderr: "+ curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/\n+ test 7 -ne 0\n"
I0910 19:10:04.502] Sep 10 19:09:46.444: INFO: stdout: ""
I0910 19:10:04.502] STEP: Update service to tolerate unready services again
I0910 19:10:04.502] STEP: Check if terminating pod is available through service
I0910 19:10:04.503] Sep 10 19:09:46.590: INFO: Running '/workspace/kubernetes/platforms/linux/amd64/kubectl --server=https://34.82.46.129 --kubeconfig=/workspace/.kube/config exec --namespace=services-2604 execpod-zntkh -- /bin/sh -x -c curl -q -s --connect-timeout 2 http://tolerate-unready.services-2604.svc.cluster.local:80/'
I0910 19:10:04.503] Sep 10 19:09:48.642: INF