W1016 21:48:25.841] ************************************************************************** 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 21:48:25.841] 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 '$HOME/.ssh' I1016 21:48:25.841] Bootstrap chewong-e2e-ccm-test... I1016 21:48:25.845] Builder: 95f3ab30-f05e-11e9-918b-16773c0c7aed I1016 21:48:25.845] Image: chewong/kubekins-e2e:v20191016-test I1016 21:48:25.845] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586868621053952 I1016 21:48:25.846] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 21:48:26.360] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 21:48:26.388] process 214 exited with code 0 after 0.0m I1016 21:48:26.388] Call: gcloud config get-value account I1016 21:48:26.624] process 226 exited with code 0 after 0.0m I1016 21:48:26.624] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 21:48:26.624] Root: /go/src I1016 21:48:26.624] cd to /go/src I1016 21:48:26.624] Checkout: /go/src/k8s.io/cloud-provider-azure master:c470e6f36d76e5a565478fcba22118c06bc1548f,248:95c5c01d4ae3237e932ed7bae98f82bd66987c26 to /go/src/k8s.io/cloud-provider-azure I1016 21:48:26.625] Call: git init k8s.io/cloud-provider-azure I1016 21:48:26.628] Initialized empty Git repository in /go/src/k8s.io/cloud-provider-azure/.git/ I1016 21:48:26.628] process 238 exited with code 0 after 0.0m I1016 21:48:26.629] Call: git config --local user.name 'K8S Bootstrap' I1016 21:48:26.631] process 239 exited with code 0 after 0.0m I1016 21:48:26.631] Call: git config --local user.email k8s_bootstrap@localhost I1016 21:48:26.634] process 240 exited with code 0 after 0.0m I1016 21:48:26.634] Call: git fetch --quiet --tags https://github.com/kubernetes/cloud-provider-azure master +refs/pull/248/head:refs/pr/248 I1016 21:48:28.067] process 241 exited with code 0 after 0.0m I1016 21:48:28.067] Call: git checkout -B test c470e6f36d76e5a565478fcba22118c06bc1548f W1016 21:48:28.485] Switched to a new branch 'test' I1016 21:48:28.488] process 253 exited with code 0 after 0.0m I1016 21:48:28.488] Call: git show -s --format=format:%ct HEAD I1016 21:48:28.491] process 254 exited with code 0 after 0.0m I1016 21:48:28.491] Call: git merge --no-ff -m 'Merge +refs/pull/248/head:refs/pr/248' 95c5c01d4ae3237e932ed7bae98f82bd66987c26 I1016 21:48:28.980] Merge made by the 'recursive' strategy. I1016 21:48:28.982] hack/log-dump.sh | 161 +++++++++++++++++++++++++++++++++++++++++++++++++++++++ I1016 21:48:28.983] 1 file changed, 161 insertions(+) I1016 21:48:28.983] create mode 100755 hack/log-dump.sh I1016 21:48:28.983] process 255 exited with code 0 after 0.0m I1016 21:48:28.983] Configure environment... I1016 21:48:28.983] Call: git show -s --format=format:%ct HEAD I1016 21:48:28.986] process 257 exited with code 0 after 0.0m I1016 21:48:28.987] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 21:48:29.595] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 21:48:29.703] process 258 exited with code 0 after 0.0m I1016 21:48:29.704] Call: gcloud config get-value account I1016 21:48:29.931] process 270 exited with code 0 after 0.0m I1016 21:48:29.931] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 21:48:29.931] Start 1184586868621053952 at unknown... I1016 21:48:29.933] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_ItnCzz gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586868621053952/started.json I1016 21:48:31.322] process 282 exited with code 0 after 0.0m I1016 21:48:31.323] 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/1184586868621053952' cp /tmp/gsutil_bIVbLG gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/1184586868621053952.txt I1016 21:48:32.617] process 460 exited with code 0 after 0.0m I1016 21:48:32.618] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py ls /root/.ssh W1016 21:48:32.638] Run: ('ls', '/root/.ssh') W1016 21:48:32.640] ls: cannot access '/root/.ssh': No such file or directory W1016 21:48:32.640] Traceback (most recent call last): W1016 21:48:32.640] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1016 21:48:32.640] main(ARGS.env, ARGS.cmd + ARGS.args) W1016 21:48:32.641] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1016 21:48:32.641] check(*cmd) W1016 21:48:32.641] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1016 21:48:32.641] subprocess.check_call(cmd) W1016 21:48:32.641] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1016 21:48:32.641] raise CalledProcessError(retcode, cmd) W1016 21:48:32.641] subprocess.CalledProcessError: Command '('ls', '/root/.ssh')' returned non-zero exit status 2 E1016 21:48:32.643] Command failed I1016 21:48:32.643] process 638 exited with code 1 after 0.0m E1016 21:48:32.643] FAIL: chewong-e2e-ccm-test I1016 21:48:32.644] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 21:48:33.168] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 21:48:33.198] process 640 exited with code 0 after 0.0m I1016 21:48:33.198] Call: gcloud config get-value account I1016 21:48:33.433] process 652 exited with code 0 after 0.0m I1016 21:48:33.433] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 21:48:33.433] Upload result and artifacts... I1016 21:48:33.433] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586868621053952 W1016 21:48:33.433] Missing local artifacts : /workspace/_artifacts W1016 21:48:33.434] metadata path /workspace/_artifacts/metadata.json does not exist W1016 21:48:33.434] metadata not found or invalid, init with empty metadata I1016 21:48:33.434] Call: git rev-parse HEAD I1016 21:48:33.436] process 664 exited with code 0 after 0.0m I1016 21:48:33.436] Call: git rev-parse HEAD I1016 21:48:33.438] process 665 exited with code 0 after 0.0m I1016 21:48:33.439] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:48:34.384] process 666 exited with code 0 after 0.0m I1016 21:48:34.385] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json#1571262336231016' I1016 21:48:35.639] process 808 exited with code 0 after 0.0m I1016 21:48:35.640] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571262336231016 cp /tmp/gsutil_RgzOZY gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:48:36.921] process 952 exited with code 0 after 0.0m I1016 21:48:36.922] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:48:37.846] process 1130 exited with code 0 after 0.0m I1016 21:48:37.846] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json#1571262339707608' I1016 21:48:39.090] process 1272 exited with code 0 after 0.0m I1016 21:48:39.091] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571262339707608 cp /tmp/gsutil_kJ4AQG gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:48:40.374] process 1416 exited with code 0 after 0.0m I1016 21:48:40.375] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_U2HFV1 gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586868621053952/finished.json I1016 21:48:41.665] process 1594 exited with code 0 after 0.0m I1016 21:48:41.665] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_hCeDHP gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/latest-build.txt I1016 21:48:42.946] process 1772 exited with code 0 after 0.0m I1016 21:48:42.947] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_ZgnE0O gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/latest-build.txt I1016 21:48:44.225] process 1950 exited with code 0 after 0.0m