This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 8 succeeded
Started2022-08-12 04:47
Elapsed14m48s
Revision
Builderdec6d78c-19f9-11ed-93e1-babc36ce5807
infra-commit228f8bc1d
job-versionv1.23.10-rc.0.27+e9dbbc3f7471c7
kubetest-versionv20220804-4fa19ea91a
revisionv1.23.10-rc.0.27+e9dbbc3f7471c7

Test Failures


kubetest Up 7m2s

error during ./hack/e2e-internal/e2e-up.sh: exit status 2
				from junit_runner.xml

Filter through log files | View test history on testgrid


Show 8 Passed Tests

Error lines from build-log.txt

... skipping 15 lines ...
I0812 04:47:35.360] process 60 exited with code 0 after 0.0m
I0812 04:47:35.360] Will upload results to gs://kubernetes-jenkins/logs using prow-build@k8s-infra-prow-build.iam.gserviceaccount.com
I0812 04:47:35.361] Root: /workspace
I0812 04:47:35.361] cd to /workspace
I0812 04:47:35.361] Configure environment...
I0812 04:47:35.361] Call:  git show -s --format=format:%ct HEAD
W0812 04:47:35.364] fatal: not a git repository (or any of the parent directories): .git
I0812 04:47:35.364] process 74 exited with code 128 after 0.0m
W0812 04:47:35.364] Unable to print commit date for HEAD
I0812 04:47:35.364] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0812 04:47:36.078] Activated service account credentials for: [prow-build@k8s-infra-prow-build.iam.gserviceaccount.com]
I0812 04:47:36.257] process 75 exited with code 0 after 0.0m
I0812 04:47:36.258] Call:  gcloud config get-value account
... skipping 360 lines ...
W0812 04:50:44.331] Trying to find master named 'test-a8a0b221b9-master'
W0812 04:50:44.331] Looking for address 'test-a8a0b221b9-master-ip'
W0812 04:50:45.314] Using master: test-a8a0b221b9-master (external IP: 35.203.140.79; internal IP: (not set))
I0812 04:50:45.414] Waiting up to 300 seconds for cluster initialization.
I0812 04:50:45.414] 
I0812 04:50:45.415]   This will continually check to see if the API for kubernetes is reachable.
I0812 04:50:45.415]   This may time out if there was some uncaught error during start up.
I0812 04:50:45.415] 
I0812 04:55:46.280] ..........................................................................................................................................Checking for custom logdump instances, if any
I0812 04:55:46.284] ----------------------------------------------------------------------------------------------------
I0812 04:55:46.284] k/k version of the log-dump.sh script is deprecated!
I0812 04:55:46.284] Please migrate your test job to use test-infra's repo version of log-dump.sh!
I0812 04:55:46.284] Migration steps can be found in the readme file.
I0812 04:55:46.285] ----------------------------------------------------------------------------------------------------
I0812 04:55:46.285] Sourcing kube-util.sh
I0812 04:55:46.329] Detecting project
I0812 04:55:46.330] Project: k8s-infra-e2e-boskos-079
I0812 04:55:46.330] Network Project: k8s-infra-e2e-boskos-079
I0812 04:55:46.330] Zone: us-west1-b
I0812 04:55:46.337] Dumping logs from master locally to '/workspace/_artifacts'
W0812 04:55:46.437] Cluster failed to initialize within 300 seconds.
W0812 04:55:46.438] Last output from querying API server follows:
W0812 04:55:46.438] -----------------------------------------------------
W0812 04:55:46.438] * Expire in 0 ms for 6 (transfer 0x562d8c81b0f0)
W0812 04:55:46.438] * Expire in 5000 ms for 8 (transfer 0x562d8c81b0f0)
W0812 04:55:46.438] *   Trying 35.203.140.79...
W0812 04:55:46.438] * TCP_NODELAY set
W0812 04:55:46.438] * Expire in 200 ms for 4 (transfer 0x562d8c81b0f0)
W0812 04:55:46.438] * connect to 35.203.140.79 port 443 failed: Connection refused
W0812 04:55:46.439] * Failed to connect to 35.203.140.79 port 443: Connection refused
W0812 04:55:46.439] * Closing connection 0
W0812 04:55:46.439] curl: (7) Failed to connect to 35.203.140.79 port 443: Connection refused
W0812 04:55:46.439] -----------------------------------------------------
W0812 04:55:46.439] 2022/08/12 04:55:46 process.go:155: Step './hack/e2e-internal/e2e-up.sh' finished in 7m2.759816336s
W0812 04:55:46.439] 2022/08/12 04:55:46 e2e.go:574: Dumping logs locally to: /workspace/_artifacts
W0812 04:55:46.440] 2022/08/12 04:55:46 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
W0812 04:55:46.440] Trying to find master named 'test-a8a0b221b9-master'
W0812 04:55:46.440] Looking for address 'test-a8a0b221b9-master-ip'
... skipping 5 lines ...
W0812 04:56:26.120] scp: /var/log/glbc.log*: No such file or directory
W0812 04:56:26.120] scp: /var/log/cluster-autoscaler.log*: No such file or directory
W0812 04:56:26.120] scp: /var/log/kube-addon-manager.log*: No such file or directory
W0812 04:56:26.198] scp: /var/log/fluentd.log*: No such file or directory
W0812 04:56:26.198] scp: /var/log/kubelet.cov*: No such file or directory
W0812 04:56:26.198] scp: /var/log/startupscript.log*: No such file or directory
W0812 04:56:26.202] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
I0812 04:56:26.340] Dumping logs from nodes locally to '/workspace/_artifacts'
I0812 04:56:26.341] Detecting nodes in the cluster
I0812 04:57:29.263] Changing logfiles to be world-readable for download
I0812 04:57:30.102] Changing logfiles to be world-readable for download
I0812 04:57:32.913] Changing logfiles to be world-readable for download
I0812 04:57:33.989] Copying 'kube-proxy.log containers/konnectivity-agent-*.log fluentd.log node-problem-detector.log kubelet.cov startupscript.log' from test-a8a0b221b9-minion-group-6jh0
... skipping 5 lines ...
W0812 04:57:38.299] scp: /var/log/kube-proxy.log*: No such file or directory
W0812 04:57:38.299] scp: /var/log/containers/konnectivity-agent-*.log*: No such file or directory
W0812 04:57:38.299] scp: /var/log/fluentd.log*: No such file or directory
W0812 04:57:38.300] scp: /var/log/node-problem-detector.log*: No such file or directory
W0812 04:57:38.300] scp: /var/log/kubelet.cov*: No such file or directory
W0812 04:57:38.300] scp: /var/log/startupscript.log*: No such file or directory
W0812 04:57:38.302] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
I0812 04:57:38.805] Copying 'kube-proxy.log containers/konnectivity-agent-*.log fluentd.log node-problem-detector.log kubelet.cov startupscript.log' from test-a8a0b221b9-minion-group-8dwk
W0812 04:57:39.575] scp: /var/log/kube-proxy.log*: No such file or directory
W0812 04:57:39.576] scp: /var/log/containers/konnectivity-agent-*.log*: No such file or directory
W0812 04:57:39.576] scp: /var/log/fluentd.log*: No such file or directory
W0812 04:57:39.576] scp: /var/log/node-problem-detector.log*: No such file or directory
W0812 04:57:39.576] scp: /var/log/kubelet.cov*: No such file or directory
W0812 04:57:39.576] scp: /var/log/startupscript.log*: No such file or directory
W0812 04:57:39.579] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0812 04:57:39.639] 
W0812 04:57:39.639] Specify --start=99409 in the next get-serial-port-output invocation to get only the new output starting from here.
W0812 04:57:41.447] scp: /var/log/kube-proxy.log*: No such file or directory
W0812 04:57:41.447] scp: /var/log/containers/konnectivity-agent-*.log*: No such file or directory
W0812 04:57:41.447] scp: /var/log/fluentd.log*: No such file or directory
W0812 04:57:41.448] scp: /var/log/node-problem-detector.log*: No such file or directory
W0812 04:57:41.448] scp: /var/log/kubelet.cov*: No such file or directory
W0812 04:57:41.448] scp: /var/log/startupscript.log*: No such file or directory
W0812 04:57:41.450] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0812 04:57:45.636] INSTANCE_GROUPS=test-a8a0b221b9-minion-group
W0812 04:57:45.637] NODE_NAMES=test-a8a0b221b9-minion-group-6jh0 test-a8a0b221b9-minion-group-8dwk test-a8a0b221b9-minion-group-n166
I0812 04:57:46.626] Failures for test-a8a0b221b9-minion-group (if any):
W0812 04:57:47.837] 2022/08/12 04:57:47 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 2m1.559321642s
W0812 04:57:47.837] 2022/08/12 04:57:47 process.go:153: Running: ./hack/e2e-internal/e2e-down.sh
W0812 04:57:47.888] Project: k8s-infra-e2e-boskos-079
... skipping 14 lines ...
I0812 04:57:54.370] Bringing down cluster
W0812 04:58:20.209] Deleting Managed Instance Group...
W0812 04:58:20.209] ..Deleted [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/zones/us-west1-b/instanceGroupManagers/test-a8a0b221b9-minion-group].
W0812 04:58:20.212] done.
W0812 04:58:26.625] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/global/instanceTemplates/test-a8a0b221b9-minion-template].
W0812 04:58:27.326] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/global/instanceTemplates/test-a8a0b221b9-windows-node-template].
W0812 04:59:28.664] Failed to execute 'curl -s --cacert /etc/srv/kubernetes/pki/etcd-apiserver-ca.crt --cert /etc/srv/kubernetes/pki/etcd-apiserver-client.crt --key /etc/srv/kubernetes/pki/etcd-apiserver-client.key https://127.0.0.1:2379/v2/members/$(curl -s --cacert /etc/srv/kubernetes/pki/etcd-apiserver-ca.crt --cert /etc/srv/kubernetes/pki/etcd-apiserver-client.crt --key /etc/srv/kubernetes/pki/etcd-apiserver-client.key https://127.0.0.1:2379/v2/members -XGET | sed 's/{\"id/\n/g' | grep test-a8a0b221b9-master\" | cut -f 3 -d \") -XDELETE -L 2>/dev/null' on test-a8a0b221b9-master despite 5 attempts
W0812 04:59:28.664] Last attempt failed with: 
I0812 04:59:28.765] Removing etcd replica, name: test-a8a0b221b9-master, port: 2379, result: 1
W0812 05:00:28.116] Failed to execute 'curl -s  http://127.0.0.1:4002/v2/members/$(curl -s  http://127.0.0.1:4002/v2/members -XGET | sed 's/{\"id/\n/g' | grep test-a8a0b221b9-master\" | cut -f 3 -d \") -XDELETE -L 2>/dev/null' on test-a8a0b221b9-master despite 5 attempts
W0812 05:00:28.116] Last attempt failed with: 
I0812 05:00:28.216] Removing etcd replica, name: test-a8a0b221b9-master, port: 4002, result: 1
W0812 05:00:31.718] Updated [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/zones/us-west1-b/instances/test-a8a0b221b9-master].
W0812 05:00:47.279] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/zones/us-west1-b/instances/test-a8a0b221b9-master].
W0812 05:00:49.318] WARNING: The following filter keys were not present in any resource : name
W0812 05:00:55.870] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/global/firewalls/test-a8a0b221b9-master-https].
W0812 05:00:57.789] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-infra-e2e-boskos-079/global/firewalls/test-a8a0b221b9-master-etcd].
... skipping 20 lines ...
I0812 05:02:12.193] Cleared config for k8s-infra-e2e-boskos-079_test-a8a0b221b9 from /workspace/.kube/config
I0812 05:02:12.193] Done
W0812 05:02:12.213] W0812 05:02:12.191143   11484 loader.go:221] Config not found: /workspace/.kube/config
W0812 05:02:12.213] W0812 05:02:12.191299   11484 loader.go:221] Config not found: /workspace/.kube/config
W0812 05:02:12.213] 2022/08/12 05:02:12 process.go:155: Step './hack/e2e-internal/e2e-down.sh' finished in 4m24.356792683s
W0812 05:02:12.214] 2022/08/12 05:02:12 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0812 05:02:12.214] 2022/08/12 05:02:12 main.go:331: Something went wrong: starting e2e cluster: error during ./hack/e2e-internal/e2e-up.sh: exit status 2
W0812 05:02:12.214] Traceback (most recent call last):
W0812 05:02:12.214]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 723, in <module>
W0812 05:02:12.214]     main(parse_args())
W0812 05:02:12.214]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 569, in main
W0812 05:02:12.214]     mode.start(runner_args)
W0812 05:02:12.215]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 228, in start
W0812 05:02:12.215]     check_env(env, self.command, *args)
W0812 05:02:12.215]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0812 05:02:12.215]     subprocess.check_call(cmd, env=env)
W0812 05:02:12.215]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W0812 05:02:12.215]     raise CalledProcessError(retcode, cmd)
W0812 05:02:12.216] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--provider=gce', '--cluster=test-a8a0b221b9', '--gcp-network=test-a8a0b221b9', '--check-leaked-resources', '--gcp-zone=us-west1-b', '--gcp-node-image=gci', '--extract=ci/latest-1.23', '--extract-ci-bucket=k8s-release-dev', '--timeout=180m', '--runtime-config=api/all=true', '--test_args=--ginkgo.focus=\\[Feature:(Audit|BlockVolume|PodPreset|ExpandCSIVolumes|ExpandInUseVolumes)\\]|Networking --ginkgo.skip=Networking-Performance|IPv6|Feature:(Volumes|SCTPConnectivity) --minStartupPods=8')' returned non-zero exit status 1
E0812 05:02:12.216] Command failed
I0812 05:02:12.216] process 275 exited with code 1 after 14.6m
E0812 05:02:12.216] FAIL: ci-kubernetes-e2e-gce-cos-k8sstable2-alphafeatures
I0812 05:02:12.216] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0812 05:02:12.792] Activated service account credentials for: [prow-build@k8s-infra-prow-build.iam.gserviceaccount.com]
I0812 05:02:12.874] process 11494 exited with code 0 after 0.0m
I0812 05:02:12.874] Call:  gcloud config get-value account
I0812 05:02:13.380] process 11508 exited with code 0 after 0.0m
I0812 05:02:13.380] Will upload results to gs://kubernetes-jenkins/logs using prow-build@k8s-infra-prow-build.iam.gserviceaccount.com
I0812 05:02:13.381] Upload result and artifacts...
I0812 05:02:13.381] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-kubernetes-e2e-gce-cos-k8sstable2-alphafeatures/1557951884868718592
I0812 05:02:13.381] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gce-cos-k8sstable2-alphafeatures/1557951884868718592/artifacts
W0812 05:02:14.346] CommandException: One or more URLs matched no objects.
E0812 05:02:14.498] Command failed
I0812 05:02:14.498] process 11522 exited with code 1 after 0.0m
W0812 05:02:14.499] Remote dir gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gce-cos-k8sstable2-alphafeatures/1557951884868718592/artifacts not exist yet
I0812 05:02:14.499] 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-gce-cos-k8sstable2-alphafeatures/1557951884868718592/artifacts
I0812 05:02:18.646] process 11662 exited with code 0 after 0.1m
I0812 05:02:18.647] Call:  git rev-parse HEAD
W0812 05:02:18.649] fatal: not a git repository (or any of the parent directories): .git
E0812 05:02:18.649] Command failed
I0812 05:02:18.650] process 12290 exited with code 128 after 0.0m
I0812 05:02:18.650] Call:  git rev-parse HEAD
I0812 05:02:18.652] process 12291 exited with code 0 after 0.0m
I0812 05:02:18.652] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gce-cos-k8sstable2-alphafeatures/jobResultsCache.json
I0812 05:02:19.763] process 12292 exited with code 0 after 0.0m
I0812 05:02:19.764] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gce-cos-k8sstable2-alphafeatures/jobResultsCache.json#1660258960784042'
... skipping 8 lines ...