W1001 17:41:25.016] ************************************************************************** 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 ************************************************************************** I1001 17:41:25.016] Args: --job=pull-azuredisk-csi-driver-verify --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azuredisk-csi-driver-verify --root=/go/src --service-account=/etc/service-account/service-account.json --repo=github.com/kubernetes-sigs/azuredisk-csi-driver=master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:f4689cb00bc33d7617c7a4ddd6b460c88b65a9ab --upload=gs://kubernetes-upstream/pr-logs --scenario=execute -- make verify I1001 17:41:25.017] Bootstrap pull-azuredisk-csi-driver-verify... I1001 17:41:25.020] Builder: 90d6b188-e472-11e9-918b-16773c0c7aed I1001 17:41:25.020] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190927-21e0205-master I1001 17:41:25.020] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179088765260402688 I1001 17:41:25.021] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:41:25.632] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:41:25.666] process 32 exited with code 0 after 0.0m I1001 17:41:25.666] Call: gcloud config get-value account I1001 17:41:26.046] process 44 exited with code 0 after 0.0m I1001 17:41:26.046] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:41:26.046] Root: /go/src I1001 17:41:26.047] cd to /go/src I1001 17:41:26.047] Checkout: /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:f4689cb00bc33d7617c7a4ddd6b460c88b65a9ab to /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver I1001 17:41:26.047] Call: git init github.com/kubernetes-sigs/azuredisk-csi-driver I1001 17:41:26.051] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/.git/ I1001 17:41:26.052] process 56 exited with code 0 after 0.0m I1001 17:41:26.052] Call: git config --local user.name 'K8S Bootstrap' I1001 17:41:26.055] process 57 exited with code 0 after 0.0m I1001 17:41:26.056] Call: git config --local user.email k8s_bootstrap@localhost I1001 17:41:26.058] process 58 exited with code 0 after 0.0m I1001 17:41:26.059] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azuredisk-csi-driver master +refs/pull/166/head:refs/pr/166 I1001 17:41:33.807] process 59 exited with code 0 after 0.1m I1001 17:41:33.808] Call: git checkout -B test 6b4d4209385f8a3b04a39f1970017a27f2667a9a W1001 17:41:34.264] Switched to a new branch 'test' I1001 17:41:34.268] process 71 exited with code 0 after 0.0m I1001 17:41:34.268] Call: git show -s --format=format:%ct HEAD I1001 17:41:34.271] process 72 exited with code 0 after 0.0m I1001 17:41:34.272] Call: git merge --no-ff -m 'Merge +refs/pull/166/head:refs/pr/166' f4689cb00bc33d7617c7a4ddd6b460c88b65a9ab I1001 17:41:34.854] Removing test/integration/azure.json I1001 17:41:34.855] Removing test/e2e/run-test.sh I1001 17:41:34.855] Merge made by the 'recursive' strategy. I1001 17:41:34.861] .travis.yml | 21 +- I1001 17:41:34.862] Gopkg.lock | 17 +- I1001 17:41:34.862] Makefile | 102 +- I1001 17:41:34.862] hack/verify-all.sh | 2 +- I1001 17:41:34.863] hack/verify-gofmt.sh | 2 +- I1001 17:41:34.863] hack/verify-golint.sh | 7 +- I1001 17:41:34.863] pkg/azuredisk/controllerserver.go | 2 +- I1001 17:41:34.863] test/e2e/dynamic_provisioning.go | 10 - I1001 17:41:34.864] test/e2e/pre_provisioning.go | 16 +- I1001 17:41:34.864] test/e2e/run-test.sh | 37 - I1001 17:41:34.864] test/e2e/suite_test.go | 87 + I1001 17:41:34.864] test/integration/azure.json | 33 - I1001 17:41:34.865] test/integration/integration_test.go | 90 + I1001 17:41:34.865] test/integration/run-test.sh | 157 +- I1001 17:41:34.865] test/integration/run-tests-all-clouds.sh | 32 +- I1001 17:41:34.865] test/sanity/run-test.sh | 47 +- I1001 17:41:34.865] test/sanity/run-tests-all-clouds.sh | 37 +- I1001 17:41:34.866] test/sanity/sanity_test.go | 72 +- I1001 17:41:34.866] test/utils/azure/azure_helpers.go | 96 + I1001 17:41:34.866] test/utils/credentials/credentials.go | 188 ++ I1001 17:41:34.866] test/utils/credentials/credentials_test.go | 181 ++ I1001 17:41:34.867] test/utils/testutil/testutil.go | 8 + I1001 17:41:34.867] .../resources/mgmt/2018-05-01/resources/client.go | 51 + I1001 17:41:34.867] .../2018-05-01/resources/deploymentoperations.go | 474 +++++ I1001 17:41:34.867] .../mgmt/2018-05-01/resources/deployments.go | 1553 +++++++++++++++ I1001 17:41:34.868] .../resources/mgmt/2018-05-01/resources/groups.go | 676 +++++++ I1001 17:41:34.868] .../resources/mgmt/2018-05-01/resources/models.go | 2044 ++++++++++++++++++++ I1001 17:41:34.868] .../mgmt/2018-05-01/resources/operations.go | 147 ++ I1001 17:41:34.868] .../mgmt/2018-05-01/resources/providers.go | 392 ++++ I1001 17:41:34.868] .../mgmt/2018-05-01/resources/resources.go | 1352 +++++++++++++ I1001 17:41:34.869] .../resources/mgmt/2018-05-01/resources/tags.go | 454 +++++ I1001 17:41:34.869] .../resources/mgmt/2018-05-01/resources/version.go | 30 + I1001 17:41:34.869] vendor/github.com/pelletier/go-toml/LICENSE | 21 + I1001 17:41:34.869] vendor/github.com/pelletier/go-toml/doc.go | 23 + I1001 17:41:34.869] vendor/github.com/pelletier/go-toml/fuzz.go | 31 + I1001 17:41:34.870] vendor/github.com/pelletier/go-toml/keysparsing.go | 113 ++ I1001 17:41:34.870] vendor/github.com/pelletier/go-toml/lexer.go | 752 +++++++ I1001 17:41:34.870] vendor/github.com/pelletier/go-toml/marshal.go | 803 ++++++++ I1001 17:41:34.870] vendor/github.com/pelletier/go-toml/parser.go | 442 +++++ I1001 17:41:34.871] vendor/github.com/pelletier/go-toml/position.go | 29 + I1001 17:41:34.871] vendor/github.com/pelletier/go-toml/token.go | 144 ++ I1001 17:41:34.871] vendor/github.com/pelletier/go-toml/toml.go | 393 ++++ I1001 17:41:34.871] .../pelletier/go-toml/tomltree_create.go | 142 ++ I1001 17:41:34.872] .../github.com/pelletier/go-toml/tomltree_write.go | 434 +++++ I1001 17:41:34.872] .../azure/azure_controller_common.go | 29 +- I1001 17:41:34.872] 45 files changed, 11409 insertions(+), 364 deletions(-) I1001 17:41:34.872] delete mode 100755 test/e2e/run-test.sh I1001 17:41:34.872] delete mode 100644 test/integration/azure.json I1001 17:41:34.873] create mode 100644 test/integration/integration_test.go I1001 17:41:34.873] create mode 100644 test/utils/azure/azure_helpers.go I1001 17:41:34.873] create mode 100644 test/utils/credentials/credentials.go I1001 17:41:34.873] create mode 100644 test/utils/credentials/credentials_test.go I1001 17:41:34.873] create mode 100644 test/utils/testutil/testutil.go I1001 17:41:34.874] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/client.go I1001 17:41:34.874] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deploymentoperations.go I1001 17:41:34.874] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deployments.go I1001 17:41:34.875] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/groups.go I1001 17:41:34.875] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/models.go I1001 17:41:34.875] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/operations.go I1001 17:41:34.875] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/providers.go I1001 17:41:34.876] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/resources.go I1001 17:41:34.876] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/tags.go I1001 17:41:34.876] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/version.go I1001 17:41:34.876] create mode 100644 vendor/github.com/pelletier/go-toml/LICENSE I1001 17:41:34.877] create mode 100644 vendor/github.com/pelletier/go-toml/doc.go I1001 17:41:34.877] create mode 100644 vendor/github.com/pelletier/go-toml/fuzz.go I1001 17:41:34.877] create mode 100644 vendor/github.com/pelletier/go-toml/keysparsing.go I1001 17:41:34.877] create mode 100644 vendor/github.com/pelletier/go-toml/lexer.go I1001 17:41:34.877] create mode 100644 vendor/github.com/pelletier/go-toml/marshal.go I1001 17:41:34.878] create mode 100644 vendor/github.com/pelletier/go-toml/parser.go I1001 17:41:34.878] create mode 100644 vendor/github.com/pelletier/go-toml/position.go I1001 17:41:34.878] create mode 100644 vendor/github.com/pelletier/go-toml/token.go I1001 17:41:34.878] create mode 100644 vendor/github.com/pelletier/go-toml/toml.go I1001 17:41:34.878] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_create.go I1001 17:41:34.879] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_write.go I1001 17:41:34.879] process 73 exited with code 0 after 0.0m I1001 17:41:34.879] Configure environment... I1001 17:41:34.880] Call: git show -s --format=format:%ct HEAD I1001 17:41:34.884] process 75 exited with code 0 after 0.0m I1001 17:41:34.884] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:41:35.515] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:41:35.633] process 76 exited with code 0 after 0.0m I1001 17:41:35.634] Call: gcloud config get-value account I1001 17:41:35.907] process 88 exited with code 0 after 0.0m I1001 17:41:35.907] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:41:35.907] Start 1179088765260402688 at unknown... I1001 17:41:35.911] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_HgIjr0 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179088765260402688/started.json I1001 17:41:37.529] process 100 exited with code 0 after 0.0m I1001 17:41:37.529] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179088765260402688' cp /tmp/gsutil_gKoCXY gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/1179088765260402688.txt I1001 17:41:39.243] process 278 exited with code 0 after 0.0m I1001 17:41:39.244] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make verify W1001 17:41:39.274] Run: ('make', 'verify') I1001 17:41:39.378] hack/verify-all.sh I1001 17:41:39.378] Verifying gofmt I1001 17:41:39.464] No issue found I1001 17:41:39.470] Verifying govet I1001 17:44:06.765] Done W1001 17:44:06.968] golangci/golangci-lint info checking GitHub for tag 'v1.17.1' W1001 17:44:07.210] golangci/golangci-lint info found version: 1.17.1 for v1.17.1/linux/amd64 W1001 17:44:11.432] golangci/golangci-lint info installed ./bin/golangci-lint I1001 17:44:29.628] Congratulations! All Go source files have been linted. I1001 17:44:29.630] Verifying dep check I1001 17:44:29.631] Makefile:41: recipe for target 'verify' failed W1001 17:44:29.636] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/hack/verify-dep.sh: line 20: dep: command not found W1001 17:44:29.636] make: *** [verify] Error 127 W1001 17:44:29.636] Traceback (most recent call last): W1001 17:44:29.636] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1001 17:44:29.636] main(ARGS.env, ARGS.cmd + ARGS.args) W1001 17:44:29.636] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1001 17:44:29.637] check(*cmd) W1001 17:44:29.637] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1001 17:44:29.637] subprocess.check_call(cmd) W1001 17:44:29.637] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1001 17:44:29.637] raise CalledProcessError(retcode, cmd) W1001 17:44:29.637] subprocess.CalledProcessError: Command '('make', 'verify')' returned non-zero exit status 2 E1001 17:44:29.638] Command failed I1001 17:44:29.638] process 456 exited with code 1 after 2.8m E1001 17:44:29.638] FAIL: pull-azuredisk-csi-driver-verify I1001 17:44:29.638] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:44:30.240] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:44:30.270] process 12159 exited with code 0 after 0.0m I1001 17:44:30.271] Call: gcloud config get-value account I1001 17:44:30.533] process 12171 exited with code 0 after 0.0m I1001 17:44:30.533] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:44:30.533] Upload result and artifacts... I1001 17:44:30.533] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179088765260402688 W1001 17:44:30.533] Missing local artifacts : /workspace/_artifacts W1001 17:44:30.534] metadata path /workspace/_artifacts/metadata.json does not exist W1001 17:44:30.534] metadata not found or invalid, init with empty metadata I1001 17:44:30.534] Call: git rev-parse HEAD I1001 17:44:30.537] process 12183 exited with code 0 after 0.0m I1001 17:44:30.537] Call: git rev-parse HEAD I1001 17:44:30.539] process 12184 exited with code 0 after 0.0m I1001 17:44:30.539] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/jobResultsCache.json W1001 17:44:32.294] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 17:44:32.294] process 12185 exited with code 1 after 0.0m I1001 17:44:32.295] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_YtXogT gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 17:44:34.217] process 12327 exited with code 0 after 0.0m I1001 17:44:34.217] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/jobResultsCache.json W1001 17:44:35.261] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 17:44:35.262] process 12505 exited with code 1 after 0.0m I1001 17:44:35.262] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_pyeRVR gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 17:44:36.552] process 12647 exited with code 0 after 0.0m I1001 17:44:36.553] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_2EGpju gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179088765260402688/finished.json I1001 17:44:37.916] process 12825 exited with code 0 after 0.0m I1001 17:44:37.917] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_QvjZOV gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/latest-build.txt I1001 17:44:39.324] process 13003 exited with code 0 after 0.0m I1001 17:44:39.325] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_q5Cm_B gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/latest-build.txt I1001 17:44:40.656] process 13181 exited with code 0 after 0.0m