W1016 21:45:25.267] ************************************************************************** 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:45:25.267] 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 '~/.ssh' I1016 21:45:25.267] Bootstrap chewong-e2e-ccm-test... I1016 21:45:25.270] Builder: 2c928dbd-f05e-11e9-918b-16773c0c7aed I1016 21:45:25.270] Image: chewong/kubekins-e2e:v20191016-test I1016 21:45:25.271] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586113629556736 I1016 21:45:25.271] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 21:45:25.800] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 21:45:25.829] process 219 exited with code 0 after 0.0m I1016 21:45:25.830] Call: gcloud config get-value account I1016 21:45:26.077] process 231 exited with code 0 after 0.0m I1016 21:45:26.078] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 21:45:26.078] Root: /go/src I1016 21:45:26.078] cd to /go/src I1016 21:45:26.078] Checkout: /go/src/k8s.io/cloud-provider-azure master:c470e6f36d76e5a565478fcba22118c06bc1548f,248:95c5c01d4ae3237e932ed7bae98f82bd66987c26 to /go/src/k8s.io/cloud-provider-azure I1016 21:45:26.078] Call: git init k8s.io/cloud-provider-azure I1016 21:45:26.082] Initialized empty Git repository in /go/src/k8s.io/cloud-provider-azure/.git/ I1016 21:45:26.082] process 243 exited with code 0 after 0.0m I1016 21:45:26.082] Call: git config --local user.name 'K8S Bootstrap' I1016 21:45:26.085] process 244 exited with code 0 after 0.0m I1016 21:45:26.085] Call: git config --local user.email k8s_bootstrap@localhost I1016 21:45:26.087] process 245 exited with code 0 after 0.0m I1016 21:45:26.087] Call: git fetch --quiet --tags https://github.com/kubernetes/cloud-provider-azure master +refs/pull/248/head:refs/pr/248 I1016 21:45:27.502] process 246 exited with code 0 after 0.0m I1016 21:45:27.503] Call: git checkout -B test c470e6f36d76e5a565478fcba22118c06bc1548f W1016 21:45:27.925] Switched to a new branch 'test' I1016 21:45:27.928] process 258 exited with code 0 after 0.0m I1016 21:45:27.928] Call: git show -s --format=format:%ct HEAD I1016 21:45:27.931] process 259 exited with code 0 after 0.0m I1016 21:45:27.932] Call: git merge --no-ff -m 'Merge +refs/pull/248/head:refs/pr/248' 95c5c01d4ae3237e932ed7bae98f82bd66987c26 I1016 21:45:28.282] Merge made by the 'recursive' strategy. I1016 21:45:28.284] hack/log-dump.sh | 161 +++++++++++++++++++++++++++++++++++++++++++++++++++++++ I1016 21:45:28.284] 1 file changed, 161 insertions(+) I1016 21:45:28.284] create mode 100755 hack/log-dump.sh I1016 21:45:28.284] process 260 exited with code 0 after 0.0m I1016 21:45:28.285] Configure environment... I1016 21:45:28.285] Call: git show -s --format=format:%ct HEAD I1016 21:45:28.288] process 262 exited with code 0 after 0.0m I1016 21:45:28.288] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 21:45:28.881] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 21:45:28.989] process 263 exited with code 0 after 0.0m I1016 21:45:28.990] Call: gcloud config get-value account I1016 21:45:29.235] process 275 exited with code 0 after 0.0m I1016 21:45:29.235] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 21:45:29.235] Start 1184586113629556736 at unknown... I1016 21:45:29.237] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_YR8zEi gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586113629556736/started.json I1016 21:45:30.811] process 287 exited with code 0 after 0.0m I1016 21:45:30.811] 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/1184586113629556736' cp /tmp/gsutil_Omy9Ns gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/1184586113629556736.txt I1016 21:45:32.156] process 465 exited with code 0 after 0.0m I1016 21:45:32.157] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py ls '~/.ssh' W1016 21:45:32.177] Run: ('ls', '~/.ssh') W1016 21:45:32.179] ls: cannot access '~/.ssh': No such file or directory W1016 21:45:32.179] Traceback (most recent call last): W1016 21:45:32.179] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1016 21:45:32.179] main(ARGS.env, ARGS.cmd + ARGS.args) W1016 21:45:32.179] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1016 21:45:32.180] check(*cmd) W1016 21:45:32.180] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1016 21:45:32.180] subprocess.check_call(cmd) W1016 21:45:32.180] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1016 21:45:32.180] raise CalledProcessError(retcode, cmd) W1016 21:45:32.180] subprocess.CalledProcessError: Command '('ls', '~/.ssh')' returned non-zero exit status 2 E1016 21:45:32.182] Command failed I1016 21:45:32.182] process 643 exited with code 1 after 0.0m E1016 21:45:32.182] FAIL: chewong-e2e-ccm-test I1016 21:45:32.183] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1016 21:45:32.709] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1016 21:45:32.739] process 645 exited with code 0 after 0.0m I1016 21:45:32.739] Call: gcloud config get-value account I1016 21:45:32.994] process 657 exited with code 0 after 0.0m I1016 21:45:32.995] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1016 21:45:32.995] Upload result and artifacts... I1016 21:45:32.995] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586113629556736 W1016 21:45:32.995] Missing local artifacts : /workspace/_artifacts W1016 21:45:32.995] metadata path /workspace/_artifacts/metadata.json does not exist W1016 21:45:32.996] metadata not found or invalid, init with empty metadata I1016 21:45:32.996] Call: git rev-parse HEAD I1016 21:45:32.998] process 669 exited with code 0 after 0.0m I1016 21:45:32.998] Call: git rev-parse HEAD I1016 21:45:33.001] process 670 exited with code 0 after 0.0m I1016 21:45:33.001] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:45:33.927] process 671 exited with code 0 after 0.0m I1016 21:45:33.928] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json#1571262246137113' I1016 21:45:35.141] process 813 exited with code 0 after 0.0m I1016 21:45:35.142] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571262246137113 cp /tmp/gsutil_6tyJKf gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:45:36.439] process 957 exited with code 0 after 0.0m I1016 21:45:36.439] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:45:37.360] process 1135 exited with code 0 after 0.0m I1016 21:45:37.360] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json#1571262249610883' I1016 21:45:38.610] process 1277 exited with code 0 after 0.0m I1016 21:45:38.611] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1571262249610883 cp /tmp/gsutil_rbZHp6 gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/jobResultsCache.json I1016 21:45:39.925] process 1421 exited with code 0 after 0.0m I1016 21:45:39.926] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_CKHYfS gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/1184586113629556736/finished.json I1016 21:45:41.285] process 1599 exited with code 0 after 0.0m I1016 21:45:41.286] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_37VyhG gs://kubernetes-upstream/pr-logs/pull/cloud-provider-azure/248/chewong-e2e-ccm-test/latest-build.txt I1016 21:45:42.656] process 1777 exited with code 0 after 0.0m I1016 21:45:42.656] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_DCoeKf gs://kubernetes-upstream/pr-logs/directory/chewong-e2e-ccm-test/latest-build.txt I1016 21:45:43.971] process 1955 exited with code 0 after 0.0m