This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 63 succeeded
Started2020-01-17 11:48
Elapsed44m22s
Revision
Buildergke-prow-default-pool-cf4891d4-ppqk
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/e33bcba9-8138-49de-9cee-c07b730d29fd/targets/test'}}
pod2f46c1a9-391f-11ea-8780-468616f72408
resultstorehttps://source.cloud.google.com/results/invocations/e33bcba9-8138-49de-9cee-c07b730d29fd/targets/test
infra-commitf3c464c7f
job-versionv1.11.2-beta.0.20+21777efd99bd59
master_os_imagecos-stable-65-10323-64-0
node_os_imagecos-stable-65-10323-64-0
pod2f46c1a9-391f-11ea-8780-468616f72408
repogithub.com/containerd/cri
repo-commitb2804c06934245b0ff4a9114c9f1f592a5120815
repos{u'k8s.io/examples': u'master', u'github.com/containerd/cri': u'master'}
revisionv1.11.2-beta.0.20+21777efd99bd59

Test Failures


listResources After 1m24s

Failed to list resources (error during ./cluster/gce/list-resources.sh: exit status 2):
Project: k8s-jkns-gci-gce-slow-1-6
Region: 
Zone: 
Instance prefix: bootstrap-e2e
Network: bootstrap-e2e
Provider: gce


[ compute instance-templates ]



[ compute instance-groups ]



[ compute instances ]

				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 63 Passed Tests

Show 952 Skipped Tests

Error lines from build-log.txt

... skipping 459 lines ...
W0117 11:50:01.110] Project: k8s-jkns-gci-gce-slow-1-6
W0117 11:50:01.111] Network Project: k8s-jkns-gci-gce-slow-1-6
W0117 11:50:01.111] Zone: asia-southeast1-a
I0117 11:50:02.679] Bringing down cluster
W0117 11:50:02.794] INSTANCE_GROUPS=
W0117 11:50:02.794] NODE_NAMES=
W0117 11:50:06.368] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 11:50:06.369]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 11:50:06.369]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 11:50:06.370]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 11:50:06.370] 
W0117 11:50:13.254] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 11:50:13.254]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 11:50:13.254]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 11:50:13.255]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 11:50:13.255] 
W0117 11:50:21.905] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 11:50:21.906]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 11:50:21.906]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 11:50:21.906]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 11:50:21.906] 
I0117 11:50:30.611] Deleting firewall rules remaining in network bootstrap-e2e: 
I0117 11:50:33.209] Property "clusters.k8s-jkns-gci-gce-slow-1-6_bootstrap-e2e" unset.
... skipping 4 lines ...
I0117 11:50:33.837] Done
W0117 11:50:33.938] 2020/01/17 11:50:33 process.go:155: Step './hack/e2e-internal/e2e-down.sh' finished in 37.323485337s
W0117 11:50:33.938] 2020/01/17 11:50:33 e2e.go:456: Listing resources...
W0117 11:50:33.938] 2020/01/17 11:50:33 process.go:153: Running: ./cluster/gce/list-resources.sh
W0117 11:50:34.881] Listed 0 items.
W0117 11:50:36.053] Listed 0 items.
W0117 11:50:37.667] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 11:50:37.668]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 11:50:37.668]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 11:50:37.668]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 11:50:37.668] 
W0117 11:50:37.780] Attempt 1 failed to list instances. Retrying.
W0117 11:50:49.556] Listed 0 items.
W0117 11:50:50.672] Listed 0 items.
W0117 11:50:51.724] Listed 0 items.
W0117 11:50:53.796] 
W0117 11:50:53.797] To show all fields of the firewall, please show in JSON format: --format=json
W0117 11:50:53.797] To show all fields in table format, please see the examples in --help.
... skipping 17 lines ...
I0117 11:51:00.779] +++ Staging server tars to Google Storage: gs://kubernetes-staging-673da3137c-asia/bootstrap-e2e-devel
I0117 11:51:05.052] +++ kubernetes-server-linux-amd64.tar.gz uploaded earlier, cloud and local file md5 match (md5 = 1b380ae96ad3f894573cfd9a89158ce0)
I0117 11:51:07.977] +++ kubernetes-manifests.tar.gz uploaded earlier, cloud and local file md5 match (md5 = c8bd7d314c0380671abb3e4180234188)
W0117 11:51:09.083] INSTANCE_GROUPS=
W0117 11:51:09.083] NODE_NAMES=
I0117 11:51:09.184] Looking for already existing resources
W0117 11:51:14.105] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 11:51:14.106]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 11:51:14.106]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 11:51:14.106]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 11:51:14.106] 
I0117 11:51:20.004] Creating new auto network: bootstrap-e2e
W0117 11:52:14.033] Created [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/global/networks/bootstrap-e2e].
... skipping 92 lines ...
W0117 11:53:30.475] NODE_NAMES=bootstrap-e2e-minion-group-1wg0 bootstrap-e2e-minion-group-67dq bootstrap-e2e-minion-group-7zh5 bootstrap-e2e-minion-group-plkm
W0117 11:53:30.476] Trying to find master named 'bootstrap-e2e-master'
W0117 11:53:30.476] Looking for address 'bootstrap-e2e-master-ip'
I0117 11:53:32.378] Waiting up to 300 seconds for cluster initialization.
I0117 11:53:32.379] 
I0117 11:53:32.379]   This will continually check to see if the API for kubernetes is reachable.
I0117 11:53:32.379]   This may time out if there was some uncaught error during start up.
I0117 11:53:32.379] 
W0117 11:53:32.482] Using master: bootstrap-e2e-master (external IP: 35.187.228.193)
I0117 11:55:36.901] .........................................Kubernetes cluster created.
I0117 11:55:37.472] Cluster "k8s-jkns-gci-gce-slow-1-6_bootstrap-e2e" set.
I0117 11:55:38.056] User "k8s-jkns-gci-gce-slow-1-6_bootstrap-e2e" set.
I0117 11:55:38.824] Context "k8s-jkns-gci-gce-slow-1-6_bootstrap-e2e" created.
... skipping 28 lines ...
I0117 11:56:59.484] bootstrap-e2e-master              Ready,SchedulingDisabled   <none>    36s       v1.11.2-beta.0.20+21777efd99bd59
I0117 11:56:59.485] bootstrap-e2e-minion-group-1wg0   Ready                      <none>    30s       v1.11.2-beta.0.20+21777efd99bd59
I0117 11:56:59.485] bootstrap-e2e-minion-group-67dq   Ready                      <none>    29s       v1.11.2-beta.0.20+21777efd99bd59
I0117 11:56:59.485] bootstrap-e2e-minion-group-7zh5   Ready                      <none>    30s       v1.11.2-beta.0.20+21777efd99bd59
I0117 11:56:59.485] bootstrap-e2e-minion-group-plkm   Ready                      <none>    30s       v1.11.2-beta.0.20+21777efd99bd59
I0117 11:57:01.157] Validate output:
I0117 11:57:02.671] NAME                 STATUS    MESSAGE              ERROR
I0117 11:57:02.672] etcd-1               Healthy   {"health": "true"}   
I0117 11:57:02.672] scheduler            Healthy   ok                   
I0117 11:57:02.672] controller-manager   Healthy   ok                   
I0117 11:57:02.672] etcd-0               Healthy   {"health": "true"}   
I0117 11:57:02.738] Cluster validation succeeded
W0117 11:57:02.839] Done, listing cluster services:
... skipping 1586 lines ...
I0117 12:03:00.817] Jan 17 12:02:30.190: INFO: Pod "nodeport-test-ls5x2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.395837662s
I0117 12:03:00.817] Jan 17 12:02:32.380: INFO: Pod "nodeport-test-ls5x2": Phase="Running", Reason="", readiness=true. Elapsed: 4.586203072s
I0117 12:03:00.817] Jan 17 12:02:32.380: INFO: Pod "nodeport-test-ls5x2" satisfied condition "running and ready"
I0117 12:03:00.817] Jan 17 12:02:32.380: INFO: Wanted all 1 pods to be running and ready. Result: true. Pods: [nodeport-test-ls5x2]
I0117 12:03:00.818] STEP: hitting the pod through the service's NodePort
I0117 12:03:00.818] Jan 17 12:02:32.381: INFO: Testing HTTP reachability of http://34.87.4.239:30116/echo?msg=hello
I0117 12:03:00.819] Jan 17 12:02:32.566: INFO: Got error testing for reachability of http://34.87.4.239:30116/echo?msg=hello: Get http://34.87.4.239:30116/echo?msg=hello: dial tcp 34.87.4.239:30116: connect: connection refused
I0117 12:03:00.819] Jan 17 12:02:34.574: INFO: Testing HTTP reachability of http://34.87.4.239:30116/echo?msg=hello
I0117 12:03:00.819] Jan 17 12:02:34.760: INFO: Got error testing for reachability of http://34.87.4.239:30116/echo?msg=hello: Get http://34.87.4.239:30116/echo?msg=hello: dial tcp 34.87.4.239:30116: connect: connection refused
I0117 12:03:00.820] Jan 17 12:02:36.570: INFO: Testing HTTP reachability of http://34.87.4.239:30116/echo?msg=hello
I0117 12:03:00.820] Jan 17 12:02:36.753: INFO: Got error testing for reachability of http://34.87.4.239:30116/echo?msg=hello: Get http://34.87.4.239:30116/echo?msg=hello: dial tcp 34.87.4.239:30116: connect: connection refused
I0117 12:03:00.820] Jan 17 12:02:38.570: INFO: Testing HTTP reachability of http://34.87.4.239:30116/echo?msg=hello
I0117 12:03:00.820] Jan 17 12:02:38.758: INFO: Got error testing for reachability of http://34.87.4.239:30116/echo?msg=hello: Get http://34.87.4.239:30116/echo?msg=hello: dial tcp 34.87.4.239:30116: connect: connection refused
I0117 12:03:00.821] Jan 17 12:02:40.576: INFO: Testing HTTP reachability of http://34.87.4.239:30116/echo?msg=hello
I0117 12:03:00.821] STEP: verifying the node port is locked
I0117 12:03:00.821] Jan 17 12:02:43.624: INFO: Running '/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-vwsdm hostexec -- /bin/sh -c for i in $(seq 1 300); do if ss -ant46 'sport = :30116' | grep ^LISTEN; then exit 0; fi; sleep 1; done; exit 1'
I0117 12:03:00.822] Jan 17 12:02:46.135: INFO: stderr: ""
I0117 12:03:00.823] Jan 17 12:02:46.135: INFO: stdout: "LISTEN     0      128         :::30116                   :::*                  \n"
I0117 12:03:00.823] [AfterEach] [sig-network] Services
... skipping 111 lines ...
I0117 12:03:19.167] [BeforeEach] [sig-network] Services
I0117 12:03:19.167]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/network/service.go:83
I0117 12:03:19.167] [It] should serve multiport endpoints from pods  [Conformance]
I0117 12:03:19.168]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:684
I0117 12:03:19.168] STEP: creating service multi-endpoint-test in namespace e2e-tests-services-s6nc6
I0117 12:03:19.168] STEP: waiting up to 3m0s for service multi-endpoint-test in namespace e2e-tests-services-s6nc6 to expose endpoints map[]
I0117 12:03:19.168] Jan 17 12:02:25.086: INFO: Get endpoints failed (188.125598ms elapsed, ignoring for 5s): endpoints "multi-endpoint-test" not found
I0117 12:03:19.169] Jan 17 12:02:26.279: INFO: successfully validated that service multi-endpoint-test in namespace e2e-tests-services-s6nc6 exposes endpoints map[] (1.380824898s elapsed)
I0117 12:03:19.169] STEP: Creating pod pod1 in namespace e2e-tests-services-s6nc6
I0117 12:03:19.169] STEP: waiting up to 3m0s for service multi-endpoint-test in namespace e2e-tests-services-s6nc6 to expose endpoints map[pod1:[100]]
I0117 12:03:19.169] Jan 17 12:02:31.011: INFO: successfully validated that service multi-endpoint-test in namespace e2e-tests-services-s6nc6 exposes endpoints map[pod1:[100]] (4.533989928s elapsed)
I0117 12:03:19.172] STEP: Creating pod pod2 in namespace e2e-tests-services-s6nc6
I0117 12:03:19.172] STEP: waiting up to 3m0s for service multi-endpoint-test in namespace e2e-tests-services-s6nc6 to expose endpoints map[pod1:[100] pod2:[101]]
... skipping 80 lines ...
I0117 12:03:21.813] [BeforeEach] [sig-network] Services
I0117 12:03:21.813]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/network/service.go:83
I0117 12:03:21.813] [It] should serve a basic endpoint from pods  [Conformance]
I0117 12:03:21.813]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework/framework.go:684
I0117 12:03:21.814] STEP: creating service endpoint-test2 in namespace e2e-tests-services-jskn9
I0117 12:03:21.814] STEP: waiting up to 3m0s for service endpoint-test2 in namespace e2e-tests-services-jskn9 to expose endpoints map[]
I0117 12:03:21.814] Jan 17 12:02:25.623: INFO: Get endpoints failed (204.875501ms elapsed, ignoring for 5s): endpoints "endpoint-test2" not found
I0117 12:03:21.815] Jan 17 12:02:26.816: INFO: successfully validated that service endpoint-test2 in namespace e2e-tests-services-jskn9 exposes endpoints map[] (1.398338341s elapsed)
I0117 12:03:21.815] STEP: Creating pod pod1 in namespace e2e-tests-services-jskn9
I0117 12:03:21.815] STEP: waiting up to 3m0s for service endpoint-test2 in namespace e2e-tests-services-jskn9 to expose endpoints map[pod1:[80]]
I0117 12:03:21.815] Jan 17 12:02:32.996: INFO: successfully validated that service endpoint-test2 in namespace e2e-tests-services-jskn9 exposes endpoints map[pod1:[80]] (5.982059587s elapsed)
I0117 12:03:21.816] STEP: Creating pod pod2 in namespace e2e-tests-services-jskn9
I0117 12:03:21.816] STEP: waiting up to 3m0s for service endpoint-test2 in namespace e2e-tests-services-jskn9 to expose endpoints map[pod1:[80] pod2:[80]]
... skipping 832 lines ...
I0117 12:04:00.380] Jan 17 12:03:31.735: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 39.712978846s
I0117 12:04:00.381] Jan 17 12:03:33.923: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 41.901364447s
I0117 12:04:00.381] Jan 17 12:03:36.114: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 44.091969136s
I0117 12:04:00.381] Jan 17 12:03:38.302: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 46.280096599s
I0117 12:04:00.406] Jan 17 12:03:40.488: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 48.465680699s
I0117 12:04:00.406] Jan 17 12:03:42.679: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 50.656668869s
I0117 12:04:00.407] Jan 17 12:03:44.870: INFO: Pod "client-b": Phase="Failed", Reason="", readiness=false. Elapsed: 52.847964232s
I0117 12:04:00.407] STEP: Cleaning up the pod client-b
I0117 12:04:00.407] STEP: Cleaning up the policy.
I0117 12:04:00.407] [AfterEach] NetworkPolicy between server and client
I0117 12:04:00.407]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/network/network_policy.go:63
I0117 12:04:00.407] STEP: Cleaning up the server.
I0117 12:04:00.408] STEP: Cleaning up the server's service.
... skipping 170 lines ...
I0117 12:04:07.605] Jan 17 12:03:22.400: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 37.640885111s
I0117 12:04:07.605] Jan 17 12:03:24.611: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 39.852188096s
I0117 12:04:07.606] Jan 17 12:03:26.805: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 42.046449167s
I0117 12:04:07.606] Jan 17 12:03:29.007: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 44.248452636s
I0117 12:04:07.606] Jan 17 12:03:31.207: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 46.447908653s
I0117 12:04:07.606] Jan 17 12:03:33.412: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 48.652698929s
I0117 12:04:07.607] Jan 17 12:03:35.606: INFO: Pod "client-a": Phase="Failed", Reason="", readiness=false. Elapsed: 50.847110137s
I0117 12:04:07.607] STEP: Cleaning up the pod client-a
I0117 12:04:07.607] STEP: Cleaning up the policy.
I0117 12:04:07.607] [AfterEach] NetworkPolicy between server and client
I0117 12:04:07.607]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/network/network_policy.go:63
I0117 12:04:07.607] STEP: Cleaning up the server.
I0117 12:04:07.607] STEP: Cleaning up the server's service.
... skipping 171 lines ...
I0117 12:04:10.035] Jan 17 12:03:37.138: INFO: Pod "client-cannot-connect": Phase="Running", Reason="", readiness=true. Elapsed: 35.376129593s
I0117 12:04:10.036] Jan 17 12:03:39.329: INFO: Pod "client-cannot-connect": Phase="Running", Reason="", readiness=true. Elapsed: 37.567478894s
I0117 12:04:10.036] Jan 17 12:03:41.526: INFO: Pod "client-cannot-connect": Phase="Running", Reason="", readiness=true. Elapsed: 39.764303886s
I0117 12:04:10.036] Jan 17 12:03:43.738: INFO: Pod "client-cannot-connect": Phase="Running", Reason="", readiness=true. Elapsed: 41.976311132s
I0117 12:04:10.036] Jan 17 12:03:45.934: INFO: Pod "client-cannot-connect": Phase="Running", Reason="", readiness=true. Elapsed: 44.172396119s
I0117 12:04:10.036] Jan 17 12:03:48.127: INFO: Pod "client-cannot-connect": Phase="Running", Reason="", readiness=true. Elapsed: 46.365048453s
I0117 12:04:10.037] Jan 17 12:03:50.322: INFO: Pod "client-cannot-connect": Phase="Failed", Reason="", readiness=false. Elapsed: 48.560422712s
I0117 12:04:10.037] STEP: Cleaning up the pod client-cannot-connect
I0117 12:04:10.037] STEP: Cleaning up the policy.
I0117 12:04:10.037] [AfterEach] NetworkPolicy between server and client
I0117 12:04:10.037]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/network/network_policy.go:63
I0117 12:04:10.037] STEP: Cleaning up the server.
I0117 12:04:10.038] STEP: Cleaning up the server's service.
... skipping 254 lines ...
I0117 12:04:16.587] Jan 17 12:03:29.881: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 37.547891351s
I0117 12:04:16.588] Jan 17 12:03:32.070: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 39.737064576s
I0117 12:04:16.588] Jan 17 12:03:34.271: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 41.937640611s
I0117 12:04:16.588] Jan 17 12:03:36.465: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 44.132425527s
I0117 12:04:16.588] Jan 17 12:03:38.654: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 46.320960351s
I0117 12:04:16.589] Jan 17 12:03:40.846: INFO: Pod "client-b": Phase="Running", Reason="", readiness=true. Elapsed: 48.512950754s
I0117 12:04:16.589] Jan 17 12:03:43.036: INFO: Pod "client-b": Phase="Failed", Reason="", readiness=false. Elapsed: 50.702938827s
I0117 12:04:16.589] STEP: Cleaning up the pod client-b
I0117 12:04:16.589] STEP: Cleaning up the policy.
I0117 12:04:16.589] [AfterEach] NetworkPolicy between server and client
I0117 12:04:16.589]   /go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/network/network_policy.go:63
I0117 12:04:16.589] STEP: Cleaning up the server.
I0117 12:04:16.589] STEP: Cleaning up the server's service.
... skipping 160 lines ...
I0117 12:04:30.235] Jan 17 12:03:56.942: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 37.440176654s
I0117 12:04:30.235] Jan 17 12:03:59.129: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 39.627379337s
I0117 12:04:30.235] Jan 17 12:04:01.317: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 41.815414627s
I0117 12:04:30.236] Jan 17 12:04:03.505: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 44.003245415s
I0117 12:04:30.236] Jan 17 12:04:05.694: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 46.191727712s
I0117 12:04:30.242] Jan 17 12:04:07.890: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 48.387911319s
I0117 12:04:30.243] Jan 17 12:04:10.082: INFO: Pod "client-a": Phase="Failed", Reason="", readiness=false. Elapsed: 50.579894573s
I0117 12:04:30.243] STEP: Cleaning up the pod client-a
I0117 12:04:30.243] STEP: Creating client pod client-b that should successfully connect to svc-server.
I0117 12:04:30.243] Jan 17 12:04:10.486: INFO: Waiting for client-b to complete.
I0117 12:04:30.243] Jan 17 12:04:14.857: INFO: Waiting for client-b to complete.
I0117 12:04:30.244] Jan 17 12:04:14.857: INFO: Waiting up to 5m0s for pod "client-b" in namespace "e2e-tests-network-policy-4xcj2" to be "success or failure"
I0117 12:04:30.244] Jan 17 12:04:15.042: INFO: Pod "client-b": Phase="Succeeded", Reason="", readiness=false. Elapsed: 184.799606ms
... skipping 185 lines ...
I0117 12:04:42.592] Jan 17 12:03:52.994: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 37.656298573s
I0117 12:04:42.592] Jan 17 12:03:55.180: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 39.842045224s
I0117 12:04:42.593] Jan 17 12:03:57.370: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 42.03218903s
I0117 12:04:42.593] Jan 17 12:03:59.562: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 44.224194136s
I0117 12:04:42.593] Jan 17 12:04:01.750: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 46.411999473s
I0117 12:04:42.593] Jan 17 12:04:03.938: INFO: Pod "client-a": Phase="Running", Reason="", readiness=true. Elapsed: 48.600209374s
I0117 12:04:42.593] Jan 17 12:04:06.130: INFO: Pod "client-a": Phase="Failed", Reason="", readiness=false. Elapsed: 50.792161532s
I0117 12:04:42.598] STEP: Cleaning up the pod client-a
I0117 12:04:42.598] STEP: Creating client pod client-b that should successfully connect to svc-server.
I0117 12:04:42.598] Jan 17 12:04:06.546: INFO: Waiting for client-b to complete.
I0117 12:04:42.598] Jan 17 12:04:08.938: INFO: Waiting for client-b to complete.
I0117 12:04:42.599] Jan 17 12:04:08.938: INFO: Waiting up to 5m0s for pod "client-b" in namespace "e2e-tests-network-policy-b-r8gvn" to be "success or failure"
I0117 12:04:42.599] Jan 17 12:04:09.126: INFO: Pod "client-b": Phase="Succeeded", Reason="", readiness=false. Elapsed: 187.805265ms
... skipping 154 lines ...
I0117 12:05:05.152] STEP: Scaling down replication controller to zero
I0117 12:05:05.153] STEP: Scaling { ReplicationController} slow-terminating-unready-pod in namespace e2e-tests-services-d5g4b to 0
I0117 12:05:05.153] STEP: Update service to not tolerate unready services
I0117 12:05:05.153] STEP: Check if pod is unreachable
I0117 12:05:05.153] Jan 17 12:02:24.686: INFO: Running '/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-d5g4b execpod-d9r67 -- /bin/sh -c wget -qO- -T 2 http://tolerate-unready.e2e-tests-services-d5g4b.svc.cluster.local:80/; test "$?" -eq "1"'
I0117 12:05:05.153] Jan 17 12:02:27.411: INFO: rc: 1
I0117 12:05:05.154] Jan 17 12:02:27.411: INFO: expected un-ready endpoint for Service slow-terminating-unready-pod, stdout: , err error running &{/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl [kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-d5g4b execpod-d9r67 -- /bin/sh -c wget -qO- -T 2 http://tolerate-unready.e2e-tests-services-d5g4b.svc.cluster.local:80/; test "$?" -eq "1"] []  <nil> NOW: 2020-01-17 12:02:27.265134995 +0000 UTC m=+16.344015533 command terminated with exit code 1
I0117 12:05:05.154]  [] <nil> 0xc42194e960 exit status 1 <nil> <nil> true [0xc4214ee208 0xc4214ee220 0xc4214ee238] [0xc4214ee208 0xc4214ee220 0xc4214ee238] [0xc4214ee218 0xc4214ee230] [0x8f9080 0x8f9080] 0xc421201bc0 <nil>}:
I0117 12:05:05.155] Command stdout:
I0117 12:05:05.155] NOW: 2020-01-17 12:02:27.265134995 +0000 UTC m=+16.344015533
I0117 12:05:05.155] stderr:
I0117 12:05:05.155] command terminated with exit code 1
I0117 12:05:05.155] 
I0117 12:05:05.156] error:
I0117 12:05:05.156] exit status 1
I0117 12:05:05.156] 
I0117 12:05:05.156] Jan 17 12:02:29.411: INFO: Running '/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-d5g4b execpod-d9r67 -- /bin/sh -c wget -qO- -T 2 http://tolerate-unready.e2e-tests-services-d5g4b.svc.cluster.local:80/; test "$?" -eq "1"'
I0117 12:05:05.156] Jan 17 12:02:34.332: INFO: stderr: "wget: download timed out\n"
I0117 12:05:05.157] Jan 17 12:02:34.332: INFO: stdout: ""
I0117 12:05:05.157] STEP: Update service to tolerate unready services again
I0117 12:05:05.157] STEP: Check if terminating pod is available through service
I0117 12:05:05.157] Jan 17 12:02:34.734: INFO: Running '/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-d5g4b execpod-d9r67 -- /bin/sh -c wget -qO- http://tolerate-unready.e2e-tests-services-d5g4b.svc.cluster.local:80/'
I0117 12:05:05.158] Jan 17 12:04:44.830: INFO: rc: 1
I0117 12:05:05.159] Jan 17 12:04:44.830: INFO: expected un-ready endpoint for Service slow-terminating-unready-pod, stdout: , err error running &{/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl [kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-d5g4b execpod-d9r67 -- /bin/sh -c wget -qO- http://tolerate-unready.e2e-tests-services-d5g4b.svc.cluster.local:80/] []  <nil>  wget: can't connect to remote host (10.0.78.108): Connection timed out
I0117 12:05:05.159] command terminated with exit code 1
I0117 12:05:05.159]  [] <nil> 0xc42194f2c0 exit status 1 <nil> <nil> true [0xc4214ee268 0xc4214ee280 0xc4214ee298] [0xc4214ee268 0xc4214ee280 0xc4214ee298] [0xc4214ee278 0xc4214ee290] [0x8f9080 0x8f9080] 0xc421201d40 <nil>}:
I0117 12:05:05.159] Command stdout:
I0117 12:05:05.159] 
I0117 12:05:05.159] stderr:
I0117 12:05:05.160] wget: can't connect to remote host (10.0.78.108): Connection timed out
I0117 12:05:05.160] command terminated with exit code 1
I0117 12:05:05.160] 
I0117 12:05:05.160] error:
I0117 12:05:05.160] exit status 1
I0117 12:05:05.160] 
I0117 12:05:05.160] Jan 17 12:04:46.831: INFO: Running '/go/src/github.com/containerd/cri/kubernetes/platforms/linux/amd64/kubectl --server=https://35.187.228.193 --kubeconfig=/workspace/.kube/config exec --namespace=e2e-tests-services-d5g4b execpod-d9r67 -- /bin/sh -c wget -qO- http://tolerate-unready.e2e-tests-services-d5g4b.svc.cluster.local:80/'
I0117 12:05:05.161] Jan 17 12:04:49.319: INFO: stderr: ""
I0117 12:05:05.161] Jan 17 12:04:49.319: INFO: stdout: "NOW: 2020-01-17 12:04:49.059219685 +0000 UTC m=+158.138100221"
I0117 12:05:05.161] STEP: Remove pods immediately
... skipping 104 lines ...
I0117 12:06:22.480] Jan 17 12:04:16.813: INFO: Running AfterSuite actions on all node
I0117 12:06:22.480] Jan 17 12:06:22.478: INFO: Running AfterSuite actions on node 1
I0117 12:06:22.481] Jan 17 12:06:22.478: INFO: Skipping dumping logs from cluster
I0117 12:06:22.481] 
I0117 12:06:22.481] 
I0117 12:06:22.481] Ran 47 of 999 Specs in 504.113 seconds
I0117 12:06:22.516] SUCCESS! -- 47 Passed | 0 Failed | 0 Pending | 952 Skipped 
I0117 12:06:22.516] 
I0117 12:06:22.516] Ginkgo ran 1 suite in 8m27.404912359s
I0117 12:06:22.516] Test Suite Passed
I0117 12:06:22.540] Checking for custom logdump instances, if any
I0117 12:06:22.557] Sourcing kube-util.sh
I0117 12:06:22.651] Detecting project
... skipping 11 lines ...
I0117 12:07:57.936] Copying 'kube-apiserver kube-apiserver-audit kube-scheduler rescheduler kube-controller-manager etcd etcd-events glbc cluster-autoscaler kube-addon-manager fluentd startupscript' from bootstrap-e2e-master
W0117 12:08:01.643] 
W0117 12:08:01.643] Specify --start=42927 in the next get-serial-port-output invocation to get only the new output starting from here.
W0117 12:08:16.150] scp: /var/log/cluster-autoscaler.log*: No such file or directory
W0117 12:08:16.526] scp: /var/log/fluentd.log*: No such file or directory
W0117 12:08:16.526] scp: /var/log/startupscript.log*: No such file or directory
W0117 12:08:16.542] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
I0117 12:08:16.744] Dumping logs from nodes locally to '/workspace/_artifacts'
I0117 12:08:16.744] Detecting nodes in the cluster
I0117 12:10:10.883] Changing logfiles to be world-readable for download
I0117 12:10:11.647] Changing logfiles to be world-readable for download
I0117 12:10:12.608] Changing logfiles to be world-readable for download
I0117 12:10:13.562] Changing logfiles to be world-readable for download
... skipping 9 lines ...
W0117 12:10:27.306] Specify --start=50017 in the next get-serial-port-output invocation to get only the new output starting from here.
W0117 12:10:29.403] 
W0117 12:10:29.403] Specify --start=49685 in the next get-serial-port-output invocation to get only the new output starting from here.
W0117 12:10:30.978] scp: /var/log/fluentd.log*: No such file or directory
W0117 12:10:30.978] scp: /var/log/node-problem-detector.log*: No such file or directory
W0117 12:10:30.979] scp: /var/log/startupscript.log*: No such file or directory
W0117 12:10:30.996] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0117 12:10:33.049] scp: /var/log/fluentd.log*: No such file or directory
W0117 12:10:33.049] scp: /var/log/node-problem-detector.log*: No such file or directory
W0117 12:10:33.050] scp: /var/log/startupscript.log*: No such file or directory
W0117 12:10:33.080] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0117 12:10:33.726] scp: /var/log/fluentd.log*: No such file or directory
W0117 12:10:33.726] scp: /var/log/node-problem-detector.log*: No such file or directory
W0117 12:10:33.726] scp: /var/log/startupscript.log*: No such file or directory
W0117 12:10:33.727] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0117 12:10:35.499] scp: /var/log/fluentd.log*: No such file or directory
W0117 12:10:35.499] scp: /var/log/node-problem-detector.log*: No such file or directory
W0117 12:10:35.500] scp: /var/log/startupscript.log*: No such file or directory
W0117 12:10:35.509] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0117 12:10:35.645] 2020/01/17 12:10:35 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 4m13.112735262s
W0117 12:10:35.648] 2020/01/17 12:10:35 e2e.go:456: Listing resources...
W0117 12:10:35.649] 2020/01/17 12:10:35 process.go:153: Running: ./cluster/gce/list-resources.sh
W0117 12:10:41.234] WARNING: Some requests did not succeed.
W0117 12:10:41.238]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:10:41.239]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
... skipping 32 lines ...
W0117 12:13:55.381] 
W0117 12:13:57.076] WARNING: Some requests did not succeed.
W0117 12:13:57.076]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:13:57.076]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:13:57.077]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:13:57.077] 
I0117 12:14:00.803] {"message":"Internal Server Error"}Removing etcd replica, name: bootstrap-e2e-master, port: 2379, result: 0
I0117 12:14:04.604] {"message":"Internal Server Error"}Removing etcd replica, name: bootstrap-e2e-master, port: 4002, result: 0
W0117 12:14:10.932] Updated [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/zones/asia-southeast1-a/instances/bootstrap-e2e-master].
W0117 12:16:11.270] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/zones/asia-southeast1-a/instances/bootstrap-e2e-master].
W0117 12:16:15.646] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:16:15.646]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:16:15.647]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:16:15.647]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:16:15.647] 
W0117 12:16:27.540] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/global/firewalls/bootstrap-e2e-master-https].
W0117 12:16:30.742] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/global/firewalls/bootstrap-e2e-minion-all].
W0117 12:16:33.057] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/global/firewalls/bootstrap-e2e-master-etcd].
W0117 12:16:40.830] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/regions/asia-southeast1/addresses/bootstrap-e2e-master-ip].
W0117 12:16:42.880] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:16:42.880]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:16:42.880]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:16:42.881]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:16:42.881] 
I0117 12:16:44.496] Deleting routes bootstrap-e2e-61c822b2-3920-11ea-881f-42010a940002
W0117 12:16:53.052] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-jkns-gci-gce-slow-1-6/global/routes/bootstrap-e2e-61c822b2-3920-11ea-881f-42010a940002].
... skipping 12 lines ...
W0117 12:17:58.987] 2020/01/17 12:17:58 process.go:155: Step './hack/e2e-internal/e2e-down.sh' finished in 7m7.567618118s
W0117 12:17:58.987] 2020/01/17 12:17:58 e2e.go:278: Sleeping for 30 seconds...
W0117 12:18:28.889] 2020/01/17 12:18:28 e2e.go:456: Listing resources...
W0117 12:18:28.890] 2020/01/17 12:18:28 process.go:153: Running: ./cluster/gce/list-resources.sh
W0117 12:18:30.425] Listed 0 items.
W0117 12:18:32.120] Listed 0 items.
W0117 12:18:34.500] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:18:34.500]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:18:34.501]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:18:34.501]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:18:34.501] 
W0117 12:18:34.686] Attempt 1 failed to list instances. Retrying.
W0117 12:18:47.102] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:18:47.103]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:18:47.103]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:18:47.103]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:18:47.103] 
W0117 12:18:47.248] Attempt 2 failed to list instances. Retrying.
W0117 12:19:04.107] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:19:04.108]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:19:04.108]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:19:04.108]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:19:04.109] 
W0117 12:19:04.227] Attempt 3 failed to list instances. Retrying.
W0117 12:19:25.734] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:19:25.734]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:19:25.735]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:19:25.735]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:19:25.735] 
W0117 12:19:25.828] Attempt 4 failed to list instances. Retrying.
W0117 12:19:52.890] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0117 12:19:52.890]  - Invalid value for field 'zone': 'asia-northeast3-a'. Unknown zone.
W0117 12:19:52.891]  - Invalid value for field 'zone': 'asia-northeast3-b'. Unknown zone.
W0117 12:19:52.891]  - Invalid value for field 'zone': 'asia-northeast3-c'. Unknown zone.
W0117 12:19:52.891] 
W0117 12:19:52.980] Attempt 5 failed to list instances. Retrying.
W0117 12:19:52.981] List instances failed!
W0117 12:19:52.981] 2020/01/17 12:19:52 process.go:155: Step './cluster/gce/list-resources.sh' finished in 1m24.092322912s
W0117 12:19:52.993] 2020/01/17 12:19:52 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0117 12:33:07.817] 2020/01/17 12:33:07 main.go:316: Something went wrong: encountered 1 errors: [Failed to list resources (error during ./cluster/gce/list-resources.sh: exit status 2):
W0117 12:33:07.817] Project: k8s-jkns-gci-gce-slow-1-6
W0117 12:33:07.818] Region: 
W0117 12:33:07.818] Zone: 
W0117 12:33:07.818] Instance prefix: bootstrap-e2e
W0117 12:33:07.818] Network: bootstrap-e2e
W0117 12:33:07.819] Provider: gce
... skipping 18 lines ...
W0117 12:33:07.829]     check_env(env, self.command, *args)
W0117 12:33:07.829]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0117 12:33:07.829]     subprocess.check_call(cmd, env=env)
W0117 12:33:07.829]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W0117 12:33:07.829]     raise CalledProcessError(retcode, cmd)
W0117 12:33:07.830] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--provider=gce', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--check-leaked-resources', '--extract=gs://koonwah-repo-pub/devel/v1.11.2-beta.0.20+21777efd99bd59', '--gcp-node-image=gci', '--gcp-nodes=4', '--gcp-zone=asia-southeast1-a', '--ginkgo-parallel=30', '--test_args=--ginkgo.focus=\\[sig-network\\] --ginkgo.skip=\\[Slow\\]|\\[Flaky\\]|\\[Feature:Networking-IPv6\\]|\\[Disruptive\\]|\\[Feature:ServiceLoadBalancer\\]|\\[Feature:PerformanceDNS\\] --minStartupPods=8', '--timeout=120m')' returned non-zero exit status 1
E0117 12:33:07.844] Command failed
I0117 12:33:07.844] process 301 exited with code 1 after 44.0m
E0117 12:33:07.844] FAIL: ci-kubernetes-e2e-containerd-gce-calico
I0117 12:33:07.845] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0117 12:33:08.450] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0117 12:33:08.518] process 10061 exited with code 0 after 0.0m
I0117 12:33:08.518] Call:  gcloud config get-value account
I0117 12:33:08.960] process 10074 exited with code 0 after 0.0m
I0117 12:33:08.960] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0117 12:33:08.960] Upload result and artifacts...
I0117 12:33:08.960] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-kubernetes-e2e-containerd-gce-calico/1218138000156790789
I0117 12:33:08.961] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-containerd-gce-calico/1218138000156790789/artifacts
W0117 12:33:10.142] CommandException: One or more URLs matched no objects.
E0117 12:33:10.324] Command failed
I0117 12:33:10.324] process 10087 exited with code 1 after 0.0m
W0117 12:33:10.324] Remote dir gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-containerd-gce-calico/1218138000156790789/artifacts not exist yet
I0117 12:33:10.325] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-containerd-gce-calico/1218138000156790789/artifacts
I0117 12:33:16.627] process 10232 exited with code 0 after 0.1m
I0117 12:33:16.628] Call:  git rev-parse HEAD
I0117 12:33:16.633] process 10930 exited with code 0 after 0.0m
... skipping 13 lines ...