W1001 17:57:33.238] ************************************************************************** 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.238] 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:35f2901acb3478c935a8b603cb4856fe60190383 --upload=gs://kubernetes-upstream/pr-logs --scenario=execute -- make integration-test I1001 17:57:33.239] Bootstrap pull-azuredisk-csi-driver-integration... I1001 17:57:33.242] Builder: dedee316-e474-11e9-918b-16773c0c7aed I1001 17:57:33.243] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190927-21e0205-master I1001 17:57:33.243] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179092917625556995 I1001 17:57:33.243] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:57:33.780] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:57:33.812] process 32 exited with code 0 after 0.0m I1001 17:57:33.812] Call: gcloud config get-value account I1001 17:57:34.169] process 44 exited with code 0 after 0.0m I1001 17:57:34.170] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:57:34.170] Root: /go/src I1001 17:57:34.170] cd to /go/src I1001 17:57:34.170] 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.170] Call: git init github.com/kubernetes-sigs/azuredisk-csi-driver I1001 17:57:34.175] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/.git/ I1001 17:57:34.175] process 56 exited with code 0 after 0.0m I1001 17:57:34.176] Call: git config --local user.name 'K8S Bootstrap' I1001 17:57:34.178] process 57 exited with code 0 after 0.0m I1001 17:57:34.178] Call: git config --local user.email k8s_bootstrap@localhost I1001 17:57:34.180] process 58 exited with code 0 after 0.0m I1001 17:57:34.181] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azuredisk-csi-driver master +refs/pull/166/head:refs/pr/166 I1001 17:57:42.675] process 59 exited with code 0 after 0.1m I1001 17:57:42.676] Call: git checkout -B test 6b4d4209385f8a3b04a39f1970017a27f2667a9a W1001 17:57:43.371] Switched to a new branch 'test' I1001 17:57:43.376] process 71 exited with code 0 after 0.0m I1001 17:57:43.376] Call: git show -s --format=format:%ct HEAD I1001 17:57:43.380] process 72 exited with code 0 after 0.0m I1001 17:57:43.381] Call: git merge --no-ff -m 'Merge +refs/pull/166/head:refs/pr/166' 35f2901acb3478c935a8b603cb4856fe60190383 I1001 17:57:43.786] Removing test/integration/azure.json I1001 17:57:43.787] Removing test/e2e/run-test.sh I1001 17:57:43.787] Removing .travis.yml I1001 17:57:43.787] Merge made by the 'recursive' strategy. I1001 17:57:43.797] .travis.yml | 36 - I1001 17:57:43.797] Gopkg.lock | 17 +- I1001 17:57:43.797] Makefile | 103 +- I1001 17:57:43.798] hack/verify-all.sh | 2 +- I1001 17:57:43.798] hack/verify-dep.sh | 1 + I1001 17:57:43.798] hack/verify-gofmt.sh | 2 +- I1001 17:57:43.798] hack/verify-golint.sh | 7 +- I1001 17:57:43.799] pkg/azuredisk/controllerserver.go | 2 +- I1001 17:57:43.799] test/e2e/dynamic_provisioning.go | 10 - I1001 17:57:43.799] test/e2e/pre_provisioning.go | 16 +- I1001 17:57:43.799] test/e2e/run-test.sh | 37 - I1001 17:57:43.799] test/e2e/suite_test.go | 87 + I1001 17:57:43.800] test/integration/azure.json | 33 - I1001 17:57:43.800] test/integration/integration_test.go | 90 + I1001 17:57:43.800] test/integration/run-test.sh | 157 +- I1001 17:57:43.800] test/integration/run-tests-all-clouds.sh | 32 +- I1001 17:57:43.800] test/sanity/run-test.sh | 47 +- I1001 17:57:43.801] test/sanity/run-tests-all-clouds.sh | 37 +- I1001 17:57:43.801] test/sanity/sanity_test.go | 72 +- I1001 17:57:43.801] test/utils/azure/azure_helpers.go | 96 + I1001 17:57:43.801] test/utils/credentials/credentials.go | 188 ++ I1001 17:57:43.801] test/utils/credentials/credentials_test.go | 181 ++ I1001 17:57:43.802] test/utils/testutil/testutil.go | 8 + I1001 17:57:43.802] .../resources/mgmt/2018-05-01/resources/client.go | 51 + I1001 17:57:43.802] .../2018-05-01/resources/deploymentoperations.go | 474 +++++ I1001 17:57:43.802] .../mgmt/2018-05-01/resources/deployments.go | 1553 +++++++++++++++ I1001 17:57:43.802] .../resources/mgmt/2018-05-01/resources/groups.go | 676 +++++++ I1001 17:57:43.803] .../resources/mgmt/2018-05-01/resources/models.go | 2044 ++++++++++++++++++++ I1001 17:57:43.803] .../mgmt/2018-05-01/resources/operations.go | 147 ++ I1001 17:57:43.803] .../mgmt/2018-05-01/resources/providers.go | 392 ++++ I1001 17:57:43.803] .../mgmt/2018-05-01/resources/resources.go | 1352 +++++++++++++ I1001 17:57:43.804] .../resources/mgmt/2018-05-01/resources/tags.go | 454 +++++ I1001 17:57:43.804] .../resources/mgmt/2018-05-01/resources/version.go | 30 + I1001 17:57:43.804] vendor/github.com/pelletier/go-toml/LICENSE | 21 + I1001 17:57:43.804] vendor/github.com/pelletier/go-toml/doc.go | 23 + I1001 17:57:43.804] vendor/github.com/pelletier/go-toml/fuzz.go | 31 + I1001 17:57:43.805] vendor/github.com/pelletier/go-toml/keysparsing.go | 113 ++ I1001 17:57:43.805] vendor/github.com/pelletier/go-toml/lexer.go | 752 +++++++ I1001 17:57:43.805] vendor/github.com/pelletier/go-toml/marshal.go | 803 ++++++++ I1001 17:57:43.805] vendor/github.com/pelletier/go-toml/parser.go | 442 +++++ I1001 17:57:43.806] vendor/github.com/pelletier/go-toml/position.go | 29 + I1001 17:57:43.806] vendor/github.com/pelletier/go-toml/token.go | 144 ++ I1001 17:57:43.806] vendor/github.com/pelletier/go-toml/toml.go | 393 ++++ I1001 17:57:43.806] .../pelletier/go-toml/tomltree_create.go | 142 ++ I1001 17:57:43.806] .../github.com/pelletier/go-toml/tomltree_write.go | 434 +++++ I1001 17:57:43.807] .../azure/azure_controller_common.go | 29 +- I1001 17:57:43.807] 46 files changed, 11400 insertions(+), 390 deletions(-) I1001 17:57:43.807] delete mode 100644 .travis.yml I1001 17:57:43.807] delete mode 100755 test/e2e/run-test.sh I1001 17:57:43.808] delete mode 100644 test/integration/azure.json I1001 17:57:43.808] create mode 100644 test/integration/integration_test.go I1001 17:57:43.808] create mode 100644 test/utils/azure/azure_helpers.go I1001 17:57:43.808] create mode 100644 test/utils/credentials/credentials.go I1001 17:57:43.808] create mode 100644 test/utils/credentials/credentials_test.go I1001 17:57:43.809] create mode 100644 test/utils/testutil/testutil.go I1001 17:57:43.809] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/client.go I1001 17:57:43.809] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deploymentoperations.go I1001 17:57:43.809] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/deployments.go I1001 17:57:43.810] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/groups.go I1001 17:57:43.810] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/models.go I1001 17:57:43.810] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/operations.go I1001 17:57:43.810] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/providers.go I1001 17:57:43.811] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/resources.go I1001 17:57:43.811] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/tags.go I1001 17:57:43.811] create mode 100644 vendor/github.com/Azure/azure-sdk-for-go/services/resources/mgmt/2018-05-01/resources/version.go I1001 17:57:43.811] create mode 100644 vendor/github.com/pelletier/go-toml/LICENSE I1001 17:57:43.812] create mode 100644 vendor/github.com/pelletier/go-toml/doc.go I1001 17:57:43.812] create mode 100644 vendor/github.com/pelletier/go-toml/fuzz.go I1001 17:57:43.812] create mode 100644 vendor/github.com/pelletier/go-toml/keysparsing.go I1001 17:57:43.812] create mode 100644 vendor/github.com/pelletier/go-toml/lexer.go I1001 17:57:43.813] create mode 100644 vendor/github.com/pelletier/go-toml/marshal.go I1001 17:57:43.813] create mode 100644 vendor/github.com/pelletier/go-toml/parser.go I1001 17:57:43.813] create mode 100644 vendor/github.com/pelletier/go-toml/position.go I1001 17:57:43.813] create mode 100644 vendor/github.com/pelletier/go-toml/token.go I1001 17:57:43.813] create mode 100644 vendor/github.com/pelletier/go-toml/toml.go I1001 17:57:43.814] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_create.go I1001 17:57:43.814] create mode 100644 vendor/github.com/pelletier/go-toml/tomltree_write.go I1001 17:57:43.814] process 73 exited with code 0 after 0.0m I1001 17:57:43.814] Configure environment... I1001 17:57:43.815] Call: git show -s --format=format:%ct HEAD I1001 17:57:43.820] process 75 exited with code 0 after 0.0m I1001 17:57:43.821] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 17:57:44.407] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 17:57:44.518] process 76 exited with code 0 after 0.0m I1001 17:57:44.518] Call: gcloud config get-value account I1001 17:57:44.793] process 88 exited with code 0 after 0.0m I1001 17:57:44.794] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 17:57:44.794] Start 1179092917625556995 at unknown... I1001 17:57:44.797] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_P6D8TL gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179092917625556995/started.json I1001 17:57:46.454] process 100 exited with code 0 after 0.0m I1001 17:57:46.455] 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/1179092917625556995' cp /tmp/gsutil_GsMi6v gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/1179092917625556995.txt I1001 17:57:48.270] process 278 exited with code 0 after 0.0m I1001 17:57:48.271] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make integration-test W1001 17:57:48.303] Run: ('make', 'integration-test') I1001 17:57:48.404] if [ ! -d ./vendor ]; then dep ensure -vendor-only; fi I1001 17:57:48.405] 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=9a06ade8e6e1381f88f4332ea800e16364a342d0 -X github.com/kubernetes-sigs/azuredisk-csi-driver/pkg/azuredisk.buildDate=2019-10-01T17:57:48Z -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:59:53.555] go test -v -timeout=20m ./test/integration I1001 18:00:14.275] === RUN TestIntegrationOnAzurePublicCloud I1001 18:00:14.276] 2019/10/01 18:00:14 Creating resource group azuredisk-csi-driver-test-51985d50-e475-11e9-88fe-d692a71f8452 in AzurePublicCloud I1001 18:00:16.443] I1001 18:00:16.443] WARNING: apt does not have a stable CLI interface. Use with caution in scripts. I1001 18:00:16.444] I1001 18:00:16.582] Get:1 https://download.docker.com/linux/debian stretch InRelease [44.8 kB] I1001 18:00:16.762] Get:2 http://security-cdn.debian.org/debian-security stretch/updates InRelease [94.3 kB] I1001 18:00:16.805] Get:3 https://download.docker.com/linux/debian stretch/stable amd64 Packages [10.7 kB] I1001 18:00:17.008] Get:4 http://security-cdn.debian.org/debian-security stretch/updates/main amd64 Packages [499 kB] I1001 18:00:24.490] Ign:5 http://cdn-fastly.deb.debian.org/debian stretch InRelease I1001 18:00:24.598] Get:6 http://cdn-fastly.deb.debian.org/debian stretch-updates InRelease [91.0 kB] I1001 18:00:26.214] Get:7 http://cdn-fastly.deb.debian.org/debian stretch Release [118 kB] I1001 18:00:26.438] Get:8 http://cdn-fastly.deb.debian.org/debian stretch Release.gpg [2365 B] I1001 18:00:26.526] Get:9 http://cdn-fastly.deb.debian.org/debian stretch-updates/main amd64 Packages [27.4 kB] I1001 18:00:26.841] Get:10 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 Packages [7086 kB] I1001 18:00:28.755] Fetched 7974 kB in 12s (650 kB/s) I1001 18:00:29.686] Reading package lists... I1001 18:00:29.920] Building dependency tree... I1001 18:00:29.920] Reading state information... I1001 18:00:29.930] 26 packages can be upgraded. Run 'apt list --upgradable' to see them. I1001 18:00:29.943] I1001 18:00:29.944] WARNING: apt does not have a stable CLI interface. Use with caution in scripts. I1001 18:00:29.944] I1001 18:00:30.963] Reading package lists... I1001 18:00:31.153] Building dependency tree... I1001 18:00:31.153] Reading state information... I1001 18:00:31.248] procps is already the newest version (2:3.3.12-3+deb9u1). I1001 18:00:31.249] The following additional packages will be installed: I1001 18:00:31.249] libavahi-client3 libavahi-common-data libavahi-common3 libcups2 libldb1 I1001 18:00:31.249] libtalloc2 libtdb1 libtevent0 libwbclient0 python-crypto python-ldb I1001 18:00:31.249] python-samba python-talloc python-tdb samba-common samba-common-bin I1001 18:00:31.250] samba-libs I1001 18:00:31.250] Suggested packages: I1001 18:00:31.250] keyutils smbclient winbind cups-common python-crypto-dbg python-crypto-doc I1001 18:00:31.251] python-gpgme heimdal-clients I1001 18:00:31.532] The following NEW packages will be installed: I1001 18:00:31.532] cifs-utils libavahi-client3 libavahi-common-data libavahi-common3 libcups2 I1001 18:00:31.532] libldb1 libtalloc2 libtdb1 libtevent0 libwbclient0 python-crypto python-ldb I1001 18:00:31.532] python-samba python-talloc python-tdb samba-common samba-common-bin I1001 18:00:31.533] samba-libs I1001 18:00:31.742] 0 upgraded, 18 newly installed, 0 to remove and 26 not upgraded. I1001 18:00:31.742] Need to get 8646 kB of archives. I1001 18:00:31.743] After this operation, 38.4 MB of additional disk space will be used. I1001 18:00:31.743] Get:1 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libwbclient0 amd64 2:4.5.16+dfsg-1+deb9u2 [124 kB] I1001 18:00:31.918] Get:2 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-common all 2:4.5.16+dfsg-1+deb9u2 [174 kB] I1001 18:00:31.962] Get:3 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtalloc2 amd64 2.1.8-1 [36.3 kB] I1001 18:00:31.963] Get:4 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 cifs-utils amd64 2:6.7-1 [75.8 kB] I1001 18:00:31.965] Get:5 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-common-data amd64 0.6.32-2 [118 kB] I1001 18:00:32.006] Get:6 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-common3 amd64 0.6.32-2 [52.0 kB] I1001 18:00:32.007] Get:7 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-client3 amd64 0.6.32-2 [55.3 kB] I1001 18:00:32.008] Get:8 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libcups2 amd64 2.2.1-8+deb9u4 [307 kB] I1001 18:00:32.013] Get:9 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtdb1 amd64 1.3.11-2 [43.2 kB] I1001 18:00:32.014] Get:10 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtevent0 amd64 0.9.31-1 [29.3 kB] I1001 18:00:32.049] Get:11 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libldb1 amd64 2:1.1.27-1+deb9u1 [113 kB] I1001 18:00:32.051] Get:12 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-crypto amd64 2.6.1-7 [259 kB] I1001 18:00:32.092] Get:13 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-ldb amd64 2:1.1.27-1+deb9u1 [34.8 kB] I1001 18:00:32.093] Get:14 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-tdb amd64 1.3.11-2 [16.2 kB] I1001 18:00:32.094] Get:15 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-talloc amd64 2.1.8-1 [11.9 kB] I1001 18:00:32.094] Get:16 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-libs amd64 2:4.5.16+dfsg-1+deb9u2 [5231 kB] I1001 18:00:32.232] Get:17 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-samba amd64 2:4.5.16+dfsg-1+deb9u2 [1360 kB] I1001 18:00:32.255] Get:18 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-common-bin amd64 2:4.5.16+dfsg-1+deb9u2 [603 kB] I1001 18:00:32.469] debconf: delaying package configuration, since apt-utils is not installed I1001 18:00:32.517] Fetched 8646 kB in 0s (11.9 MB/s) I1001 18:00:32.674] Selecting previously unselected package libwbclient0:amd64. I1001 18:00:32.684] (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19609 files and directories currently installed.) I1001 18:00:32.684] Preparing to unpack .../00-libwbclient0_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I1001 18:00:32.759] Unpacking libwbclient0:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:33.033] Selecting previously unselected package samba-common. I1001 18:00:33.034] Preparing to unpack .../01-samba-common_2%3a4.5.16+dfsg-1+deb9u2_all.deb ... I1001 18:00:33.067] Unpacking samba-common (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:33.417] Selecting previously unselected package libtalloc2:amd64. I1001 18:00:33.420] Preparing to unpack .../02-libtalloc2_2.1.8-1_amd64.deb ... I1001 18:00:33.449] Unpacking libtalloc2:amd64 (2.1.8-1) ... I1001 18:00:33.628] Selecting previously unselected package cifs-utils. I1001 18:00:33.630] Preparing to unpack .../03-cifs-utils_2%3a6.7-1_amd64.deb ... I1001 18:00:33.644] Unpacking cifs-utils (2:6.7-1) ... I1001 18:00:33.767] Selecting previously unselected package libavahi-common-data:amd64. I1001 18:00:33.770] Preparing to unpack .../04-libavahi-common-data_0.6.32-2_amd64.deb ... I1001 18:00:33.784] Unpacking libavahi-common-data:amd64 (0.6.32-2) ... I1001 18:00:33.939] Selecting previously unselected package libavahi-common3:amd64. I1001 18:00:33.942] Preparing to unpack .../05-libavahi-common3_0.6.32-2_amd64.deb ... I1001 18:00:33.958] Unpacking libavahi-common3:amd64 (0.6.32-2) ... I1001 18:00:34.097] Selecting previously unselected package libavahi-client3:amd64. I1001 18:00:34.099] Preparing to unpack .../06-libavahi-client3_0.6.32-2_amd64.deb ... I1001 18:00:34.113] Unpacking libavahi-client3:amd64 (0.6.32-2) ... I1001 18:00:34.233] Selecting previously unselected package libcups2:amd64. I1001 18:00:34.235] Preparing to unpack .../07-libcups2_2.2.1-8+deb9u4_amd64.deb ... I1001 18:00:34.286] Unpacking libcups2:amd64 (2.2.1-8+deb9u4) ... I1001 18:00:34.637] Selecting previously unselected package libtdb1:amd64. I1001 18:00:34.638] Preparing to unpack .../08-libtdb1_1.3.11-2_amd64.deb ... I1001 18:00:34.678] Unpacking libtdb1:amd64 (1.3.11-2) ... I1001 18:00:34.839] Selecting previously unselected package libtevent0:amd64. I1001 18:00:34.841] Preparing to unpack .../09-libtevent0_0.9.31-1_amd64.deb ... I1001 18:00:34.856] Unpacking libtevent0:amd64 (0.9.31-1) ... I1001 18:00:34.986] Selecting previously unselected package libldb1:amd64. I1001 18:00:34.988] Preparing to unpack .../10-libldb1_2%3a1.1.27-1+deb9u1_amd64.deb ... I1001 18:00:35.002] Unpacking libldb1:amd64 (2:1.1.27-1+deb9u1) ... I1001 18:00:35.230] Selecting previously unselected package python-crypto. I1001 18:00:35.234] Preparing to unpack .../11-python-crypto_2.6.1-7_amd64.deb ... I1001 18:00:35.254] Unpacking python-crypto (2.6.1-7) ... I1001 18:00:35.606] Selecting previously unselected package python-ldb. I1001 18:00:35.606] Preparing to unpack .../12-python-ldb_2%3a1.1.27-1+deb9u1_amd64.deb ... I1001 18:00:35.631] Unpacking python-ldb (2:1.1.27-1+deb9u1) ... I1001 18:00:35.857] Selecting previously unselected package python-tdb. I1001 18:00:35.857] Preparing to unpack .../13-python-tdb_1.3.11-2_amd64.deb ... I1001 18:00:35.886] Unpacking python-tdb (1.3.11-2) ... I1001 18:00:36.091] Selecting previously unselected package python-talloc. I1001 18:00:36.094] Preparing to unpack .../14-python-talloc_2.1.8-1_amd64.deb ... I1001 18:00:36.119] Unpacking python-talloc (2.1.8-1) ... I1001 18:00:36.335] Selecting previously unselected package samba-libs:amd64. I1001 18:00:36.338] Preparing to unpack .../15-samba-libs_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I1001 18:00:36.370] Unpacking samba-libs:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:37.225] Selecting previously unselected package python-samba. I1001 18:00:37.228] Preparing to unpack .../16-python-samba_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I1001 18:00:37.242] Unpacking python-samba (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:37.579] Selecting previously unselected package samba-common-bin. I1001 18:00:37.581] Preparing to unpack .../17-samba-common-bin_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I1001 18:00:37.596] Unpacking samba-common-bin (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:37.930] Setting up libtdb1:amd64 (1.3.11-2) ... I1001 18:00:38.017] Setting up libwbclient0:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:38.103] Setting up python-crypto (2.6.1-7) ... I1001 18:00:38.814] Setting up python-tdb (1.3.11-2) ... I1001 18:00:39.265] Setting up samba-common (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:39.507] debconf: unable to initialize frontend: Dialog I1001 18:00:39.507] debconf: (No usable dialog-like program is installed, so the dialog based frontend cannot be used. at /usr/share/perl5/Debconf/FrontEnd/Dialog.pm line 76.) I1001 18:00:39.507] debconf: falling back to frontend: Readline I1001 18:00:39.610] I1001 18:00:39.611] Creating config file /etc/samba/smb.conf with new version I1001 18:00:39.824] Processing triggers for libc-bin (2.24-11+deb9u4) ... I1001 18:00:39.908] Setting up libtalloc2:amd64 (2.1.8-1) ... I1001 18:00:39.994] Setting up libavahi-common-data:amd64 (0.6.32-2) ... I1001 18:00:40.073] Setting up python-talloc (2.1.8-1) ... I1001 18:00:40.134] Setting up cifs-utils (2:6.7-1) ... I1001 18:00:40.246] update-alternatives: using /usr/lib/x86_64-linux-gnu/cifs-utils/idmapwb.so to provide /etc/cifs-utils/idmap-plugin (idmap-plugin) in auto mode I1001 18:00:40.290] Setting up libtevent0:amd64 (0.9.31-1) ... I1001 18:00:40.368] Setting up libavahi-common3:amd64 (0.6.32-2) ... I1001 18:00:40.410] Setting up libldb1:amd64 (2:1.1.27-1+deb9u1) ... I1001 18:00:40.482] Setting up libavahi-client3:amd64 (0.6.32-2) ... I1001 18:00:40.534] Setting up libcups2:amd64 (2.2.1-8+deb9u4) ... I1001 18:00:40.600] Setting up python-ldb (2:1.1.27-1+deb9u1) ... I1001 18:00:41.152] Setting up samba-libs:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:41.206] Setting up python-samba (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:42.141] Setting up samba-common-bin (2:4.5.16+dfsg-1+deb9u2) ... I1001 18:00:42.194] Processing triggers for libc-bin (2.24-11+deb9u4) ... I1001 18:01:04.162] ./test/integration/run-tests-all-clouds.sh: line 27: nodeid: unbound variable I1001 18:01:04.162] 2019/10/01 18:01:04 Deleting resource group azuredisk-csi-driver-test-51985d50-e475-11e9-88fe-d692a71f8452 I1001 18:01:50.474] --- FAIL: TestIntegrationOnAzurePublicCloud (96.20s) I1001 18:01:50.475] integration_test.go:88: Integration test failed exit status 1 I1001 18:01:50.475] === RUN TestIntegrationOnAzureChinaCloud I1001 18:01:50.475] --- SKIP: TestIntegrationOnAzureChinaCloud (0.00s) I1001 18:01:50.476] integration_test.go:32: Skipping integration test on Azure China Cloud because Prow only tests on Azure Public Cloud at the moment I1001 18:01:50.476] FAIL I1001 18:01:50.476] FAIL github.com/kubernetes-sigs/azuredisk-csi-driver/test/integration 96.210s I1001 18:01:50.515] Makefile:55: recipe for target 'integration-test' failed W1001 18:01:50.520] make: *** [integration-test] Error 1 W1001 18:01:50.521] Traceback (most recent call last): W1001 18:01:50.521] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W1001 18:01:50.522] main(ARGS.env, ARGS.cmd + ARGS.args) W1001 18:01:50.522] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1001 18:01:50.522] check(*cmd) W1001 18:01:50.523] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1001 18:01:50.523] subprocess.check_call(cmd) W1001 18:01:50.523] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1001 18:01:50.523] raise CalledProcessError(retcode, cmd) W1001 18:01:50.523] subprocess.CalledProcessError: Command '('make', 'integration-test')' returned non-zero exit status 2 E1001 18:01:50.524] Command failed I1001 18:01:50.524] process 456 exited with code 1 after 4.0m E1001 18:01:50.524] FAIL: pull-azuredisk-csi-driver-integration I1001 18:01:50.524] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1001 18:01:52.176] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1001 18:01:52.206] process 9251 exited with code 0 after 0.0m I1001 18:01:52.207] Call: gcloud config get-value account I1001 18:01:52.444] process 9263 exited with code 0 after 0.0m I1001 18:01:52.444] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1001 18:01:52.445] Upload result and artifacts... I1001 18:01:52.445] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179092917625556995 W1001 18:01:52.445] Missing local artifacts : /workspace/_artifacts W1001 18:01:52.445] metadata path /workspace/_artifacts/metadata.json does not exist W1001 18:01:52.445] metadata not found or invalid, init with empty metadata I1001 18:01:52.445] Call: git rev-parse HEAD I1001 18:01:52.448] process 9275 exited with code 0 after 0.0m I1001 18:01:52.448] Call: git rev-parse HEAD I1001 18:01:52.450] process 9276 exited with code 0 after 0.0m I1001 18:01:52.451] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 18:01:53.487] process 9277 exited with code 0 after 0.0m I1001 18:01:53.488] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/jobResultsCache.json#1569951970822478' I1001 18:01:54.641] process 9419 exited with code 0 after 0.0m I1001 18:01:54.642] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569951970822478 cp /tmp/gsutil_2wowr3 gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 18:01:56.018] process 9563 exited with code 0 after 0.0m I1001 18:01:56.018] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 18:01:56.967] process 9741 exited with code 0 after 0.0m I1001 18:01:56.968] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/jobResultsCache.json#1569951973112490' I1001 18:01:58.169] process 9883 exited with code 0 after 0.0m I1001 18:01:58.170] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569951973112490 cp /tmp/gsutil_Iu5mld gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/jobResultsCache.json I1001 18:01:59.568] process 10027 exited with code 0 after 0.0m I1001 18:01:59.569] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_m51JJ6 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/1179092917625556995/finished.json I1001 18:02:00.819] process 10205 exited with code 0 after 0.0m I1001 18:02:00.819] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_XJzoFr gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azuredisk-csi-driver/166/pull-azuredisk-csi-driver-integration/latest-build.txt I1001 18:02:02.118] process 10383 exited with code 0 after 0.0m I1001 18:02:02.119] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_47Nlbj gs://kubernetes-upstream/pr-logs/directory/pull-azuredisk-csi-driver-integration/latest-build.txt I1001 18:02:03.570] process 10561 exited with code 0 after 0.0m