W1001 17:57:33.243] ************************************************************************** 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:57:33.243] 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:35f2901acb3478c935a8b603cb4856fe60190383 --upload=gs://kubernetes-upstream/pr-logs --scenario=execute -- make verify I1001 17:57:33.243] Bootstrap pull-azuredisk-csi-driver-verify... I1001 17:57:33.247] Builder: dedaa547-e474-11e9-918b-16773c0c7aed I1001 17:57:33.247] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190927-21e0205-master I1001 17:57:33.247] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179092917625556992 I1001 17:57:33.247] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:57:33.817] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:57:33.853] process 33 exited with code 0 after 0.0m I1001 17:57:33.854] Call: gcloud config get-value account I1001 17:57:34.120] process 45 exited with code 0 after 0.0m I1001 17:57:34.120] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:57:34.121] Root: /go/src I1001 17:57:34.121] cd to /go/src I1001 17:57:34.121] Checkout: /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:35f2901acb3478c935a8b603cb4856fe60190383 to /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver I1001 17:57:34.121] Call: git init github.com/kubernetes-sigs/azuredisk-csi-driver I1001 17:57:34.126] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/.git/ I1001 17:57:34.126] process 57 exited with code 0 after 0.0m I1001 17:57:34.126] Call: git config --local user.name 'K8S Bootstrap' I1001 17:57:34.129] process 58 exited with code 0 after 0.0m I1001 17:57:34.129] Call: git config --local user.email k8s_bootstrap@localhost I1001 17:57:34.132] process 59 exited with code 0 after 0.0m I1001 17:57:34.132] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azuredisk-csi-driver master +refs/pull/166/head:refs/pr/166 I1001 17:57:39.925] process 60 exited with code 0 after 0.1m I1001 17:57:39.926] Call: git checkout -B test 6b4d4209385f8a3b04a39f1970017a27f2667a9a W1001 17:57:40.367] Switched to a new branch 'test' I1001 17:57:40.371] process 72 exited with code 0 after 0.0m I1001 17:57:40.371] Call: git show -s --format=format:%ct HEAD I1001 17:57:40.375] process 73 exited with code 0 after 0.0m I1001 17:57:40.375] Call: git merge --no-ff -m 'Merge +refs/pull/166/head:refs/pr/166' 35f2901acb3478c935a8b603cb4856fe60190383 I1001 17:57:40.869] Removing test/integration/azure.json I1001 17:57:40.870] Removing test/e2e/run-test.sh I1001 17:57:40.870] Removing .travis.yml I1001 17:57:40.870] Merge made by the 'recursive' strategy. I1001 17:57:40.876] .travis.yml | 36 - I1001 17:57:40.877] Gopkg.lock | 17 +- I1001 17:57:40.877] Makefile | 103 +- I1001 17:57:40.877] hack/verify-all.sh | 2 +- I1001 17:57:40.877] hack/verify-dep.sh | 1 + I1001 17:57:40.877] hack/verify-gofmt.sh | 2 +- I1001 17:57:40.877] hack/verify-golint.sh | 7 +- I1001 17:57:40.878] pkg/azuredisk/controllerserver.go | 2 +- I1001 17:57:40.878] test/e2e/dynamic_provisioning.go | 10 - I1001 17:57:40.878] test/e2e/pre_provisioning.go | 16 +- I1001 17:57:40.878] test/e2e/run-test.sh | 37 - I1001 17:57:40.878] test/e2e/suite_test.go | 87 + I1001 17:57:40.878] test/integration/azure.json | 33 - I1001 17:57:40.878] test/integration/integration_test.go | 90 + I1001 17:57:40.879] test/integration/run-test.sh | 157 +- I1001 17:57:40.879] test/integration/run-tests-all-clouds.sh | 32 +- I1001 17:57:40.879] test/sanity/run-test.sh | 47 +- I1001 17:57:40.879] test/sanity/run-tests-all-clouds.sh | 37 +- I1001 17:57:40.879] test/sanity/sanity_test.go | 72 +- I1001 17:57:40.879] test/utils/azure/azure_helpers.go | 96 + I1001 17:57:40.880] test/utils/credentials/credentials.go | 188 ++ I1001 17:57:40.880] test/utils/credentials/credentials_test.go | 181 ++ I1001 17:57:40.880] test/utils/testutil/testutil.go | 8 + I1001 17:57:40.880] .../resources/mgmt/2018-05-01/resources/client.go | 51 + I1001 17:57:40.880] .../2018-05-01/resources/deploymentoperations.go | 474 +++++ I1001 17:57:40.880] .../mgmt/2018-05-01/resources/deployments.go | 1553 +++++++++++++++ I1001 17:57:40.880] .../resources/mgmt/2018-05-01/resources/groups.go | 676 +++++++ I1001 17:57:40.881] .../resources/mgmt/2018-05-01/resources/models.go | 2044 ++++++++++++++++++++ I1001 17:57:40.881] .../mgmt/2018-05-01/resources/operations.go | 147 ++ I1001 17:57:40.881] .../mgmt/2018-05-01/resources/providers.go | 392 ++++ I1001 17:57:40.881] .../mgmt/2018-05-01/resources/resources.go | 1352 +++++++++++++ I1001 17:57:40.881] .../resources/mgmt/2018-05-01/resources/tags.go | 454 +++++ I1001 17:57:40.881] .../resources/mgmt/2018-05-01/resources/version.go | 30 + I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/LICENSE | 21 + I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/doc.go | 23 + I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/fuzz.go | 31 + I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/keysparsing.go | 113 ++ I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/lexer.go | 752 +++++++ I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/marshal.go | 803 ++++++++ I1001 17:57:40.882] vendor/github.com/pelletier/go-toml/parser.go | 442 +++++ I1001 17:57:40.883] vendor/github.com/pelletier/go-toml/position.go | 29 + I1001 17:57:40.883] vendor/github.com/pelletier/go-toml/token.go | 144 ++ I1001 17:57:40.883] vendor/github.com/pelletier/go-toml/toml.go | 393 ++++ I1001 17:57:40.883] .../pelletier/go-toml/tomltree_create.go | 142 ++ I1001 17:57:40.883] .../github.com/pelletier/go-toml/tomltree_write.go | 434 +++++ I1001 17:57:40.883] .../azure/azure_controller_common.go | 29 +- I1001 17:57:40.883] 46 files changed, 11400 insertions(+), 390 deletions(-) I1001 17:57:40.884] delete mode 100644 .travis.yml I1001 17:57:40.884] delete mode 100755 test/e2e/run-test.sh I1001 17:57:40.884] delete mode 100644 test/integration/azure.json I1001 17:57:40.884] create mode 100644 test/integration/integration_test.go I1001 17:57:40.884] create mode 100644 test/utils/azure/azure_helpers.go I1001 17:57:40.884] create mode 100644 test/utils/credentials/credentials.go I1001 17:57:40.885] create mode 100644 test/utils/credentials/credentials_test.go I1001 17:57:40.885] create mode 100644 test/utils/testutil/testutil.go I1001 17:57:40.885] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/client.go I1001 17:57:40.885] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deploymentoperations.go I1001 17:57:40.885] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deployments.go I1001 17:57:40.885] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/groups.go I1001 17:57:40.886] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/models.go I1001 17:57:40.886] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/operations.go I1001 17:57:40.886] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/providers.go I1001 17:57:40.886] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/resources.go I1001 17:57:40.886] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/tags.go I1001 17:57:40.887] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/version.go I1001 17:57:40.887] create mode 100644 vendor/github.com/pelletier/go-toml/LICENSE I1001 17:57:40.887] create mode 100644 vendor/github.com/pelletier/go-toml/doc.go I1001 17:57:40.887] create mode 100644 vendor/github.com/pelletier/go-toml/fuzz.go I1001 17:57:40.887] create mode 100644 vendor/github.com/pelletier/go-toml/keysparsing.go I1001 17:57:40.887] create mode 100644 vendor/github.com/pelletier/go-toml/lexer.go I1001 17:57:40.887] create mode 100644 vendor/github.com/pelletier/go-toml/marshal.go I1001 17:57:40.888] create mode 100644 vendor/github.com/pelletier/go-toml/parser.go I1001 17:57:40.888] create mode 100644 vendor/github.com/pelletier/go-toml/position.go I1001 17:57:40.888] create mode 100644 vendor/github.com/pelletier/go-toml/token.go I1001 17:57:40.888] create mode 100644 vendor/github.com/pelletier/go-toml/toml.go I1001 17:57:40.888] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_create.go I1001 17:57:40.888] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_write.go I1001 17:57:40.889] process 74 exited with code 0 after 0.0m I1001 17:57:40.889] Configure environment... I1001 17:57:40.889] Call: git show -s --format=format:%ct HEAD I1001 17:57:40.893] process 76 exited with code 0 after 0.0m I1001 17:57:40.893] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:57:42.706] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:57:42.843] process 77 exited with code 0 after 0.0m I1001 17:57:42.843] Call: gcloud config get-value account I1001 17:57:43.240] process 89 exited with code 0 after 0.0m I1001 17:57:43.241] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:57:43.241] Start 1179092917625556992 at unknown... I1001 17:57:43.243] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_J6nRLQ gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179092917625556992/started.json I1001 17:57:44.840] process 101 exited with code 0 after 0.0m I1001 17:57:44.841] 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/1179092917625556992' cp /tmp/gsutil_YkzoZB gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/1179092917625556992.txt I1001 17:57:46.580] process 279 exited with code 0 after 0.0m I1001 17:57:46.581] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make verify W1001 17:57:46.613] Run: ('make', 'verify') I1001 17:57:46.714] hack/verify-all.sh I1001 17:57:46.714] Verifying gofmt I1001 17:57:46.750] No issue found I1001 17:57:46.755] Verifying govet I1001 18:00:16.549] Done W1001 18:00:16.713] golangci/golangci-lint info checking GitHub for tag 'v1.17.1' W1001 18:00:17.015] golangci/golangci-lint info found version: 1.17.1 for v1.17.1/linux/amd64 W1001 18:00:19.100] golangci/golangci-lint info installed ./bin/golangci-lint I1001 18:00:33.554] Congratulations! All Go source files have been linted. I1001 18:00:33.556] Verifying dep check I1001 18:00:38.728] # vendor is out of sync: I1001 18:00:38.728] k8s.io/legacy-cloud-providers: hash of vendored tree not equal to digest in Gopkg.lock I1001 18:00:38.730] Makefile:42: recipe for target 'verify' failed W1001 18:00:38.735] make: *** [verify] Error 1 W1001 18:00:38.735] Traceback (most recent call last): W1001 18:00:38.735] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1001 18:00:38.735] main(ARGS.env, ARGS.cmd + ARGS.args) W1001 18:00:38.736] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1001 18:00:38.736] check(*cmd) W1001 18:00:38.736] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1001 18:00:38.736] subprocess.check_call(cmd) W1001 18:00:38.736] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1001 18:00:38.736] raise CalledProcessError(retcode, cmd) W1001 18:00:38.737] subprocess.CalledProcessError: Command '('make', 'verify')' returned non-zero exit status 2 E1001 18:00:38.737] Command failed I1001 18:00:38.737] process 457 exited with code 1 after 2.9m E1001 18:00:38.737] FAIL: pull-azuredisk-csi-driver-verify I1001 18:00:38.737] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 18:00:39.381] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 18:00:39.414] process 12740 exited with code 0 after 0.0m I1001 18:00:39.414] Call: gcloud config get-value account I1001 18:00:39.761] process 12752 exited with code 0 after 0.0m I1001 18:00:39.762] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 18:00:39.762] Upload result and artifacts... I1001 18:00:39.762] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179092917625556992 W1001 18:00:39.762] Missing local artifacts : /workspace/_artifacts W1001 18:00:39.762] metadata path /workspace/_artifacts/metadata.json does not exist W1001 18:00:39.763] metadata not found or invalid, init with empty metadata I1001 18:00:39.763] Call: git rev-parse HEAD I1001 18:00:39.771] process 12764 exited with code 0 after 0.0m I1001 18:00:39.772] Call: git rev-parse HEAD I1001 18:00:39.780] process 12765 exited with code 0 after 0.0m I1001 18:00:39.780] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 18:00:41.882] process 12766 exited with code 0 after 0.0m I1001 18:00:41.883] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/jobResultsCache.json#1569951873903566' I1001 18:00:43.492] process 12908 exited with code 0 after 0.0m I1001 18:00:43.493] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569951873903566 cp /tmp/gsutil_jKz_sP gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 18:00:45.903] process 13052 exited with code 0 after 0.0m I1001 18:00:45.903] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 18:00:46.906] process 13230 exited with code 0 after 0.0m I1001 18:00:46.906] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/jobResultsCache.json#1569951876345431' I1001 18:00:48.284] process 13372 exited with code 0 after 0.0m I1001 18:00:48.285] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569951876345431 cp /tmp/gsutil_ecYRU1 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/jobResultsCache.json I1001 18:00:50.600] process 13516 exited with code 0 after 0.0m I1001 18:00:50.600] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_wLIIEt gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/1179092917625556992/finished.json I1001 18:00:52.125] process 13694 exited with code 0 after 0.0m I1001 18:00:52.126] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_jzglSd gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-verify/latest-build.txt I1001 18:00:53.483] process 13872 exited with code 0 after 0.0m I1001 18:00:53.501] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_SASRTq gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-verify/latest-build.txt I1001 18:00:54.814] process 14050 exited with code 0 after 0.0m