This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 18 succeeded
Started2020-02-09 19:51
Elapsed17m7s
Revision
Buildergke-prow-default-pool-cf4891d4-wb79
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/fa9175ce-35a7-486c-b56e-e2391180085f/targets/test'}}
pod83976184-4b75-11ea-9d03-564fe64f5f51
resultstorehttps://source.cloud.google.com/results/invocations/fa9175ce-35a7-486c-b56e-e2391180085f/targets/test
infra-commit98f6243d9
job-versionv1.15.10-beta.0.21+01860e753e6611
master_os_image
node_os_imagecos-77-12371-89-0
pod83976184-4b75-11ea-9d03-564fe64f5f51
revisionv1.15.10-beta.0.21+01860e753e6611

Test Failures


listResources After 1m18s

Failed to list resources (error during ./cluster/gce/list-resources.sh: exit status 2):
Project: k8s-gke-upg-g1-5-g1-6-up-clu-n
Region: us-central1
Zone: us-central1-c
Instance prefix: gke-bootstrap-e2e
Network: bootstrap-e2e
Provider: 


[ compute instance-templates ]



[ compute instance-groups ]



[ compute instances ]

				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 18 Passed Tests

Show 4410 Skipped Tests

Error lines from build-log.txt

... skipping 15 lines ...
I0209 19:51:56.105] process 48 exited with code 0 after 0.0m
I0209 19:51:56.106] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0209 19:51:56.106] Root: /workspace
I0209 19:51:56.106] cd to /workspace
I0209 19:51:56.106] Configure environment...
I0209 19:51:56.107] Call:  git show -s --format=format:%ct HEAD
W0209 19:51:56.110] fatal: not a git repository (or any of the parent directories): .git
I0209 19:51:56.110] process 61 exited with code 128 after 0.0m
W0209 19:51:56.111] Unable to print commit date for HEAD
I0209 19:51:56.111] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0209 19:51:56.729] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0209 19:51:57.111] process 62 exited with code 0 after 0.0m
I0209 19:51:57.111] Call:  gcloud config get-value account
... skipping 461 lines ...
I0209 19:54:11.039] Extracting /workspace/kubernetes/test/kubernetes-test-linux-amd64.tar.gz into /workspace/kubernetes/platforms/linux/amd64
W0209 19:54:22.622] 2020/02/09 19:54:22 process.go:155: Step './get-kube.sh' finished in 24.375789994s
W0209 19:54:23.500] 2020/02/09 19:54:23 e2e.go:456: Listing resources...
W0209 19:54:23.500] 2020/02/09 19:54:23 process.go:153: Running: ./cluster/gce/list-resources.sh
W0209 19:54:24.389] Listed 0 items.
W0209 19:54:25.368] Listed 0 items.
W0209 19:54:26.972] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 19:54:26.972]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 19:54:26.972]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 19:54:26.972]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 19:54:26.972] 
W0209 19:54:27.076] Attempt 1 failed to list instances. Retrying.
W0209 19:54:38.896] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 19:54:38.896]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 19:54:38.896]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 19:54:38.896]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 19:54:38.897] 
W0209 19:54:38.982] Attempt 2 failed to list instances. Retrying.
W0209 19:54:55.600] Listed 0 items.
W0209 19:54:56.685] Listed 0 items.
W0209 19:54:57.887] Listed 0 items.
W0209 19:55:00.912] 
W0209 19:55:00.912] To show all fields of the firewall, please show in JSON format: --format=json
W0209 19:55:00.912] To show all fields in table format, please see the examples in --help.
... skipping 89 lines ...
I0209 20:01:10.774] 
I0209 20:01:13.536] Feb  9 20:01:13.536: INFO: cluster-master-image: 
I0209 20:01:13.536] Feb  9 20:01:13.536: INFO: cluster-node-image: cos-77-12371-89-0
I0209 20:01:13.536] Feb  9 20:01:13.536: INFO: >>> kubeConfig: /tmp/gke-kubecfg021579416
I0209 20:01:13.538] Feb  9 20:01:13.538: INFO: Waiting up to 30m0s for all (but 0) nodes to be schedulable
I0209 20:01:13.549] Feb  9 20:01:13.549: INFO: Waiting up to 10m0s for all pods (need at least 8) in namespace 'kube-system' to be running and ready
I0209 20:01:13.650] Feb  9 20:01:13.650: INFO: The status of Pod fluentd-gcp-v3.1.1-m4l6x is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:13.650] Feb  9 20:01:13.650: INFO: The status of Pod fluentd-gcp-v3.1.1-xt8pj is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:13.651] Feb  9 20:01:13.650: INFO: The status of Pod heapster-gke-74ddf5b599-ttk27 is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:13.651] Feb  9 20:01:13.650: INFO: The status of Pod kube-dns-8cc7cc68-n9f9g is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:13.651] Feb  9 20:01:13.650: INFO: The status of Pod metrics-server-v0.3.3-6c88646498-mh7rz is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:13.651] Feb  9 20:01:13.650: INFO: The status of Pod stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:13.652] Feb  9 20:01:13.650: INFO: 15 / 21 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
I0209 20:01:13.652] Feb  9 20:01:13.650: INFO: expected 10 pod replicas in namespace 'kube-system', 8 are Running and Ready.
I0209 20:01:13.652] Feb  9 20:01:13.650: INFO: POD                                                        NODE                                          PHASE    GRACE  CONDITIONS
I0209 20:01:13.653] Feb  9 20:01:13.650: INFO: fluentd-gcp-v3.1.1-m4l6x                                   gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:13.653] Feb  9 20:01:13.650: INFO: fluentd-gcp-v3.1.1-xt8pj                                   gke-bootstrap-e2e-default-pool-e43419bc-nwjj  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:11 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:11 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:11 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:11 +0000 UTC  }]
I0209 20:01:13.654] Feb  9 20:01:13.650: INFO: heapster-gke-74ddf5b599-ttk27                              gke-bootstrap-e2e-default-pool-e43419bc-nwjj  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC  }]
I0209 20:01:13.655] Feb  9 20:01:13.650: INFO: kube-dns-8cc7cc68-n9f9g                                    gke-bootstrap-e2e-default-pool-e43419bc-0vzh  Running         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:53 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:53 +0000 UTC ContainersNotReady containers with unready status: [kubedns]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:53 +0000 UTC ContainersNotReady containers with unready status: [kubedns]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:53 +0000 UTC  }]
I0209 20:01:13.655] Feb  9 20:01:13.650: INFO: metrics-server-v0.3.3-6c88646498-mh7rz                     gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:13.656] Feb  9 20:01:13.650: INFO: stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb  gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  5s     [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:13.656] Feb  9 20:01:13.650: INFO: 
I0209 20:01:15.670] Feb  9 20:01:15.670: INFO: The status of Pod fluentd-gcp-v3.1.1-m4l6x is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:15.671] Feb  9 20:01:15.670: INFO: The status of Pod heapster-gke-74ddf5b599-ttk27 is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:15.671] Feb  9 20:01:15.670: INFO: The status of Pod metrics-server-v0.3.3-6c88646498-mh7rz is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:15.671] Feb  9 20:01:15.670: INFO: The status of Pod stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:15.671] Feb  9 20:01:15.670: INFO: 17 / 21 pods in namespace 'kube-system' are running and ready (2 seconds elapsed)
I0209 20:01:15.671] Feb  9 20:01:15.670: INFO: expected 10 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0209 20:01:15.671] Feb  9 20:01:15.670: INFO: POD                                                        NODE                                          PHASE    GRACE  CONDITIONS
I0209 20:01:15.672] Feb  9 20:01:15.670: INFO: fluentd-gcp-v3.1.1-m4l6x                                   gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:15.673] Feb  9 20:01:15.670: INFO: heapster-gke-74ddf5b599-ttk27                              gke-bootstrap-e2e-default-pool-e43419bc-nwjj  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC  }]
I0209 20:01:15.673] Feb  9 20:01:15.670: INFO: metrics-server-v0.3.3-6c88646498-mh7rz                     gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:15.674] Feb  9 20:01:15.670: INFO: stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb  gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  5s     [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:15.674] Feb  9 20:01:15.670: INFO: 
I0209 20:01:17.671] Feb  9 20:01:17.671: INFO: The status of Pod fluentd-gcp-v3.1.1-m4l6x is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:17.672] Feb  9 20:01:17.671: INFO: The status of Pod heapster-gke-74ddf5b599-ttk27 is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:17.672] Feb  9 20:01:17.671: INFO: The status of Pod metrics-server-v0.3.3-6c88646498-mh7rz is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:17.672] Feb  9 20:01:17.671: INFO: The status of Pod stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:17.673] Feb  9 20:01:17.671: INFO: 17 / 21 pods in namespace 'kube-system' are running and ready (4 seconds elapsed)
I0209 20:01:17.673] Feb  9 20:01:17.671: INFO: expected 10 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0209 20:01:17.673] Feb  9 20:01:17.671: INFO: POD                                                        NODE                                          PHASE    GRACE  CONDITIONS
I0209 20:01:17.674] Feb  9 20:01:17.671: INFO: fluentd-gcp-v3.1.1-m4l6x                                   gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:17.674] Feb  9 20:01:17.671: INFO: heapster-gke-74ddf5b599-ttk27                              gke-bootstrap-e2e-default-pool-e43419bc-nwjj  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:07 +0000 UTC  }]
I0209 20:01:17.675] Feb  9 20:01:17.671: INFO: metrics-server-v0.3.3-6c88646498-mh7rz                     gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:17.675] Feb  9 20:01:17.671: INFO: stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb  gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  5s     [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:17.676] Feb  9 20:01:17.671: INFO: 
I0209 20:01:19.706] Feb  9 20:01:19.706: INFO: The status of Pod fluentd-gcp-v3.1.1-m4l6x is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:19.706] Feb  9 20:01:19.706: INFO: The status of Pod heapster-gke-7bcbbb8c-qk6z7 is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:19.706] Feb  9 20:01:19.706: INFO: The status of Pod metrics-server-v0.3.3-6c88646498-mh7rz is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:19.707] Feb  9 20:01:19.706: INFO: The status of Pod stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:19.707] Feb  9 20:01:19.706: INFO: 17 / 21 pods in namespace 'kube-system' are running and ready (6 seconds elapsed)
I0209 20:01:19.707] Feb  9 20:01:19.706: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0209 20:01:19.707] Feb  9 20:01:19.706: INFO: POD                                                        NODE                                          PHASE    GRACE  CONDITIONS
I0209 20:01:19.708] Feb  9 20:01:19.706: INFO: fluentd-gcp-v3.1.1-m4l6x                                   gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:12 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:19.708] Feb  9 20:01:19.706: INFO: heapster-gke-7bcbbb8c-qk6z7                                gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:19 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:19 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:19.709] Feb  9 20:01:19.706: INFO: metrics-server-v0.3.3-6c88646498-mh7rz                     gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:13 +0000 UTC ContainersNotReady containers with unready status: [metrics-server metrics-server-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:19.710] Feb  9 20:01:19.706: INFO: stackdriver-metadata-agent-cluster-level-78d65dd5ff-cztqb  gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  5s     [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC ContainersNotReady containers with unready status: [metadata-agent metadata-agent-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:19.710] Feb  9 20:01:19.706: INFO: 
I0209 20:01:21.677] Feb  9 20:01:21.677: INFO: The status of Pod fluentd-gcp-v3.1.1-gxtgd is Pending (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:21.677] Feb  9 20:01:21.677: INFO: The status of Pod heapster-gke-7bcbbb8c-qk6z7 is Running (Ready = false), waiting for it to be either Running (with Ready = true) or Failed
I0209 20:01:21.677] Feb  9 20:01:21.677: INFO: 17 / 19 pods in namespace 'kube-system' are running and ready (8 seconds elapsed)
I0209 20:01:21.678] Feb  9 20:01:21.677: INFO: expected 9 pod replicas in namespace 'kube-system', 9 are Running and Ready.
I0209 20:01:21.678] Feb  9 20:01:21.677: INFO: POD                          NODE                                          PHASE    GRACE  CONDITIONS
I0209 20:01:21.678] Feb  9 20:01:21.677: INFO: fluentd-gcp-v3.1.1-gxtgd     gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Pending         [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:20 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:20 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:20 +0000 UTC ContainersNotReady containers with unready status: [fluentd-gcp prometheus-to-sd-exporter]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:20 +0000 UTC  }]
I0209 20:01:21.679] Feb  9 20:01:21.677: INFO: heapster-gke-7bcbbb8c-qk6z7  gke-bootstrap-e2e-default-pool-e43419bc-4f6k  Running  30s    [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  } {Ready False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:19 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {ContainersReady False 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:01:19 +0000 UTC ContainersNotReady containers with unready status: [heapster prom-to-sd heapster-nanny]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2020-02-09 20:00:30 +0000 UTC  }]
I0209 20:01:21.679] Feb  9 20:01:21.677: INFO: 
... skipping 119 lines ...
I0209 20:02:30.799] Feb  9 20:02:30.799: INFO: namespace kubectl-5097 deletion completed in 6.898482395s
I0209 20:02:30.814] •SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSFeb  9 20:02:30.810: INFO: Running AfterSuite actions on all nodes
I0209 20:02:30.815] Feb  9 20:02:30.810: INFO: Running AfterSuite actions on node 1
I0209 20:02:30.815] Feb  9 20:02:30.810: INFO: Skipping dumping logs from cluster
I0209 20:02:30.840] 
I0209 20:02:30.840] Ran 2 of 4412 Specs in 80.041 seconds
I0209 20:02:30.841] SUCCESS! -- 2 Passed | 0 Failed | 0 Pending | 4410 Skipped
I0209 20:02:30.841] PASS
I0209 20:02:30.862] 
I0209 20:02:30.862] Ginkgo ran 1 suite in 1m21.347943766s
I0209 20:02:30.863] Test Suite Passed
W0209 20:02:30.963] 2020/02/09 20:02:30 process.go:155: Step './hack/ginkgo-e2e.sh --ginkgo.focus=Kubectl.*\[Serial\] --kubectl-path=../../../../kubernetes_skew/cluster/kubectl.sh --minStartupPods=8 --num-nodes=3 --report-dir=/workspace/_artifacts --disable-log-dump=true' finished in 1m21.700855761s
W0209 20:02:30.964] 2020/02/09 20:02:30 process.go:153: Running: gcloud compute firewall-rules describe e2e-ports-e43419bc --project=k8s-gke-upg-g1-5-g1-6-up-clu-n --format=value(name)
... skipping 37 lines ...
W0209 20:03:03.401] Specify --start=50007 in the next get-serial-port-output invocation to get only the new output starting from here.
W0209 20:03:03.443] 
W0209 20:03:03.443] Specify --start=49653 in the next get-serial-port-output invocation to get only the new output starting from here.
W0209 20:03:04.408] scp: /var/log/fluentd.log*: No such file or directory
W0209 20:03:04.409] scp: /var/log/node-problem-detector.log*: No such file or directory
W0209 20:03:04.409] scp: /var/log/kubelet.cov*: No such file or directory
W0209 20:03:04.413] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0209 20:03:04.657] scp: /var/log/fluentd.log*: No such file or directory
W0209 20:03:04.658] scp: /var/log/node-problem-detector.log*: No such file or directory
W0209 20:03:04.658] scp: /var/log/kubelet.cov*: No such file or directory
W0209 20:03:04.662] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0209 20:03:04.682] scp: /var/log/fluentd.log*: No such file or directory
W0209 20:03:04.682] scp: /var/log/node-problem-detector.log*: No such file or directory
W0209 20:03:04.682] scp: /var/log/kubelet.cov*: No such file or directory
W0209 20:03:04.686] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0209 20:03:04.774] Project: k8s-gke-upg-g1-5-g1-6-up-clu-n
W0209 20:03:04.775] Network Project: k8s-gke-upg-g1-5-g1-6-up-clu-n
W0209 20:03:04.775] Zone: us-central1-c
W0209 20:03:07.455] INSTANCE_GROUPS=
W0209 20:03:07.456] NODE_NAMES=
W0209 20:03:07.457] 2020/02/09 20:03:07 process.go:155: Step 'bash -c 
... skipping 44 lines ...
W0209 20:06:47.201] 2020/02/09 20:06:47 process.go:155: Step 'gcloud compute networks delete -q bootstrap-e2e --project=k8s-gke-upg-g1-5-g1-6-up-clu-n' finished in 38.326755608s
W0209 20:06:47.202] 2020/02/09 20:06:47 e2e.go:278: Sleeping for 30 seconds...
W0209 20:07:17.202] 2020/02/09 20:07:17 e2e.go:456: Listing resources...
W0209 20:07:17.202] 2020/02/09 20:07:17 process.go:153: Running: ./cluster/gce/list-resources.sh
W0209 20:07:18.016] Listed 0 items.
W0209 20:07:18.915] Listed 0 items.
W0209 20:07:20.275] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 20:07:20.276]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 20:07:20.276]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 20:07:20.276]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 20:07:20.276] 
W0209 20:07:20.352] Attempt 1 failed to list instances. Retrying.
W0209 20:07:31.654] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 20:07:31.654]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 20:07:31.654]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 20:07:31.654]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 20:07:31.655] 
W0209 20:07:31.732] Attempt 2 failed to list instances. Retrying.
W0209 20:07:48.144] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 20:07:48.144]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 20:07:48.144]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 20:07:48.145]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 20:07:48.145] 
W0209 20:07:48.219] Attempt 3 failed to list instances. Retrying.
W0209 20:08:09.518] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 20:08:09.519]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 20:08:09.519]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 20:08:09.519]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 20:08:09.519] 
W0209 20:08:09.622] Attempt 4 failed to list instances. Retrying.
W0209 20:08:36.087] ERROR: (gcloud.compute.instances.list) Some requests did not succeed:
W0209 20:08:36.088]  - Invalid value for field 'zone': 'us-west3-a'. Unknown zone.
W0209 20:08:36.088]  - Invalid value for field 'zone': 'us-west3-b'. Unknown zone.
W0209 20:08:36.088]  - Invalid value for field 'zone': 'us-west3-c'. Unknown zone.
W0209 20:08:36.088] 
W0209 20:08:36.174] Attempt 5 failed to list instances. Retrying.
W0209 20:08:36.174] List instances failed!
W0209 20:08:36.175] 2020/02/09 20:08:36 process.go:155: Step './cluster/gce/list-resources.sh' finished in 1m18.973674514s
W0209 20:08:36.176] 2020/02/09 20:08:36 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0209 20:08:53.910] 2020/02/09 20:08:53 main.go:316: Something went wrong: encountered 1 errors: [Failed to list resources (error during ./cluster/gce/list-resources.sh: exit status 2):
W0209 20:08:53.910] Project: k8s-gke-upg-g1-5-g1-6-up-clu-n
W0209 20:08:53.910] Region: us-central1
W0209 20:08:53.911] Zone: us-central1-c
W0209 20:08:53.911] Instance prefix: gke-bootstrap-e2e
W0209 20:08:53.911] Network: bootstrap-e2e
W0209 20:08:53.911] Provider: 
... skipping 18 lines ...
W0209 20:08:53.917]     check_env(env, self.command, *args)
W0209 20:08:53.917]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0209 20:08:53.918]     subprocess.check_call(cmd, env=env)
W0209 20:08:53.918]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W0209 20:08:53.918]     raise CalledProcessError(retcode, cmd)
W0209 20:08:53.918] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--deployment=gke', '--provider=gke', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--check-leaked-resources', '--check-version-skew=false', '--extract=ci/k8s-stable1', '--extract=ci/k8s-stable2', '--gcp-cloud-sdk=gs://cloud-sdk-testing/ci/staging', '--gcp-node-image=gci', '--gcp-zone=us-central1-c', '--gke-create-command=container clusters create --quiet --enable-legacy-authorization', '--gke-environment=test', '--test_args=--ginkgo.focus=Kubectl.*\\[Serial\\] --kubectl-path=../../../../kubernetes_skew/cluster/kubectl.sh --minStartupPods=8', '--timeout=120m')' returned non-zero exit status 1
E0209 20:08:53.928] Command failed
I0209 20:08:53.928] process 269 exited with code 1 after 16.9m
E0209 20:08:53.928] FAIL: ci-kubernetes-e2e-gke-stable2-stable1-gci-kubectl-skew-serial
I0209 20:08:53.929] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0209 20:08:54.434] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0209 20:08:54.493] process 3855 exited with code 0 after 0.0m
I0209 20:08:54.494] Call:  gcloud config get-value account
I0209 20:08:54.926] process 3868 exited with code 0 after 0.0m
I0209 20:08:54.927] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0209 20:08:54.927] Upload result and artifacts...
I0209 20:08:54.927] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-stable2-stable1-gci-kubectl-skew-serial/1226594510079791104
I0209 20:08:54.927] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-stable2-stable1-gci-kubectl-skew-serial/1226594510079791104/artifacts
W0209 20:08:55.986] CommandException: One or more URLs matched no objects.
E0209 20:08:56.129] Command failed
I0209 20:08:56.129] process 3881 exited with code 1 after 0.0m
W0209 20:08:56.129] Remote dir gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-stable2-stable1-gci-kubectl-skew-serial/1226594510079791104/artifacts not exist yet
I0209 20:08:56.129] 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-gke-stable2-stable1-gci-kubectl-skew-serial/1226594510079791104/artifacts
I0209 20:08:58.382] process 4026 exited with code 0 after 0.0m
I0209 20:08:58.383] Call:  git rev-parse HEAD
W0209 20:08:58.387] fatal: not a git repository (or any of the parent directories): .git
E0209 20:08:58.388] Command failed
I0209 20:08:58.388] process 4652 exited with code 128 after 0.0m
I0209 20:08:58.388] Call:  git rev-parse HEAD
I0209 20:08:58.393] process 4653 exited with code 0 after 0.0m
I0209 20:08:58.393] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-stable2-stable1-gci-kubectl-skew-serial/jobResultsCache.json
I0209 20:08:59.493] process 4654 exited with code 0 after 0.0m
I0209 20:08:59.494] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-stable2-stable1-gci-kubectl-skew-serial/jobResultsCache.json#1581235645510147'
... skipping 8 lines ...