W1016 22:57:29.333] ************************************************************************** bootstrap.py is deprecated! test-infra oncall does not support any job still using bootstrap.py. Please migrate your job to podutils! https://github.com/kubernetes/test-infra/blob/master/prow/pod-utilities.md ************************************************************************** I1016 22:57:29.334] Args: --job=chewong-e2e-ccm-test --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=chewong-e2e-ccm-test --repo=k8s.io/cloud-provider-azure=master:c470e6f36d76e5a565478fcba22118c06bc1548f,248:95c5c01d4ae3237e932ed7bae98f82bd66987c26 --root=/go/src --service-account=/etc/service-account/service-account.json --timeout=450 --upload=gs://kubernetes-upstream/pr-logs --scenario=execute -- ls /etc/ssh-key-secret I1016 22:57:29.334] Bootstrap chewong-e2e-ccm-test... I1016 22:57:29.337] Builder: 4220232c-f068-11e9-b3d3-b20db476995a I1016 22:57:29.337] Image: chewong/kubekins-e2e:v20191016-test I1016 22:57:29.338] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184604233039613953 I1016 22:57:29.338] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 22:57:29.908] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 22:57:29.946] process 212 exited with code 0 after 0.0m I1016 22:57:29.946] Call: gcloud config get-value account I1016 22:57:30.202] process 224 exited with code 0 after 0.0m I1016 22:57:30.203] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 22:57:30.203] Root: /go/src I1016 22:57:30.203] cd to /go/src I1016 22:57:30.203] Checkout: /go/src/k8s.io/cloud-provider-azure master:c470e6f36d76e5a565478fcba22118c06bc1548f,248:95c5c01d4ae3237e932ed7bae98f82bd66987c26 to /go/src/k8s.io/cloud-provider-azure I1016 22:57:30.203] Call: git init k8s.io/cloud-provider-azure I1016 22:57:30.208] Initialized empty Git repository in /go/src/k8s.io/cloud-provider-azure/.git/ I1016 22:57:30.208] process 236 exited with code 0 after 0.0m I1016 22:57:30.208] Call: git config --local user.name 'K8S Bootstrap' I1016 22:57:30.211] process 237 exited with code 0 after 0.0m I1016 22:57:30.211] Call: git config --local user.email k8s_bootstrap@localhost I1016 22:57:30.214] process 238 exited with code 0 after 0.0m I1016 22:57:30.214] Call: git fetch --quiet --tags https://github.com/kubernetes/cloud-provider-azure master +refs/pull/248/head:refs/pr/248 I1016 22:57:31.680] process 239 exited with code 0 after 0.0m I1016 22:57:31.680] Call: git checkout -B test c470e6f36d76e5a565478fcba22118c06bc1548f W1016 22:57:32.124] Switched to a new branch 'test' I1016 22:57:32.127] process 251 exited with code 0 after 0.0m I1016 22:57:32.127] Call: git show -s --format=format:%ct HEAD I1016 22:57:32.130] process 252 exited with code 0 after 0.0m I1016 22:57:32.131] Call: git merge --no-ff -m 'Merge +refs/pull/248/head:refs/pr/248' 95c5c01d4ae3237e932ed7bae98f82bd66987c26 I1016 22:57:32.272] Merge made by the 'recursive' strategy. I1016 22:57:32.274] hack/log-dump.sh | 161 +++++++++++++++++++++++++++++++++++++++++++++++++++++++ I1016 22:57:32.274] 1 file changed, 161 insertions(+) I1016 22:57:32.274] create mode 100755 hack/log-dump.sh I1016 22:57:32.274] process 253 exited with code 0 after 0.0m I1016 22:57:32.275] Configure environment... I1016 22:57:32.275] Call: git show -s --format=format:%ct HEAD I1016 22:57:32.278] process 255 exited with code 0 after 0.0m I1016 22:57:32.278] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 22:57:32.900] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 22:57:33.008] process 256 exited with code 0 after 0.0m I1016 22:57:33.009] Call: gcloud config get-value account I1016 22:57:33.260] process 268 exited with code 0 after 0.0m I1016 22:57:33.260] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 22:57:33.260] Start 1184604233039613953 at unknown... I1016 22:57:33.262] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_I0G4zH gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184604233039613953/started.json I1016 22:57:34.823] process 280 exited with code 0 after 0.0m I1016 22:57:34.824] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184604233039613953' cp /tmp/gsutil_8sbqh0 gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/1184604233039613953.txt I1016 22:57:36.189] process 458 exited with code 0 after 0.0m I1016 22:57:36.190] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py ls /etc/ssh-key-secret W1016 22:57:36.222] Run: ('ls', '/etc/ssh-key-secret') W1016 22:57:36.224] ls: cannot access '/etc/ssh-key-secret': No such file or directory W1016 22:57:36.224] Traceback (most recent call last): W1016 22:57:36.225] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1016 22:57:36.225] main(ARGS.env, ARGS.cmd + ARGS.args) W1016 22:57:36.225] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1016 22:57:36.225] check(*cmd) W1016 22:57:36.225] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1016 22:57:36.225] subprocess.check_call(cmd) W1016 22:57:36.226] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1016 22:57:36.226] raise CalledProcessError(retcode, cmd) W1016 22:57:36.226] subprocess.CalledProcessError: Command '('ls', '/etc/ssh-key-secret')' returned non-zero exit status 2 E1016 22:57:36.229] Command failed I1016 22:57:36.229] process 636 exited with code 1 after 0.0m E1016 22:57:36.229] FAIL: chewong-e2e-ccm-test I1016 22:57:36.229] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 22:57:36.805] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 22:57:36.839] process 638 exited with code 0 after 0.0m I1016 22:57:36.839] Call: gcloud config get-value account I1016 22:57:37.113] process 650 exited with code 0 after 0.0m I1016 22:57:37.113] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 22:57:37.114] Upload result and artifacts... I1016 22:57:37.114] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184604233039613953 W1016 22:57:37.114] Missing local artifacts : /workspace/_artifacts W1016 22:57:37.114] metadata path /workspace/_artifacts/metadata.json does not exist W1016 22:57:37.114] metadata not found or invalid, init with empty metadata I1016 22:57:37.115] Call: git rev-parse HEAD I1016 22:57:37.117] process 662 exited with code 0 after 0.0m I1016 22:57:37.117] Call: git rev-parse HEAD I1016 22:57:37.120] process 663 exited with code 0 after 0.0m I1016 22:57:37.120] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:57:38.150] process 664 exited with code 0 after 0.0m I1016 22:57:38.151] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json#1571266476421905' I1016 22:57:39.513] process 806 exited with code 0 after 0.0m I1016 22:57:39.514] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571266476421905 cp /tmp/gsutil_WCQRyK gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:57:40.879] process 950 exited with code 0 after 0.0m I1016 22:57:40.880] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:57:42.006] process 1128 exited with code 0 after 0.0m I1016 22:57:42.006] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json#1571266480096091' I1016 22:57:43.273] process 1270 exited with code 0 after 0.0m I1016 22:57:43.274] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571266480096091 cp /tmp/gsutil_ZPoopr gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:57:44.698] process 1414 exited with code 0 after 0.0m I1016 22:57:44.698] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_wF74lq gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184604233039613953/finished.json I1016 22:57:46.173] process 1592 exited with code 0 after 0.0m I1016 22:57:46.173] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_WVdO6y gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/latest-build.txt I1016 22:57:47.553] process 1770 exited with code 0 after 0.0m I1016 22:57:47.554] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_5QcXTR gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/latest-build.txt I1016 22:57:48.971] process 1948 exited with code 0 after 0.0m