W1016 22:54:25.428] ************************************************************************** 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:54:25.428] 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:54:25.428] Bootstrap chewong-e2e-ccm-test... I1016 22:54:25.432] Builder: d7115d57-f067-11e9-b3d3-b20db476995a I1016 22:54:25.432] Image: chewong/kubekins-e2e:v20191016-test I1016 22:54:25.432] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184603478060699648 I1016 22:54:25.433] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 22:54:25.968] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 22:54:25.998] process 215 exited with code 0 after 0.0m I1016 22:54:25.998] Call: gcloud config get-value account I1016 22:54:26.244] process 227 exited with code 0 after 0.0m I1016 22:54:26.245] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 22:54:26.245] Root: /go/src I1016 22:54:26.245] cd to /go/src I1016 22:54:26.245] Checkout: /go/src/k8s.io/cloud-provider-azure master:c470e6f36d76e5a565478fcba22118c06bc1548f,248:95c5c01d4ae3237e932ed7bae98f82bd66987c26 to /go/src/k8s.io/cloud-provider-azure I1016 22:54:26.245] Call: git init k8s.io/cloud-provider-azure I1016 22:54:26.249] Initialized empty Git repository in /go/src/k8s.io/cloud-provider-azure/.git/ I1016 22:54:26.249] process 239 exited with code 0 after 0.0m I1016 22:54:26.250] Call: git config --local user.name 'K8S Bootstrap' I1016 22:54:26.252] process 240 exited with code 0 after 0.0m I1016 22:54:26.252] Call: git config --local user.email k8s_bootstrap@localhost I1016 22:54:26.255] process 241 exited with code 0 after 0.0m I1016 22:54:26.255] Call: git fetch --quiet --tags https://github.com/kubernetes/cloud-provider-azure master +refs/pull/248/head:refs/pr/248 I1016 22:54:27.687] process 242 exited with code 0 after 0.0m I1016 22:54:27.687] Call: git checkout -B test c470e6f36d76e5a565478fcba22118c06bc1548f W1016 22:54:28.130] Switched to a new branch 'test' I1016 22:54:28.132] process 254 exited with code 0 after 0.0m I1016 22:54:28.133] Call: git show -s --format=format:%ct HEAD I1016 22:54:28.135] process 255 exited with code 0 after 0.0m I1016 22:54:28.136] Call: git merge --no-ff -m 'Merge +refs/pull/248/head:refs/pr/248' 95c5c01d4ae3237e932ed7bae98f82bd66987c26 I1016 22:54:28.269] Merge made by the 'recursive' strategy. I1016 22:54:28.271] hack/log-dump.sh | 161 +++++++++++++++++++++++++++++++++++++++++++++++++++++++ I1016 22:54:28.271] 1 file changed, 161 insertions(+) I1016 22:54:28.272] create mode 100755 hack/log-dump.sh I1016 22:54:28.272] process 256 exited with code 0 after 0.0m I1016 22:54:28.272] Configure environment... I1016 22:54:28.272] Call: git show -s --format=format:%ct HEAD I1016 22:54:28.275] process 258 exited with code 0 after 0.0m I1016 22:54:28.275] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 22:54:28.906] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 22:54:29.010] process 259 exited with code 0 after 0.0m I1016 22:54:29.011] Call: gcloud config get-value account I1016 22:54:29.264] process 271 exited with code 0 after 0.0m I1016 22:54:29.264] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 22:54:29.264] Start 1184603478060699648 at unknown... I1016 22:54:29.267] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_1la_H5 gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184603478060699648/started.json I1016 22:54:30.723] process 283 exited with code 0 after 0.0m I1016 22:54:30.724] 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/1184603478060699648' cp /tmp/gsutil_uh3eLg gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/1184603478060699648.txt I1016 22:54:32.187] process 461 exited with code 0 after 0.0m I1016 22:54:32.188] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py ls /etc/ssh-key-secret W1016 22:54:32.208] Run: ('ls', '/etc/ssh-key-secret') W1016 22:54:32.210] ls: cannot access '/etc/ssh-key-secret': No such file or directory W1016 22:54:32.210] Traceback (most recent call last): W1016 22:54:32.210] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1016 22:54:32.210] main(ARGS.env, ARGS.cmd + ARGS.args) W1016 22:54:32.211] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1016 22:54:32.211] check(*cmd) W1016 22:54:32.211] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1016 22:54:32.211] subprocess.check_call(cmd) W1016 22:54:32.212] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1016 22:54:32.212] raise CalledProcessError(retcode, cmd) W1016 22:54:32.212] subprocess.CalledProcessError: Command '('ls', '/etc/ssh-key-secret')' returned non-zero exit status 2 E1016 22:54:32.214] Command failed I1016 22:54:32.214] process 639 exited with code 1 after 0.0m E1016 22:54:32.214] FAIL: chewong-e2e-ccm-test I1016 22:54:32.214] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 22:54:32.746] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 22:54:32.777] process 641 exited with code 0 after 0.0m I1016 22:54:32.777] Call: gcloud config get-value account I1016 22:54:33.021] process 653 exited with code 0 after 0.0m I1016 22:54:33.022] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 22:54:33.022] Upload result and artifacts... I1016 22:54:33.022] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184603478060699648 W1016 22:54:33.022] Missing local artifacts : /workspace/_artifacts W1016 22:54:33.022] metadata path /workspace/_artifacts/metadata.json does not exist W1016 22:54:33.022] metadata not found or invalid, init with empty metadata I1016 22:54:33.023] Call: git rev-parse HEAD I1016 22:54:33.025] process 665 exited with code 0 after 0.0m I1016 22:54:33.025] Call: git rev-parse HEAD I1016 22:54:33.028] process 666 exited with code 0 after 0.0m I1016 22:54:33.028] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:54:34.020] process 667 exited with code 0 after 0.0m I1016 22:54:34.021] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json#1571262516708130' I1016 22:54:35.284] process 809 exited with code 0 after 0.0m I1016 22:54:35.285] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571262516708130 cp /tmp/gsutil_ce45Yf gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:54:36.724] process 953 exited with code 0 after 0.0m I1016 22:54:36.724] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:54:37.709] process 1131 exited with code 0 after 0.0m I1016 22:54:37.710] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json#1571262520165208' I1016 22:54:38.963] process 1273 exited with code 0 after 0.0m I1016 22:54:38.964] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571262520165208 cp /tmp/gsutil_noJ5lQ gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 22:54:40.314] process 1417 exited with code 0 after 0.0m I1016 22:54:40.315] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_2uRakb gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184603478060699648/finished.json I1016 22:54:41.686] process 1595 exited with code 0 after 0.0m I1016 22:54:41.687] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_q0_0_Z gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/latest-build.txt I1016 22:54:43.075] process 1773 exited with code 0 after 0.0m I1016 22:54:43.076] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_9mQVmM gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/latest-build.txt I1016 22:54:44.407] process 1951 exited with code 0 after 0.0m