This job view page is being replaced by Spyglass soon. Check out the new job view.
PRwojtek-t: [WIP] Debug
ResultFAILURE
Tests 1 failed / 7 succeeded
Started2019-09-18 06:24
Elapsed32m10s
Revision
Buildergke-prow-ssd-pool-1a225945-qrg4
Refs master:57d87502
82479:f1a821c8
poddba09c0c-d9dc-11e9-bdcc-02fd59d786b9
infra-commitf11ee8b6f
job-versionv1.17.0-alpha.0.1528+fb9ca9669dff46
poddba09c0c-d9dc-11e9-bdcc-02fd59d786b9
repok8s.io/kubernetes
repo-commitfb9ca9669dff466933b1ae8f95c4c4fba0fe33cf
repos{u'k8s.io/kubernetes': u'master:57d87502ba6408e708e3000615bfdfd3db5dbed1,82479:f1a821c8926b1b702542a54e58d8d117250716b1', u'k8s.io/release': u'master'}
revisionv1.17.0-alpha.0.1528+fb9ca9669dff46

Test Failures


Up 7m31s

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 7 Passed Tests

Error lines from build-log.txt

... skipping 234 lines ...
W0918 06:31:20.236] INFO: 5211 processes: 5189 remote cache hit, 22 processwrapper-sandbox.
W0918 06:31:20.262] INFO: Build completed successfully, 5304 total actions
W0918 06:31:20.270] INFO: Build completed successfully, 5304 total actions
W0918 06:31:20.296] 2019/09/18 06:31:20 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 5m19.399940312s
W0918 06:31:20.297] 2019/09/18 06:31:20 util.go:255: Flushing memory.
I0918 06:31:20.398] make: Leaving directory '/go/src/k8s.io/kubernetes'
W0918 06:32:02.273] 2019/09/18 06:32:02 util.go:265: flushMem error (page cache): exit status 1
W0918 06:32:02.273] 2019/09/18 06:32:02 process.go:153: Running: /go/src/k8s.io/release/push-build.sh --nomock --verbose --noupdatelatest --bucket=kubernetes-release-pull --ci --gcs-suffix=/pull-kubernetes-e2e-gce --allow-dup
I0918 06:32:02.492] push-build.sh: BEGIN main on dba09c0c-d9dc-11e9-bdcc-02fd59d786b9 Wed Sep 18 06:32:02 UTC 2019
I0918 06:32:02.492] 
W0918 06:32:02.593] $TEST_TMPDIR defined: output root default is '/bazel-scratch/.cache/bazel' and max_idle_secs default is '15'.
W0918 06:32:02.594] Starting local Bazel server and connecting to it...
W0918 06:32:06.033] WARNING: Option 'experimental_remote_spawn_cache' is deprecated
... skipping 982 lines ...
W0918 06:42:35.240] NODE_NAMES=e2e-415a7aa477-674b9-minion-group-j0bc e2e-415a7aa477-674b9-minion-group-nw7w e2e-415a7aa477-674b9-minion-group-vk56
W0918 06:42:35.240] Trying to find master named 'e2e-415a7aa477-674b9-master'
W0918 06:42:35.240] Looking for address 'e2e-415a7aa477-674b9-master-ip'
I0918 06:42:36.068] Waiting up to 300 seconds for cluster initialization.
I0918 06:42:36.069] 
I0918 06:42:36.069]   This will continually check to see if the API for kubernetes is reachable.
I0918 06:42:36.069]   This may time out if there was some uncaught error during start up.
I0918 06:42:36.069] 
W0918 06:42:36.170] Using master: e2e-415a7aa477-674b9-master (external IP: 34.82.137.22; internal IP: (not set))
I0918 06:47:37.167] ...........................................................................................................................Checking for custom logdump instances, if any
I0918 06:47:37.173] Sourcing kube-util.sh
I0918 06:47:37.234] Detecting project
I0918 06:47:37.235] Project: k8s-boskos-gce-project-02
I0918 06:47:37.235] Network Project: k8s-boskos-gce-project-02
I0918 06:47:37.235] Zone: us-west1-b
I0918 06:47:37.235] Dumping logs from master locally to '/workspace/_artifacts'
W0918 06:47:37.336] Cluster failed to initialize within 300 seconds.
W0918 06:47:37.336] Last output from querying API server follows:
W0918 06:47:37.336] -----------------------------------------------------
W0918 06:47:37.336]   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
W0918 06:47:37.337]                                  Dload  Upload   Total   Spent    Left  Speed
W0918 06:47:37.337] 
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0curl: (7) Failed to connect to 34.82.137.22 port 443: Connection refused
W0918 06:47:37.337] -----------------------------------------------------
W0918 06:47:37.337] 2019/09/18 06:47:37 process.go:155: Step './hack/e2e-internal/e2e-up.sh' finished in 7m31.468826259s
W0918 06:47:37.337] 2019/09/18 06:47:37 e2e.go:522: Dumping logs locally to: /workspace/_artifacts
W0918 06:47:37.337] 2019/09/18 06:47:37 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
W0918 06:47:37.338] Trying to find master named 'e2e-415a7aa477-674b9-master'
W0918 06:47:37.338] Looking for address 'e2e-415a7aa477-674b9-master-ip'
... skipping 5 lines ...
W0918 06:48:15.182] scp: /var/log/glbc.log*: No such file or directory
W0918 06:48:15.183] scp: /var/log/cluster-autoscaler.log*: No such file or directory
W0918 06:48:15.183] scp: /var/log/kube-addon-manager.log*: No such file or directory
W0918 06:48:15.183] scp: /var/log/fluentd.log*: No such file or directory
W0918 06:48:15.184] scp: /var/log/kubelet.cov*: No such file or directory
W0918 06:48:15.184] scp: /var/log/startupscript.log*: No such file or directory
W0918 06:48:15.198] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
I0918 06:48:15.344] Dumping logs from nodes locally to '/workspace/_artifacts'
I0918 06:48:15.345] Detecting nodes in the cluster
I0918 06:48:51.272] Changing logfiles to be world-readable for download
I0918 06:48:51.512] Changing logfiles to be world-readable for download
I0918 06:48:51.942] Changing logfiles to be world-readable for download
I0918 06:48:54.571] Copying 'kube-proxy.log fluentd.log node-problem-detector.log kubelet.cov startupscript.log' from e2e-415a7aa477-674b9-minion-group-vk56
... skipping 6 lines ...
W0918 06:48:56.255] 
W0918 06:48:56.255] Specify --start=42198 in the next get-serial-port-output invocation to get only the new output starting from here.
W0918 06:48:57.303] scp: /var/log/fluentd.log*: No such file or directory
W0918 06:48:57.304] scp: /var/log/node-problem-detector.log*: No such file or directory
W0918 06:48:57.304] scp: /var/log/kubelet.cov*: No such file or directory
W0918 06:48:57.304] scp: /var/log/startupscript.log*: No such file or directory
W0918 06:48:57.308] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0918 06:48:57.475] scp: /var/log/fluentd.log*: No such file or directory
W0918 06:48:57.476] scp: /var/log/node-problem-detector.log*: No such file or directory
W0918 06:48:57.476] scp: /var/log/kubelet.cov*: No such file or directory
W0918 06:48:57.476] scp: /var/log/startupscript.log*: No such file or directory
W0918 06:48:57.480] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0918 06:48:58.007] scp: /var/log/fluentd.log*: No such file or directory
W0918 06:48:58.007] scp: /var/log/node-problem-detector.log*: No such file or directory
W0918 06:48:58.008] scp: /var/log/kubelet.cov*: No such file or directory
W0918 06:48:58.008] scp: /var/log/startupscript.log*: No such file or directory
W0918 06:48:58.012] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W0918 06:49:01.056] INSTANCE_GROUPS=e2e-415a7aa477-674b9-minion-group
W0918 06:49:01.056] NODE_NAMES=e2e-415a7aa477-674b9-minion-group-j0bc e2e-415a7aa477-674b9-minion-group-nw7w e2e-415a7aa477-674b9-minion-group-vk56
I0918 06:49:02.135] Failures for e2e-415a7aa477-674b9-minion-group (if any):
W0918 06:49:02.988] 2019/09/18 06:49:02 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 1m25.824364495s
W0918 06:49:02.988] 2019/09/18 06:49:02 process.go:153: Running: ./hack/e2e-internal/e2e-down.sh
W0918 06:49:03.049] Project: k8s-boskos-gce-project-02
... skipping 13 lines ...
W0918 06:49:10.896] Deleting Managed Instance Group...
W0918 06:51:27.645] .............................Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/zones/us-west1-b/instanceGroupManagers/e2e-415a7aa477-674b9-minion-group].
W0918 06:51:27.645] done.
W0918 06:51:33.567] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/global/instanceTemplates/e2e-415a7aa477-674b9-minion-template].
W0918 06:51:39.859] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/global/instanceTemplates/e2e-415a7aa477-674b9-windows-node-template].
I0918 06:51:44.354] Removing etcd replica, name: e2e-415a7aa477-674b9-master, port: 2379, result: 52
I0918 06:51:45.886] {"message":"Internal Server Error"}Removing etcd replica, name: e2e-415a7aa477-674b9-master, port: 4002, result: 0
W0918 06:51:52.780] Updated [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/zones/us-west1-b/instances/e2e-415a7aa477-674b9-master].
W0918 06:54:25.255] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/zones/us-west1-b/instances/e2e-415a7aa477-674b9-master].
W0918 06:54:40.631] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/global/firewalls/e2e-415a7aa477-674b9-master-https].
W0918 06:54:46.768] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/global/firewalls/e2e-415a7aa477-674b9-master-etcd].
W0918 06:54:47.712] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/global/firewalls/e2e-415a7aa477-674b9-minion-all].
W0918 06:54:56.908] Deleted [https://www.googleapis.com/compute/v1/projects/k8s-boskos-gce-project-02/regions/us-west1/addresses/e2e-415a7aa477-674b9-master-ip].
... skipping 140 lines ...
I0918 06:56:26.569] Cleared config for k8s-boskos-gce-project-02_e2e-415a7aa477-674b9 from /workspace/.kube/config
I0918 06:56:26.570] Done
W0918 06:56:26.670] W0918 06:56:26.563835   13179 loader.go:223] Config not found: /workspace/.kube/config
W0918 06:56:26.670] W0918 06:56:26.564043   13179 loader.go:223] Config not found: /workspace/.kube/config
W0918 06:56:26.671] 2019/09/18 06:56:26 process.go:155: Step './hack/e2e-internal/e2e-down.sh' finished in 7m23.582624785s
W0918 06:56:26.671] 2019/09/18 06:56:26 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W0918 06:56:30.801] 2019/09/18 06:56:30 main.go:319: Something went wrong: starting e2e cluster: error during ./hack/e2e-internal/e2e-up.sh: exit status 2
W0918 06:56:30.802] Traceback (most recent call last):
W0918 06:56:30.802]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W0918 06:56:30.807]     main(parse_args())
W0918 06:56:30.808]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W0918 06:56:30.808]     mode.start(runner_args)
W0918 06:56:30.808]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W0918 06:56:30.808]     check_env(env, self.command, *args)
W0918 06:56:30.808]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W0918 06:56:30.809]     subprocess.check_call(cmd, env=env)
W0918 06:56:30.809]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W0918 06:56:30.809]     raise CalledProcessError(retcode, cmd)
W0918 06:56:30.809] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--build=bazel', '--stage=gs://kubernetes-release-pull/ci/pull-kubernetes-e2e-gce', '--up', '--down', '--test', '--provider=gce', '--cluster=e2e-415a7aa477-674b9', '--gcp-network=e2e-415a7aa477-674b9', '--extract=local', '--gcp-node-image=gci', '--gcp-zone=us-west1-b', '--ginkgo-parallel=30', '--runtime-config=batch/v2alpha1=true', '--test_args=--ginkgo.skip=\\[Slow\\]|\\[Serial\\]|\\[Disruptive\\]|\\[Flaky\\]|\\[Feature:.+\\] --minStartupPods=8', '--timeout=80m')' returned non-zero exit status 1
E0918 06:56:30.821] Command failed
I0918 06:56:30.821] process 538 exited with code 1 after 30.5m
E0918 06:56:30.821] FAIL: pull-kubernetes-e2e-gce
I0918 06:56:30.822] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0918 06:56:31.369] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0918 06:56:31.425] process 13190 exited with code 0 after 0.0m
I0918 06:56:31.426] Call:  gcloud config get-value account
I0918 06:56:31.771] process 13202 exited with code 0 after 0.0m
I0918 06:56:31.772] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0918 06:56:31.772] Upload result and artifacts...
I0918 06:56:31.772] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/82479/pull-kubernetes-e2e-gce/1174207483351142400
I0918 06:56:31.773] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/82479/pull-kubernetes-e2e-gce/1174207483351142400/artifacts
W0918 06:56:32.991] CommandException: One or more URLs matched no objects.
E0918 06:56:33.119] Command failed
I0918 06:56:33.119] process 13214 exited with code 1 after 0.0m
W0918 06:56:33.119] Remote dir gs://kubernetes-jenkins/pr-logs/pull/82479/pull-kubernetes-e2e-gce/1174207483351142400/artifacts not exist yet
I0918 06:56:33.120] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/82479/pull-kubernetes-e2e-gce/1174207483351142400/artifacts
I0918 06:56:37.323] process 13356 exited with code 0 after 0.1m
I0918 06:56:37.324] Call:  git rev-parse HEAD
I0918 06:56:37.329] process 14000 exited with code 0 after 0.0m
... skipping 21 lines ...