This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 1 failed / 17 succeeded
Started2019-07-22 04:30
Elapsed17m28s
Revision
Buildergke-prow-ssd-pool-1a225945-sw6v
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/ca9d1509-4d7e-4eed-84e2-0703c5e10f79/targets/test'}}
pod6208786d-ac39-11e9-b82b-365474bd0c86
resultstorehttps://source.cloud.google.com/results/invocations/ca9d1509-4d7e-4eed-84e2-0703c5e10f79/targets/test
infra-commit6d769e14d
job-versionv1.12.11-beta.0.1+5f799a487b70ae
master_os_image
node_os_imagecos-u-73-11647-239-0
pod6208786d-ac39-11e9-b82b-365474bd0c86
revisionv1.12.11-beta.0.1+5f799a487b70ae

Test Failures


Check APIReachability 50s

error starting ./cluster/kubectl.sh --match-server-version=false version: exec: already started
				from junit_runner.xml

Filter through log files


Show 17 Passed Tests

Show 2013 Skipped Tests

Error lines from build-log.txt

... skipping 12 lines ...
I0722 04:30:37.190] process 44 exited with code 0 after 0.0m
I0722 04:30:37.191] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0722 04:30:37.191] Root: /workspace
I0722 04:30:37.191] cd to /workspace
I0722 04:30:37.192] Configure environment...
I0722 04:30:37.192] Call:  git show -s --format=format:%ct HEAD
W0722 04:30:37.196] fatal: Not a git repository (or any of the parent directories): .git
I0722 04:30:37.197] process 56 exited with code 128 after 0.0m
W0722 04:30:37.197] Unable to print commit date for HEAD
I0722 04:30:37.197] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0722 04:30:37.814] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0722 04:30:38.196] process 57 exited with code 0 after 0.0m
I0722 04:30:38.196] Call:  gcloud config get-value account
... skipping 418 lines ...
I0722 04:39:39.589] NAME             LOCATION    MASTER_VERSION                   MASTER_IP      MACHINE_TYPE   NODE_VERSION                     NUM_NODES  STATUS
I0722 04:39:39.589] test-6504807fd0  us-west1-b  1.12.11-beta.0.1+5f799a487b70ae  35.199.185.86  n1-standard-2  1.12.11-beta.0.1+5f799a487b70ae  3          RUNNING
W0722 04:39:39.690] 2019/07/22 04:39:39 process.go:155: Step 'gcloud container clusters create --quiet --project=k8s-gke-upg-g1-5-c1-6-up-clu-n --zone=us-west1-b --machine-type=n1-standard-2 --image-type=custom --num-nodes=3 --network=test-6504807fd0 --image-family=cos-shielded-lts-1 --image-project=gke-node-images-test --cluster-version=1.12.11-beta.0.1+5f799a487b70ae test-6504807fd0' finished in 4m18.110881712s
W0722 04:39:39.691] 2019/07/22 04:39:39 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version
W0722 04:39:50.572] Unable to connect to the server: net/http: TLS handshake timeout
W0722 04:39:50.576] 2019/07/22 04:39:50 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 10.96177358s
W0722 04:39:50.576] 2019/07/22 04:39:50 e2e.go:333: Failed to reach api. Sleeping for 10 seconds before retrying... ([./cluster/kubectl.sh --match-server-version=false version])
W0722 04:40:00.576] 2019/07/22 04:40:00 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version
W0722 04:40:00.577] 2019/07/22 04:40:00 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 17.783µs
W0722 04:40:00.578] 2019/07/22 04:40:00 e2e.go:333: Failed to reach api. Sleeping for 10 seconds before retrying... ([./cluster/kubectl.sh --match-server-version=false version])
W0722 04:40:10.576] 2019/07/22 04:40:10 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version
W0722 04:40:10.579] 2019/07/22 04:40:10 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 10.481µs
W0722 04:40:10.579] 2019/07/22 04:40:10 e2e.go:333: Failed to reach api. Sleeping for 10 seconds before retrying... ([./cluster/kubectl.sh --match-server-version=false version])
W0722 04:40:20.577] 2019/07/22 04:40:20 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version
W0722 04:40:20.578] 2019/07/22 04:40:20 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 13.871µs
W0722 04:40:20.578] 2019/07/22 04:40:20 e2e.go:333: Failed to reach api. Sleeping for 10 seconds before retrying... ([./cluster/kubectl.sh --match-server-version=false version])
W0722 04:40:30.579] 2019/07/22 04:40:30 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false version
W0722 04:40:30.580] 2019/07/22 04:40:30 process.go:155: Step './cluster/kubectl.sh --match-server-version=false version' finished in 13.8µs
W0722 04:40:30.581] 2019/07/22 04:40:30 process.go:153: Running: ./cluster/kubectl.sh --match-server-version=false get nodes -oyaml
W0722 04:40:32.444] 2019/07/22 04:40:32 process.go:155: Step './cluster/kubectl.sh --match-server-version=false get nodes -oyaml' finished in 1.86545115s
W0722 04:40:32.446] 2019/07/22 04:40:32 e2e.go:444: Listing resources...
W0722 04:40:32.448] 2019/07/22 04:40:32 process.go:153: Running: ./cluster/gce/list-resources.sh
... skipping 59 lines ...
I0722 04:41:30.225] Jul 22 04:41:30.225: INFO: kube-apiserver version: v1.12.11-beta.0.1+5f799a487b70ae
I0722 04:41:30.252] SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSJul 22 04:41:30.244: INFO: Running AfterSuite actions on all node
I0722 04:41:30.253] Jul 22 04:41:30.244: INFO: Running AfterSuite actions on node 1
I0722 04:41:30.254] Jul 22 04:41:30.244: INFO: Skipping dumping logs from cluster
I0722 04:41:30.254] 
I0722 04:41:30.255] Ran 0 of 2013 Specs in 6.139 seconds
I0722 04:41:30.257] SUCCESS! -- 0 Passed | 0 Failed | 0 Pending | 2013 Skipped PASS
I0722 04:41:30.271] 
I0722 04:41:30.272] Ginkgo ran 1 suite in 8.81537782s
I0722 04:41:30.273] Test Suite Passed
I0722 04:41:30.283] Checking for custom logdump instances, if any
I0722 04:41:30.297] Using 'use_custom_instance_list' with gke, skipping check for LOG_DUMP_SSH_KEY and LOG_DUMP_SSH_USER
I0722 04:41:30.366] Dumping logs from master locally to '/workspace/_artifacts'
... skipping 29 lines ...
W0722 04:42:18.920] Specify --start=47161 in the next get-serial-port-output invocation to get only the new output starting from here.
W0722 04:42:19.187] 
W0722 04:42:19.187] Specify --start=50178 in the next get-serial-port-output invocation to get only the new output starting from here.
W0722 04:42:19.954] scp: /var/log/fluentd.log*: No such file or directory
W0722 04:42:19.955] scp: /var/log/node-problem-detector.log*: No such file or directory
W0722 04:42:19.955] scp: /var/log/kubelet.cov*: No such file or directory
W0722 04:42:19.960] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0722 04:42:20.897] scp: /var/log/fluentd.log*: No such file or directory
W0722 04:42:20.898] scp: /var/log/node-problem-detector.log*: No such file or directory
W0722 04:42:20.898] scp: /var/log/kubelet.cov*: No such file or directory
W0722 04:42:20.903] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0722 04:42:21.257] scp: /var/log/fluentd.log*: No such file or directory
W0722 04:42:21.258] scp: /var/log/node-problem-detector.log*: No such file or directory
W0722 04:42:21.258] scp: /var/log/kubelet.cov*: No such file or directory
W0722 04:42:21.263] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0722 04:42:21.360] 2019/07/22 04:42:21 process.go:155: Step 'bash -c 
W0722 04:42:21.361] function log_dump_custom_get_instances() {
W0722 04:42:21.361]   if [[ $1 == "master" ]]; then
W0722 04:42:21.362]     return 0
W0722 04:42:21.362]   fi
W0722 04:42:21.362] 
... skipping 48 lines ...
W0722 04:47:42.575] Listed 0 items.
W0722 04:47:43.084] Listed 0 items.
W0722 04:47:43.139] 2019/07/22 04:47:43 process.go:155: Step './cluster/gce/list-resources.sh' finished in 9.334414906s
W0722 04:47:43.139] 2019/07/22 04:47:43 process.go:153: Running: diff -sw -U0 -F^\[.*\]$ /workspace/_artifacts/gcp-resources-before.txt /workspace/_artifacts/gcp-resources-after.txt
W0722 04:47:43.144] 2019/07/22 04:47:43 process.go:155: Step 'diff -sw -U0 -F^\[.*\]$ /workspace/_artifacts/gcp-resources-before.txt /workspace/_artifacts/gcp-resources-after.txt' finished in 5.307224ms
W0722 04:47:43.146] 2019/07/22 04:47:43 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0722 04:47:51.993] 2019/07/22 04:47:51 main.go:316: Something went wrong: encountered 1 errors: [error starting ./cluster/kubectl.sh --match-server-version=false version: exec: already started]
W0722 04:47:51.998] Traceback (most recent call last):
W0722 04:47:51.999]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0722 04:47:52.000]     main(parse_args())
W0722 04:47:52.002]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0722 04:47:52.003]     mode.start(runner_args)
W0722 04:47:52.003]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0722 04:47:52.003]     check_env(env, self.command, *args)
W0722 04:47:52.003]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0722 04:47:52.004]     subprocess.check_call(cmd, env=env)
W0722 04:47:52.004]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0722 04:47:52.004]     raise CalledProcessError(retcode, cmd)
W0722 04:47:52.005] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--deployment=gke', '--provider=gke', '--cluster=test-6504807fd0', '--gcp-network=test-6504807fd0', '--check-leaked-resources', '--gcp-zone=us-west1-b', '--gcp-cloud-sdk=gs://cloud-sdk-testing/ci/staging', '--gke-environment=test', '--image-family=cos-shielded-lts-1', '--image-project=gke-node-images-test', '--gcp-node-image=custom', '--extract=ci/k8s-stable3', '--timeout=180m', '--test_args=--ginkgo.focus=\\[Feature:(ExternalTrafficLocalOnly|DynamicKubeletConfig)\\] --minStartupPods=8')' returned non-zero exit status 1
E0722 04:47:52.015] Command failed
I0722 04:47:52.015] process 259 exited with code 1 after 17.2m
E0722 04:47:52.015] FAIL: ci-kubernetes-e2e-gke-cos1-k8sstable3-alphafeatures
I0722 04:47:52.016] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0722 04:47:52.825] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0722 04:47:52.904] process 3638 exited with code 0 after 0.0m
I0722 04:47:52.905] Call:  gcloud config get-value account
I0722 04:47:53.413] process 3650 exited with code 0 after 0.0m
I0722 04:47:53.414] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0722 04:47:53.414] Upload result and artifacts...
I0722 04:47:53.414] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-cos1-k8sstable3-alphafeatures/1153160319686676480
I0722 04:47:53.415] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-cos1-k8sstable3-alphafeatures/1153160319686676480/artifacts
W0722 04:47:55.202] CommandException: One or more URLs matched no objects.
E0722 04:47:55.401] Command failed
I0722 04:47:55.402] process 3662 exited with code 1 after 0.0m
W0722 04:47:55.402] Remote dir gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-cos1-k8sstable3-alphafeatures/1153160319686676480/artifacts not exist yet
I0722 04:47:55.403] 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-cos1-k8sstable3-alphafeatures/1153160319686676480/artifacts
I0722 04:47:59.853] process 3804 exited with code 0 after 0.1m
I0722 04:47:59.854] Call:  git rev-parse HEAD
W0722 04:47:59.864] fatal: Not a git repository (or any of the parent directories): .git
E0722 04:47:59.864] Command failed
I0722 04:47:59.865] process 4440 exited with code 128 after 0.0m
I0722 04:47:59.865] Call:  git rev-parse HEAD
I0722 04:47:59.875] process 4441 exited with code 0 after 0.0m
I0722 04:47:59.876] Call:  gsutil stat gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-cos1-k8sstable3-alphafeatures/jobResultsCache.json
I0722 04:48:01.351] process 4442 exited with code 0 after 0.0m
I0722 04:48:01.353] Call:  gsutil -q cat 'gs://kubernetes-jenkins/logs/ci-kubernetes-e2e-gke-cos1-k8sstable3-alphafeatures/jobResultsCache.json#1563749263018760'
... skipping 8 lines ...