W1001 17:41:25.052] ************************************************************************** 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.052] Args: --job=pull-azuredisk-csi-driver-integration --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azuredisk-csi-driver-integration --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 integration-test I1001 17:41:25.052] Bootstrap pull-azuredisk-csi-driver-integration... I1001 17:41:25.055] Builder: 90da306a-e472-11e9-918b-16773c0c7aed I1001 17:41:25.056] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190927-21e0205-master I1001 17:41:25.056] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179088765260402691 I1001 17:41:25.056] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:41:25.661] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:41:25.696] process 32 exited with code 0 after 0.0m I1001 17:41:25.696] Call: gcloud config get-value account I1001 17:41:25.990] process 44 exited with code 0 after 0.0m I1001 17:41:25.990] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:41:25.991] Root: /go/src I1001 17:41:25.991] cd to /go/src I1001 17:41:25.991] 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:25.991] Call: git init github.com/kubernetes-sigs/azuredisk-csi-driver I1001 17:41:25.997] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/.git/ I1001 17:41:25.998] process 56 exited with code 0 after 0.0m I1001 17:41:25.998] Call: git config --local user.name 'K8S Bootstrap' I1001 17:41:26.003] process 57 exited with code 0 after 0.0m I1001 17:41:26.003] Call: git config --local user.email k8s_bootstrap@localhost I1001 17:41:26.006] process 58 exited with code 0 after 0.0m I1001 17:41:26.007] 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.809] process 59 exited with code 0 after 0.1m I1001 17:41:33.809] Call: git checkout -B test 6b4d4209385f8a3b04a39f1970017a27f2667a9a W1001 17:41:34.256] Switched to a new branch 'test' I1001 17:41:34.259] process 71 exited with code 0 after 0.0m I1001 17:41:34.260] Call: git show -s --format=format:%ct HEAD I1001 17:41:34.264] process 72 exited with code 0 after 0.0m I1001 17:41:34.265] Call: git merge --no-ff -m 'Merge +refs/pull/166/head:refs/pr/166' f4689cb00bc33d7617c7a4ddd6b460c88b65a9ab I1001 17:41:34.601] Removing test/integration/azure.json I1001 17:41:34.601] Removing test/e2e/run-test.sh I1001 17:41:34.601] Merge made by the 'recursive' strategy. I1001 17:41:34.608] .travis.yml | 21 +- I1001 17:41:34.608] Gopkg.lock | 17 +- I1001 17:41:34.608] Makefile | 102 +- I1001 17:41:34.608] hack/verify-all.sh | 2 +- I1001 17:41:34.609] hack/verify-gofmt.sh | 2 +- I1001 17:41:34.609] hack/verify-golint.sh | 7 +- I1001 17:41:34.609] pkg/azuredisk/controllerserver.go | 2 +- I1001 17:41:34.609] test/e2e/dynamic_provisioning.go | 10 - I1001 17:41:34.609] test/e2e/pre_provisioning.go | 16 +- I1001 17:41:34.609] test/e2e/run-test.sh | 37 - I1001 17:41:34.610] test/e2e/suite_test.go | 87 + I1001 17:41:34.610] test/integration/azure.json | 33 - I1001 17:41:34.610] test/integration/integration_test.go | 90 + I1001 17:41:34.610] test/integration/run-test.sh | 157 +- I1001 17:41:34.610] test/integration/run-tests-all-clouds.sh | 32 +- I1001 17:41:34.610] test/sanity/run-test.sh | 47 +- I1001 17:41:34.611] test/sanity/run-tests-all-clouds.sh | 37 +- I1001 17:41:34.611] test/sanity/sanity_test.go | 72 +- I1001 17:41:34.611] test/utils/azure/azure_helpers.go | 96 + I1001 17:41:34.611] test/utils/credentials/credentials.go | 188 ++ I1001 17:41:34.611] test/utils/credentials/credentials_test.go | 181 ++ I1001 17:41:34.611] test/utils/testutil/testutil.go | 8 + I1001 17:41:34.612] .../resources/mgmt/2018-05-01/resources/client.go | 51 + I1001 17:41:34.612] .../2018-05-01/resources/deploymentoperations.go | 474 +++++ I1001 17:41:34.612] .../mgmt/2018-05-01/resources/deployments.go | 1553 +++++++++++++++ I1001 17:41:34.612] .../resources/mgmt/2018-05-01/resources/groups.go | 676 +++++++ I1001 17:41:34.612] .../resources/mgmt/2018-05-01/resources/models.go | 2044 ++++++++++++++++++++ I1001 17:41:34.612] .../mgmt/2018-05-01/resources/operations.go | 147 ++ I1001 17:41:34.613] .../mgmt/2018-05-01/resources/providers.go | 392 ++++ I1001 17:41:34.613] .../mgmt/2018-05-01/resources/resources.go | 1352 +++++++++++++ I1001 17:41:34.613] .../resources/mgmt/2018-05-01/resources/tags.go | 454 +++++ I1001 17:41:34.613] .../resources/mgmt/2018-05-01/resources/version.go | 30 + I1001 17:41:34.613] vendor/github.com/pelletier/go-toml/LICENSE | 21 + I1001 17:41:34.613] vendor/github.com/pelletier/go-toml/doc.go | 23 + I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/fuzz.go | 31 + I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/keysparsing.go | 113 ++ I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/lexer.go | 752 +++++++ I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/marshal.go | 803 ++++++++ I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/parser.go | 442 +++++ I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/position.go | 29 + I1001 17:41:34.614] vendor/github.com/pelletier/go-toml/token.go | 144 ++ I1001 17:41:34.615] vendor/github.com/pelletier/go-toml/toml.go | 393 ++++ I1001 17:41:34.615] .../pelletier/go-toml/tomltree_create.go | 142 ++ I1001 17:41:34.615] .../github.com/pelletier/go-toml/tomltree_write.go | 434 +++++ I1001 17:41:34.615] .../azure/azure_controller_common.go | 29 +- I1001 17:41:34.615] 45 files changed, 11409 insertions(+), 364 deletions(-) I1001 17:41:34.615] delete mode 100755 test/e2e/run-test.sh I1001 17:41:34.615] delete mode 100644 test/integration/azure.json I1001 17:41:34.616] create mode 100644 test/integration/integration_test.go I1001 17:41:34.616] create mode 100644 test/utils/azure/azure_helpers.go I1001 17:41:34.616] create mode 100644 test/utils/credentials/credentials.go I1001 17:41:34.616] create mode 100644 test/utils/credentials/credentials_test.go I1001 17:41:34.616] create mode 100644 test/utils/testutil/testutil.go I1001 17:41:34.616] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/client.go I1001 17:41:34.616] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deploymentoperations.go I1001 17:41:34.617] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deployments.go I1001 17:41:34.617] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/groups.go I1001 17:41:34.617] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/models.go I1001 17:41:34.617] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/operations.go I1001 17:41:34.617] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/providers.go I1001 17:41:34.617] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/resources.go I1001 17:41:34.618] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/tags.go I1001 17:41:34.618] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/version.go I1001 17:41:34.618] create mode 100644 vendor/github.com/pelletier/go-toml/LICENSE I1001 17:41:34.618] create mode 100644 vendor/github.com/pelletier/go-toml/doc.go I1001 17:41:34.618] create mode 100644 vendor/github.com/pelletier/go-toml/fuzz.go I1001 17:41:34.618] create mode 100644 vendor/github.com/pelletier/go-toml/keysparsing.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/lexer.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/marshal.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/parser.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/position.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/token.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/toml.go I1001 17:41:34.619] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_create.go I1001 17:41:34.620] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_write.go I1001 17:41:34.620] process 73 exited with code 0 after 0.0m I1001 17:41:34.620] Configure environment... I1001 17:41:34.620] Call: git show -s --format=format:%ct HEAD I1001 17:41:34.624] process 75 exited with code 0 after 0.0m I1001 17:41:34.624] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:41:35.228] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:41:35.338] process 76 exited with code 0 after 0.0m I1001 17:41:35.338] Call: gcloud config get-value account I1001 17:41:35.600] process 88 exited with code 0 after 0.0m I1001 17:41:35.601] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:41:35.601] Start 1179088765260402691 at unknown... I1001 17:41:35.603] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_rAKSrb gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179088765260402691/started.json I1001 17:41:37.338] process 100 exited with code 0 after 0.0m I1001 17:41:37.339] 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-integration/1179088765260402691' cp /tmp/gsutil_DPAQhY gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/1179088765260402691.txt I1001 17:41:39.028] process 278 exited with code 0 after 0.0m I1001 17:41:39.029] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make integration-test W1001 17:41:39.056] Run: ('make', 'integration-test') I1001 17:41:39.156] if [ ! -d ./vendor ]; then dep ensure -vendor-only; fi I1001 17:41:39.157] CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.driverVersion=e2e- -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.gitCommit=3f222044af0d020ba548d5ab0f64551eb331cd04 -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.buildDate=2019-10-01T17:41:39Z -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static"" -o _output/azurediskplugin ./pkg/azurediskplugin I1001 17:43:44.734] go test -v -timeout=20m ./test/integration I1001 17:44:04.798] === RUN TestIntegrationOnAzurePublicCloud I1001 17:44:04.799] 2019/10/01 17:44:04 Creating resource group azuredisk-csi-driver-test-0fbdf1f5-e473-11e9-b27e-0aed933203a4 in AzurePublicCloud I1001 17:44:06.858] ./test/integration/run-tests-all-clouds.sh: line 19: sudo: command not found I1001 17:44:35.108] ./test/integration/run-tests-all-clouds.sh: line 27: nodeid: unbound variable I1001 17:44:35.109] 2019/10/01 17:44:35 Deleting resource group azuredisk-csi-driver-test-0fbdf1f5-e473-11e9-b27e-0aed933203a4 I1001 17:46:06.758] --- FAIL: TestIntegrationOnAzurePublicCloud (121.96s) I1001 17:46:06.758] integration_test.go:88: Integration test failed exit status 1 I1001 17:46:06.759] === RUN TestIntegrationOnAzureChinaCloud I1001 17:46:06.759] --- SKIP: TestIntegrationOnAzureChinaCloud (0.00s) I1001 17:46:06.759] integration_test.go:32: Skipping integration test on Azure China Cloud because Prow only tests on Azure Public Cloud at the moment I1001 17:46:06.759] FAIL I1001 17:46:06.761] FAIL github.com/kubernetes-sigs/azuredisk-csi-driver/test/integration 121.970s I1001 17:46:06.792] Makefile:54: recipe for target 'integration-test' failed W1001 17:46:06.797] make: *** [integration-test] Error 1 W1001 17:46:06.797] Traceback (most recent call last): W1001 17:46:06.797] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1001 17:46:06.797] main(ARGS.env, ARGS.cmd + ARGS.args) W1001 17:46:06.797] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1001 17:46:06.798] check(*cmd) W1001 17:46:06.798] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1001 17:46:06.798] subprocess.check_call(cmd) W1001 17:46:06.798] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1001 17:46:06.798] raise CalledProcessError(retcode, cmd) W1001 17:46:06.798] subprocess.CalledProcessError: Command '('make', 'integration-test')' returned non-zero exit status 2 E1001 17:46:06.798] Command failed I1001 17:46:06.799] process 456 exited with code 1 after 4.5m E1001 17:46:06.799] FAIL: pull-azuredisk-csi-driver-integration I1001 17:46:06.799] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:46:08.394] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:46:08.425] process 8724 exited with code 0 after 0.0m I1001 17:46:08.425] Call: gcloud config get-value account I1001 17:46:08.677] process 8736 exited with code 0 after 0.0m I1001 17:46:08.677] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:46:08.677] Upload result and artifacts... I1001 17:46:08.677] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179088765260402691 W1001 17:46:08.678] Missing local artifacts : /workspace/_artifacts W1001 17:46:08.678] metadata path /workspace/_artifacts/metadata.json does not exist W1001 17:46:08.678] metadata not found or invalid, init with empty metadata I1001 17:46:08.678] Call: git rev-parse HEAD I1001 17:46:08.680] process 8748 exited with code 0 after 0.0m I1001 17:46:08.681] Call: git rev-parse HEAD I1001 17:46:08.683] process 8749 exited with code 0 after 0.0m I1001 17:46:08.683] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/jobResultsCache.json W1001 17:46:09.673] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 17:46:09.673] process 8750 exited with code 1 after 0.0m I1001 17:46:09.674] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_utgBUD gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 17:46:11.132] process 8892 exited with code 0 after 0.0m I1001 17:46:11.133] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/jobResultsCache.json W1001 17:46:12.043] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 17:46:12.043] process 9070 exited with code 1 after 0.0m I1001 17:46:12.044] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_KUxBzk gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 17:46:13.319] process 9212 exited with code 0 after 0.0m I1001 17:46:13.320] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_T7S5eM gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179088765260402691/finished.json I1001 17:46:14.584] process 9390 exited with code 0 after 0.0m I1001 17:46:14.585] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_Gly4sR gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/latest-build.txt I1001 17:46:15.859] process 9568 exited with code 0 after 0.0m I1001 17:46:15.860] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_8oZ4fQ gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/latest-build.txt I1001 17:46:17.279] process 9746 exited with code 0 after 0.0m