This job view page is being replaced by Spyglass soon. Check out the new job view.
PRaramase: Automated cherry pick of #85115: remove disk locks per vm
ResultFAILURE
Tests 1 failed / 7 succeeded
Started2019-12-03 02:33
Elapsed23m16s
Revision
Buildergke-prow-ssd-pool-1a225945-qlhl
Refs release-1.14:82c99a27
85832:a7abc99d
pod290182a9-1575-11ea-8eaf-cae7c81f6ce4
infra-commit3f123df15
job-versionv1.14.10-beta.0.16+d5174327e29813
pod290182a9-1575-11ea-8eaf-cae7c81f6ce4
repok8s.io/kubernetes
repo-commitd5174327e2981382561a08882294ff4c0d7c24b8
repos{u'k8s.io/kubernetes': u'release-1.14:82c99a27a66378f1f5ed136c61a9ffb5a7e989c2,85832:a7abc99dd91613175cf8d6b2f805c7719493943c', u'k8s.io/release': u'master'}
revisionv1.14.10-beta.0.16+d5174327e29813

Test Failures


Up 7m14s

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 ...
W1203 02:37:52.893] INFO: 4140 processes: 4114 remote cache hit, 26 processwrapper-sandbox.
W1203 02:37:52.901] INFO: Build completed successfully, 4209 total actions
W1203 02:37:52.904] INFO: Build completed successfully, 4209 total actions
W1203 02:37:52.919] 2019/12/03 02:37:52 process.go:155: Step 'make -C /go/src/k8s.io/kubernetes bazel-release' finished in 3m4.725840255s
W1203 02:37:52.919] 2019/12/03 02:37:52 util.go:265: Flushing memory.
I1203 02:37:53.020] make: Leaving directory '/go/src/k8s.io/kubernetes'
W1203 02:38:19.660] 2019/12/03 02:38:19 util.go:275: flushMem error (page cache): exit status 1
W1203 02:38:19.661] 2019/12/03 02:38:19 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
I1203 02:38:19.761] push-build.sh: BEGIN main on 290182a9-1575-11ea-8eaf-cae7c81f6ce4 Tue Dec  3 02:38:19 UTC 2019
I1203 02:38:19.762] 
W1203 02:38:19.862] $TEST_TMPDIR defined: output root default is '/bazel-scratch/.cache/bazel' and max_idle_secs default is '15'.
W1203 02:38:19.863] Starting local Bazel server and connecting to it...
W1203 02:38:22.235] WARNING: Option 'experimental_remote_spawn_cache' is deprecated
... skipping 857 lines ...
I1203 02:44:22.888] Creating nodes.
W1203 02:44:22.988] WARNING: You have selected a disk size of under [200GB]. This may result in poor I/O performance. For more information, see: https://developers.google.com/compute/docs/disks#performance.
W1203 02:44:22.989] WARNING: Some requests generated warnings:
W1203 02:44:22.989]  - Disk size: '20 GB' is larger than image size: '10 GB'. You might need to resize the root repartition manually if the operating system does not support automatic resizing. See https://cloud.google.com/compute/docs/disks/add-persistent-disk#resize_pd for details.
W1203 02:44:22.990]  - The resource 'projects/cos-cloud/global/images/cos-beta-73-11647-64-0' is deprecated. A suggested replacement is 'projects/cos-cloud/global/images/cos-beta-73-11647-75-0'.
W1203 02:44:22.990] 
W1203 02:44:22.990] ERROR: (gcloud.compute.instances.create) Could not fetch resource:
W1203 02:44:22.990]  - Internal error. Please try again or contact Google Support. (Code: '-3017415405704216970')
W1203 02:44:22.991] Failed to create master instance due to non-retryable error
W1203 02:44:22.991] Some commands failed.
I1203 02:44:24.928] Using subnet e2e-c6008da287-674b9-custom-subnet
W1203 02:44:25.835] Attempt 1 to create e2e-c6008da287-674b9-minion-template
W1203 02:44:26.441] WARNING: You have selected a disk size of under [200GB]. This may result in poor I/O performance. For more information, see: https://developers.google.com/compute/docs/disks#performance.
W1203 02:44:31.204] Created [https://www.googleapis.com/compute/v1/projects/kubernetes-gci-petset/global/instanceTemplates/e2e-c6008da287-674b9-minion-template].
W1203 02:44:31.389] NAME                                  MACHINE_TYPE   PREEMPTIBLE  CREATION_TIMESTAMP
W1203 02:44:31.390] e2e-c6008da287-674b9-minion-template  n1-standard-2               2019-12-02T18:44:27.299-08:00
... skipping 12 lines ...
W1203 02:45:07.767] NODE_NAMES=e2e-c6008da287-674b9-minion-group-6stt e2e-c6008da287-674b9-minion-group-9qxp e2e-c6008da287-674b9-minion-group-b986
W1203 02:45:07.768] Trying to find master named 'e2e-c6008da287-674b9-master'
W1203 02:45:07.768] Looking for address 'e2e-c6008da287-674b9-master-ip'
I1203 02:45:08.719] Waiting up to 300 seconds for cluster initialization.
I1203 02:45:08.719] 
I1203 02:45:08.719]   This will continually check to see if the API for kubernetes is reachable.
I1203 02:45:08.719]   This may time out if there was some uncaught error during start up.
I1203 02:45:08.719] 
W1203 02:45:08.820] Using master: e2e-c6008da287-674b9-master (external IP: 34.83.188.121)
I1203 02:50:15.672] ...........................................Checking for custom logdump instances, if any
I1203 02:50:15.680] Sourcing kube-util.sh
I1203 02:50:15.738] Detecting project
I1203 02:50:15.739] Project: kubernetes-gci-petset
I1203 02:50:15.739] Network Project: kubernetes-gci-petset
I1203 02:50:15.739] Zone: us-west1-b
I1203 02:50:15.739] Dumping logs from master locally to '/workspace/_artifacts'
W1203 02:50:15.840] Cluster failed to initialize within 300 seconds.
W1203 02:50:15.840] Last output from querying API server follows:
W1203 02:50:15.841] -----------------------------------------------------
W1203 02:50:15.841]   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
W1203 02:50:15.841]                                  Dload  Upload   Total   Spent    Left  Speed
W1203 02:50:15.842] 
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
  0     0    0     0    0     0      0      0 --:--:--  0:00:01 --:--:--     0
  0     0    0     0    0     0      0      0 --:--:--  0:00:02 --:--:--     0
  0     0    0     0    0     0      0      0 --:--:--  0:00:03 --:--:--     0
  0     0    0     0    0     0      0      0 --:--:--  0:00:04 --:--:--     0
  0     0    0     0    0     0      0      0 --:--:--  0:00:05 --:--:--     0
W1203 02:50:15.842] curl: (28) Connection timed out after 5001 milliseconds
... skipping 2 lines ...
W1203 02:50:15.843] 2019/12/03 02:50:15 e2e.go:534: Dumping logs locally to: /workspace/_artifacts
W1203 02:50:15.843] 2019/12/03 02:50:15 process.go:153: Running: ./cluster/log-dump/log-dump.sh /workspace/_artifacts
W1203 02:50:15.843] Trying to find master named 'e2e-c6008da287-674b9-master'
W1203 02:50:15.844] Looking for address 'e2e-c6008da287-674b9-master-ip'
W1203 02:50:16.669] Using master: e2e-c6008da287-674b9-master (external IP: 34.83.188.121)
I1203 02:51:18.828] Changing logfiles to be world-readable for download
W1203 02:51:19.688] ERROR: (gcloud.compute.ssh) Could not fetch resource:
W1203 02:51:19.688]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:19.688] 
W1203 02:51:25.689] ERROR: (gcloud.compute.ssh) Could not fetch resource:
W1203 02:51:25.690]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:25.690] 
W1203 02:51:31.827] ERROR: (gcloud.compute.ssh) Could not fetch resource:
W1203 02:51:31.827]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:31.828] 
W1203 02:51:38.391] ERROR: (gcloud.compute.ssh) Could not fetch resource:
W1203 02:51:38.391]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:38.392] 
W1203 02:51:44.918] ERROR: (gcloud.compute.ssh) Could not fetch resource:
W1203 02:51:44.919]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:44.919] 
W1203 02:51:50.839] ERROR: (gcloud.compute.ssh) Could not fetch resource:
W1203 02:51:50.839]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:50.839] 
I1203 02:51:50.940] Copying 'kern.log kube-apiserver.log kube-apiserver-audit.log kube-scheduler.log kube-controller-manager.log etcd.log etcd-events.log glbc.log cluster-autoscaler.log kube-addon-manager.log fluentd.log kubelet.cov startupscript.log docker/log kubelet.log supervisor/supervisord.log supervisor/kubelet-stdout.log supervisor/kubelet-stderr.log supervisor/docker-stdout.log supervisor/docker-stderr.log' from e2e-c6008da287-674b9-master
W1203 02:51:51.792] ERROR: (gcloud.compute.instances.get-serial-port-output) Could not fetch serial port output: The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:52.749] ERROR: (gcloud.compute.scp) Could not fetch resource:
W1203 02:51:52.749]  - The resource 'projects/kubernetes-gci-petset/zones/us-west1-b/instances/e2e-c6008da287-674b9-master' was not found
W1203 02:51:52.749] 
I1203 02:51:52.850] Dumping logs from nodes locally to '/workspace/_artifacts'
I1203 02:51:52.850] Detecting nodes in the cluster
I1203 02:52:34.683] Changing logfiles to be world-readable for download
I1203 02:52:34.746] Changing logfiles to be world-readable for download
... skipping 8 lines ...
W1203 02:52:39.842] 
W1203 02:52:39.842] Specify --start=42837 in the next get-serial-port-output invocation to get only the new output starting from here.
W1203 02:52:41.637] scp: /var/log/fluentd.log*: No such file or directory
W1203 02:52:41.638] scp: /var/log/node-problem-detector.log*: No such file or directory
W1203 02:52:41.638] scp: /var/log/kubelet.cov*: No such file or directory
W1203 02:52:41.638] scp: /var/log/startupscript.log*: No such file or directory
W1203 02:52:41.641] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W1203 02:52:41.732] scp: /var/log/fluentd.log*: No such file or directory
W1203 02:52:41.732] scp: /var/log/node-problem-detector.log*: No such file or directory
W1203 02:52:41.732] scp: /var/log/kubelet.cov*: No such file or directory
W1203 02:52:41.733] scp: /var/log/startupscript.log*: No such file or directory
W1203 02:52:41.737] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W1203 02:52:41.749] scp: /var/log/fluentd.log*: No such file or directory
W1203 02:52:41.749] scp: /var/log/node-problem-detector.log*: No such file or directory
W1203 02:52:41.750] scp: /var/log/kubelet.cov*: No such file or directory
W1203 02:52:41.750] scp: /var/log/startupscript.log*: No such file or directory
W1203 02:52:41.755] ERROR: (gcloud.compute.scp) [/usr/bin/scp] exited with return code [1].
W1203 02:52:45.492] INSTANCE_GROUPS=e2e-c6008da287-674b9-minion-group
W1203 02:52:45.493] NODE_NAMES=e2e-c6008da287-674b9-minion-group-6stt e2e-c6008da287-674b9-minion-group-9qxp e2e-c6008da287-674b9-minion-group-b986
I1203 02:52:46.630] Failures for e2e-c6008da287-674b9-minion-group
W1203 02:52:47.834] 2019/12/03 02:52:47 process.go:155: Step './cluster/log-dump/log-dump.sh /workspace/_artifacts' finished in 2m32.166035097s
W1203 02:52:47.834] 2019/12/03 02:52:47 process.go:153: Running: ./hack/e2e-internal/e2e-down.sh
W1203 02:52:47.892] Project: kubernetes-gci-petset
... skipping 32 lines ...
I1203 02:56:10.611] Property "users.kubernetes-gci-petset_e2e-c6008da287-674b9-basic-auth" unset.
I1203 02:56:10.776] Property "contexts.kubernetes-gci-petset_e2e-c6008da287-674b9" unset.
I1203 02:56:10.782] Cleared config for kubernetes-gci-petset_e2e-c6008da287-674b9 from /workspace/.kube/config
I1203 02:56:10.782] Done
W1203 02:56:10.883] 2019/12/03 02:56:10 process.go:155: Step './hack/e2e-internal/e2e-down.sh' finished in 3m22.949729101s
W1203 02:56:10.883] 2019/12/03 02:56:10 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml.
W1203 02:56:19.896] 2019/12/03 02:56:19 main.go:319: Something went wrong: starting e2e cluster: error during ./hack/e2e-internal/e2e-up.sh: exit status 2
W1203 02:56:19.902] Traceback (most recent call last):
W1203 02:56:19.902]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module>
W1203 02:56:19.902]     main(parse_args())
W1203 02:56:19.902]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main
W1203 02:56:19.903]     mode.start(runner_args)
W1203 02:56:19.903]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start
W1203 02:56:19.903]     check_env(env, self.command, *args)
W1203 02:56:19.903]   File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env
W1203 02:56:19.903]     subprocess.check_call(cmd, env=env)
W1203 02:56:19.903]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W1203 02:56:19.903]     raise CalledProcessError(retcode, cmd)
W1203 02:56:19.904] 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-c6008da287-674b9', '--gcp-network=e2e-c6008da287-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=65m')' returned non-zero exit status 1
E1203 02:56:19.916] Command failed
I1203 02:56:19.917] process 584 exited with code 1 after 21.7m
E1203 02:56:19.917] FAIL: pull-kubernetes-e2e-gce
I1203 02:56:19.917] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1203 02:56:20.478] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1203 02:56:20.544] process 12746 exited with code 0 after 0.0m
I1203 02:56:20.544] Call:  gcloud config get-value account
I1203 02:56:20.913] process 12759 exited with code 0 after 0.0m
I1203 02:56:20.914] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I1203 02:56:20.914] Upload result and artifacts...
I1203 02:56:20.914] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/pr-logs/pull/85832/pull-kubernetes-e2e-gce/1201690789390848000
I1203 02:56:20.915] Call:  gsutil ls gs://kubernetes-jenkins/pr-logs/pull/85832/pull-kubernetes-e2e-gce/1201690789390848000/artifacts
W1203 02:56:21.850] CommandException: One or more URLs matched no objects.
E1203 02:56:21.984] Command failed
I1203 02:56:21.984] process 12772 exited with code 1 after 0.0m
W1203 02:56:21.984] Remote dir gs://kubernetes-jenkins/pr-logs/pull/85832/pull-kubernetes-e2e-gce/1201690789390848000/artifacts not exist yet
I1203 02:56:21.984] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/pr-logs/pull/85832/pull-kubernetes-e2e-gce/1201690789390848000/artifacts
I1203 02:56:24.215] process 12917 exited with code 0 after 0.0m
I1203 02:56:24.216] Call:  git rev-parse HEAD
I1203 02:56:24.222] process 13537 exited with code 0 after 0.0m
I1203 02:56:24.223] Call:  git rev-parse HEAD
I1203 02:56:24.229] process 13538 exited with code 0 after 0.0m
I1203 02:56:24.230] Call:  gsutil stat gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json
I1203 02:56:25.288] process 13539 exited with code 0 after 0.0m
I1203 02:56:25.289] Call:  gsutil -q cat 'gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json#1575341770006897'
I1203 02:56:26.423] process 13684 exited with code 0 after 0.0m
I1203 02:56:26.429] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1575341770006897 cp /tmp/gsutil_DwosEU gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json
W1203 02:56:27.673] PreconditionException: 412 Precondition Failed
E1203 02:56:27.811] Command failed
I1203 02:56:27.812] process 13831 exited with code 1 after 0.0m
W1203 02:56:27.812] Failed to append to gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json#1575341770006897
I1203 02:56:29.509] Call:  gsutil stat gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json
I1203 02:56:30.574] process 14011 exited with code 0 after 0.0m
I1203 02:56:30.575] Call:  gsutil -q cat 'gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json#1575341786206409'
I1203 02:56:31.736] process 14156 exited with code 0 after 0.0m
I1203 02:56:31.741] Call:  gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1575341786206409 cp /tmp/gsutil_YBK00c gs://kubernetes-jenkins/pr-logs/directory/pull-kubernetes-e2e-gce/jobResultsCache.json
I1203 02:56:33.237] process 14303 exited with code 0 after 0.0m
... skipping 13 lines ...