W0923 20:26:33.964] ************************************************************************** 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 ************************************************************************** I0923 20:26:33.965] Args: --job=pull-azurefile-csi-driver-integration --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azurefile-csi-driver-integration --root=/go/src --service-account=/etc/service-account/service-account.json --repo=github.com/kubernetes-sigs/azurefile-csi-driver=master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:570298b9ba25107121c6f79cf327e6f9c6a99dbc --upload=gs://kubernetes-upstream/pr-logs --scenario=execute -- make integration-test I0923 20:26:33.965] Bootstrap pull-azurefile-csi-driver-integration... I0923 20:26:33.968] Builder: 59da2d03-de40-11e9-b3d3-b20db476995a I0923 20:26:33.968] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190903-df3b2f0-master I0923 20:26:33.969] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/1176231311723991043 I0923 20:26:33.969] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 20:26:34.561] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 20:26:34.594] process 32 exited with code 0 after 0.0m I0923 20:26:34.594] Call: gcloud config get-value account I0923 20:26:34.876] process 44 exited with code 0 after 0.0m I0923 20:26:34.876] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 20:26:34.877] Root: /go/src I0923 20:26:34.877] cd to /go/src I0923 20:26:34.877] Checkout: /go/src/github.com/kubernetes-sigs/azurefile-csi-driver master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:570298b9ba25107121c6f79cf327e6f9c6a99dbc to /go/src/github.com/kubernetes-sigs/azurefile-csi-driver I0923 20:26:34.877] Call: git init github.com/kubernetes-sigs/azurefile-csi-driver I0923 20:26:34.883] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/.git/ I0923 20:26:34.884] process 56 exited with code 0 after 0.0m I0923 20:26:34.884] Call: git config --local user.name 'K8S Bootstrap' I0923 20:26:34.887] process 57 exited with code 0 after 0.0m I0923 20:26:34.888] Call: git config --local user.email k8s_bootstrap@localhost I0923 20:26:34.891] process 58 exited with code 0 after 0.0m I0923 20:26:34.892] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azurefile-csi-driver master +refs/pull/120/head:refs/pr/120 I0923 20:26:41.003] process 59 exited with code 0 after 0.1m I0923 20:26:41.004] Call: git checkout -B test daea67167aecc01a8bfa2b24f33c4a08998cdc74 W0923 20:26:42.022] Switched to a new branch 'test' I0923 20:26:42.024] process 71 exited with code 0 after 0.0m I0923 20:26:42.024] Call: git show -s --format=format:%ct HEAD I0923 20:26:42.035] process 72 exited with code 0 after 0.0m I0923 20:26:42.035] Call: git merge --no-ff -m 'Merge +refs/pull/120/head:refs/pr/120' 570298b9ba25107121c6f79cf327e6f9c6a99dbc I0923 20:26:42.419] Auto-merging test/utils/credentials/credentials_test.go I0923 20:26:42.419] Auto-merging test/utils/credentials/credentials.go I0923 20:26:42.419] Removing test/e2e/run-test.sh I0923 20:26:42.419] Removing hack/template/azure.json I0923 20:26:42.419] Merge made by the 'recursive' strategy. I0923 20:26:42.422] Makefile | 60 +++++++++++------ I0923 20:26:42.423] hack/template/azure.json | 9 --- I0923 20:26:42.423] hack/verify-golint.sh | 7 +- I0923 20:26:42.423] test/e2e/README.md | 22 +++++-- I0923 20:26:42.423] test/e2e/dynamic_provisioning.go | 17 ----- I0923 20:26:42.423] test/e2e/pre_provisioning.go | 23 +------ I0923 20:26:42.423] test/e2e/run-test.sh | 31 --------- I0923 20:26:42.424] test/e2e/suite_test.go | 84 ++++++++++++++++++++++++ I0923 20:26:42.424] test/integration/integration_test.go | 7 +- I0923 20:26:42.424] test/integration/run-tests-all-clouds.sh | 4 +- I0923 20:26:42.424] test/sanity/run-tests-all-clouds.sh | 4 +- I0923 20:26:42.424] test/sanity/sanity_test.go | 10 +-- I0923 20:26:42.424] test/{ => utils}/azure/azure_helpers.go | 0 I0923 20:26:42.424] test/{ => utils}/credentials/credentials.go | 22 +++++-- I0923 20:26:42.425] test/{ => utils}/credentials/credentials_test.go | 6 +- I0923 20:26:42.425] 15 files changed, 175 insertions(+), 131 deletions(-) I0923 20:26:42.425] delete mode 100644 hack/template/azure.json I0923 20:26:42.425] delete mode 100755 test/e2e/run-test.sh I0923 20:26:42.425] rename test/{ => utils}/azure/azure_helpers.go (100%) I0923 20:26:42.425] rename test/{ => utils}/credentials/credentials.go (87%) I0923 20:26:42.425] rename test/{ => utils}/credentials/credentials_test.go (98%) I0923 20:26:42.425] process 73 exited with code 0 after 0.0m I0923 20:26:42.426] Configure environment... I0923 20:26:42.426] Call: git show -s --format=format:%ct HEAD I0923 20:26:42.430] process 75 exited with code 0 after 0.0m I0923 20:26:42.430] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 20:26:43.777] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 20:26:43.909] process 76 exited with code 0 after 0.0m I0923 20:26:43.910] Call: gcloud config get-value account I0923 20:26:44.186] process 88 exited with code 0 after 0.0m I0923 20:26:44.186] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 20:26:44.187] Start 1176231311723991043 at unknown... I0923 20:26:44.189] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_f24YWk gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/1176231311723991043/started.json I0923 20:26:45.833] process 100 exited with code 0 after 0.0m I0923 20:26:45.834] Call: gsutil -q -h Content-Type:text/plain -h 'x-goog-meta-link: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/1176231311723991043' cp /tmp/gsutil_67ZUc3 gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-integration/1176231311723991043.txt I0923 20:26:47.314] process 278 exited with code 0 after 0.0m I0923 20:26:47.315] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make integration-test W0923 20:26:47.336] Run: ('make', 'integration-test') W0923 20:26:47.399] go: finding github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0923 20:26:47.404] go: finding github.com/coreos/go-systemd v0.0.0-20190321100706-95778dfbb74e W0923 20:26:47.404] go: finding github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 20:26:47.404] go: finding github.com/json-iterator/go v1.1.5 W0923 20:26:47.405] go: finding github.com/google/uuid v1.1.1 W0923 20:26:47.406] go: finding github.com/PuerkitoBio/purell v1.1.1 W0923 20:26:47.411] go: finding github.com/googleapis/gnostic v0.2.0 W0923 20:26:47.413] go: finding github.com/Azure/go-autorest v11.5.1+incompatible W0923 20:26:47.416] go: finding github.com/pelletier/go-toml v1.4.0 I0923 20:26:47.518] CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.driverVersion=v0.4.0 -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.gitCommit=6632b90c3e8938103e4b1fb142e688ca89779af2 -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.buildDate=2019-09-23T20:26:47Z -s -w -extldflags '-static'" -o _output/azurefileplugin ./pkg/azurefileplugin W0923 20:26:47.618] go: finding google.golang.org/api v0.1.0 W0923 20:26:48.878] go: finding github.com/golang/protobuf v1.3.0 W0923 20:26:48.987] go: finding github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0923 20:26:49.065] go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 20:26:49.103] go: finding github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 20:26:49.206] go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 20:26:49.335] go: finding github.com/peterbourgon/diskv v2.0.1+incompatible W0923 20:26:49.391] go: finding github.com/container-storage-interface/spec v1.1.0 W0923 20:26:49.523] go: finding google.golang.org/grpc v1.19.0 W0923 20:26:50.503] go: finding github.com/coreos/go-semver v0.3.0 W0923 20:26:50.994] go: finding github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 20:26:51.064] go: finding github.com/google/btree v1.0.0 W0923 20:26:51.139] go: finding contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 20:26:51.166] go: finding github.com/prometheus/client_golang v0.9.2 W0923 20:26:51.355] go: finding github.com/stretchr/testify v1.3.0 W0923 20:26:51.791] go: finding github.com/onsi/gomega v1.4.3 W0923 20:26:52.126] go: finding go.opencensus.io v0.18.0 W0923 20:26:52.316] go: finding github.com/evanphx/json-patch v3.0.0+incompatible W0923 20:26:52.376] go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 20:26:52.920] go: finding github.com/prometheus/common v0.2.0 W0923 20:26:53.286] go: finding github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 20:26:53.286] go: finding golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 20:26:53.304] go: finding go.etcd.io/bbolt v1.3.2 W0923 20:26:53.592] go: finding google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 20:26:54.210] go: finding github.com/grpc-ecosystem/go-grpc-middleware v1.0.0 W0923 20:26:54.338] go: finding honnef.co/go/tools v0.0.0-20190102054323-c2f93a96b099 W0923 20:26:54.403] go: finding github.com/onsi/ginkgo v1.7.0 W0923 20:26:54.924] go: finding k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0923 20:26:55.033] go: finding github.com/openzipkin/zipkin-go v0.1.1 W0923 20:26:55.330] go: finding github.com/gorilla/websocket v1.4.0 W0923 20:26:55.452] go: finding github.com/apache/thrift v0.0.0-20180902110319-2566ecd5d999 W0923 20:26:56.682] go: finding github.com/marstr/guid v1.1.0 W0923 20:26:56.682] go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 W0923 20:26:56.778] go: finding go.uber.org/multierr v1.1.0 W0923 20:26:57.245] go: finding github.com/elazarl/goproxy v0.0.0-20190410145444-c548f45dcf1d W0923 20:26:57.509] go: finding k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0923 20:26:58.610] go: finding github.com/gogo/protobuf v1.2.1 W0923 20:26:58.841] go: finding golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 20:26:59.052] go: finding github.com/soheilhy/cmux v0.1.4 W0923 20:26:59.447] go: finding cloud.google.com/go v0.34.0 W0923 20:26:59.570] go: finding github.com/spf13/cobra v0.0.3 W0923 20:26:59.662] go: finding github.com/stretchr/objx v0.1.1 W0923 20:27:00.834] go: finding github.com/pmezard/go-difflib v1.0.0 W0923 20:27:00.968] go: finding github.com/pkg/errors v0.8.1 W0923 20:27:01.554] go: finding github.com/xiang90/probing v0.0.0-20190116061207-43a291ad63a2 W0923 20:27:02.580] go: finding google.golang.org/appengine v1.4.0 W0923 20:27:02.653] go: finding github.com/ugorji/go v1.1.4 W0923 20:27:02.786] go: finding github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 20:27:03.141] go: finding golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 20:27:03.334] go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf W0923 20:27:04.844] go: finding sigs.k8s.io/yaml v1.1.0 W0923 20:27:05.808] go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 W0923 20:27:05.987] go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b W0923 20:27:06.185] go: finding github.com/fsnotify/fsnotify v1.4.7 W0923 20:27:06.312] go: finding github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0923 20:27:07.079] go: finding k8s.io/kubernetes v1.14.0 W0923 20:27:10.887] go: finding github.com/julienschmidt/httprouter v1.2.0 W0923 20:27:11.271] go: finding github.com/elazarl/goproxy/ext v0.0.0-20190410145444-c548f45dcf1d W0923 20:27:11.356] go: finding github.com/grpc-ecosystem/grpc-gateway v1.5.0 W0923 20:27:11.363] go: finding golang.org/x/text v0.3.0 W0923 20:27:11.368] go: finding github.com/kubernetes-csi/external-snapshotter v1.0.1 W0923 20:27:11.370] go: finding github.com/grpc-ecosystem/go-grpc-prometheus v1.2.0 W0923 20:27:11.401] go: finding github.com/davecgh/go-spew v1.1.1 W0923 20:27:11.415] go: finding k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 20:27:11.422] go: finding golang.org/x/tools v0.0.0-20190226205152-f727befe758c W0923 20:27:12.146] go: finding gopkg.in/square/go-jose.v2 v2.3.0 W0923 20:27:12.532] go: finding k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 20:27:13.619] go: finding golang.org/x/lint v0.0.0-20190227174305-5b3e6a55c961 W0923 20:27:13.636] go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc W0923 20:27:13.888] go: finding github.com/fatih/camelcase v1.0.0 W0923 20:27:14.171] go: finding github.com/opencontainers/go-digest v1.0.0-rc1 W0923 20:27:14.703] go: finding k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 20:27:14.992] go: finding github.com/inconshreveable/mousetrap v1.0.0 W0923 20:27:15.426] go: finding github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0923 20:27:15.510] go: finding k8s.io/client-go v11.0.0+incompatible W0923 20:27:15.804] go: finding github.com/go-openapi/jsonreference v0.19.0 W0923 20:27:16.535] go: finding gopkg.in/gcfg.v1 v1.2.0 W0923 20:27:16.592] go: finding github.com/jonboulle/clockwork v0.1.0 W0923 20:27:17.000] go: finding github.com/BurntSushi/toml v0.3.1 W0923 20:27:20.396] go: finding github.com/go-openapi/spec v0.19.0 W0923 20:27:20.399] go: finding k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0923 20:27:20.400] go: finding k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 20:27:20.446] go: finding github.com/satori/go.uuid v1.2.0 W0923 20:27:20.448] go: finding github.com/go-openapi/jsonpointer v0.19.0 W0923 20:27:21.118] go: finding github.com/rogpeppe/go-charset v0.0.0-20180617210344-2471d30d28b4 W0923 20:27:21.202] go: finding golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 20:27:22.678] go: finding sigs.k8s.io/kustomize v2.0.3+incompatible W0923 20:27:22.826] go: finding github.com/kisielk/errcheck v1.1.0 W0923 20:27:23.210] go: finding gopkg.in/yaml.v2 v2.2.2 W0923 20:27:23.266] go: finding github.com/emicklei/go-restful v2.9.3+incompatible W0923 20:27:23.318] go: finding github.com/sirupsen/logrus v1.2.0 W0923 20:27:23.350] go: finding golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 20:27:23.394] go: finding github.com/go-stack/stack v1.8.0 W0923 20:27:24.958] go: finding github.com/imdario/mergo v0.3.7 W0923 20:27:25.050] go: finding gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0923 20:27:25.192] go: finding github.com/spf13/pflag v1.0.3 W0923 20:27:25.792] go: finding go.uber.org/zap v1.9.1 W0923 20:27:25.982] go: finding github.com/golang/mock v1.1.1 W0923 20:27:25.991] go: finding github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 20:27:27.425] go: finding k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 20:27:27.492] go: finding github.com/go-kit/kit v0.8.0 W0923 20:27:27.511] go: finding github.com/Azure/azure-storage-file-go v0.5.0 W0923 20:27:27.568] go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 20:27:27.800] go: finding golang.org/x/exp v0.0.0-20190121172915-509febef88a4 W0923 20:27:28.288] go: finding github.com/tmc/grpc-websocket-proxy v0.0.0-20190109142713-0ad062ec5ee5 W0923 20:27:28.445] go: finding github.com/hpcloud/tail v1.0.0 W0923 20:27:28.488] go: finding gopkg.in/warnings.v0 v0.1.1 W0923 20:27:28.807] go: finding k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0923 20:27:30.292] go: finding github.com/client9/misspell v0.3.4 W0923 20:27:30.783] go: finding k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 20:27:30.870] go: finding github.com/go-openapi/swag v0.19.0 W0923 20:27:30.958] go: finding github.com/ghodss/yaml v1.0.0 W0923 20:27:30.979] go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 W0923 20:27:31.093] go: finding github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0923 20:27:31.369] go: finding github.com/hashicorp/golang-lru v0.5.1 W0923 20:27:32.394] go: finding github.com/google/go-cmp v0.2.0 W0923 20:27:33.355] go: finding github.com/vmware/govmomi v0.20.1 W0923 20:27:33.476] go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 20:27:33.516] go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 W0923 20:27:33.527] go: finding github.com/kr/pty v1.1.5 W0923 20:27:33.617] go: finding github.com/go-logfmt/logfmt v0.3.0 W0923 20:27:33.688] go: finding k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0923 20:27:33.754] go: finding golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 20:27:34.271] go: finding gopkg.in/inf.v0 v0.9.1 W0923 20:27:34.365] go: finding github.com/coreos/pkg v0.0.0-20180928190104-399ea9e2e55f W0923 20:27:34.436] go: finding github.com/coreos/bbolt v1.3.2 W0923 20:27:35.008] go: finding github.com/dnaeon/go-vcr v1.0.1 W0923 20:27:35.375] go: finding github.com/aws/aws-sdk-go v1.16.26 W0923 20:27:35.643] go: finding github.com/Azure/azure-pipeline-go v0.2.1 W0923 20:27:35.667] go: finding github.com/coreos/etcd v3.3.12+incompatible W0923 20:27:36.445] go: finding go.uber.org/atomic v1.3.2 W0923 20:27:36.457] go: finding github.com/spf13/afero v1.2.2 W0923 20:27:36.555] go: finding github.com/kr/pretty v0.1.0 W0923 20:27:36.662] go: finding k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 20:27:36.762] go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 W0923 20:27:36.833] go: finding github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 20:27:36.850] go: finding k8s.io/klog v0.2.0 W0923 20:27:37.402] go: finding github.com/kisielk/gotool v1.0.0 W0923 20:27:39.098] go: finding github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 20:27:39.120] go: finding github.com/kr/text v0.1.0 W0923 20:27:39.156] go: finding github.com/GoogleCloudPlatform/k8s-cloud-provider v0.0.0-20181220005116-f8e995905100 W0923 20:27:39.160] go: finding github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 20:27:39.174] go: finding k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 20:27:39.238] go: finding gopkg.in/fsnotify.v1 v1.4.7 W0923 20:27:46.419] go: finding github.com/jmespath/go-jmespath v0.0.0-20180206201540-c2b33e8439af W0923 20:27:48.302] go: downloading github.com/prometheus/client_golang v0.9.2 W0923 20:27:48.315] go: downloading k8s.io/klog v0.2.0 W0923 20:27:48.333] go: downloading github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 20:27:48.333] go: downloading github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 20:27:48.333] go: downloading google.golang.org/grpc v1.19.0 W0923 20:27:48.333] go: downloading k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 20:27:48.334] go: downloading sigs.k8s.io/yaml v1.1.0 W0923 20:27:48.334] go: downloading github.com/Azure/azure-storage-file-go v0.5.0 W0923 20:27:48.334] go: downloading github.com/container-storage-interface/spec v1.1.0 W0923 20:27:48.583] go: extracting github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 20:27:48.598] go: downloading k8s.io/kubernetes v1.14.0 W0923 20:27:48.616] go: extracting sigs.k8s.io/yaml v1.1.0 W0923 20:27:48.646] go: extracting github.com/prometheus/client_golang v0.9.2 W0923 20:27:48.646] go: extracting github.com/container-storage-interface/spec v1.1.0 W0923 20:27:48.646] go: extracting k8s.io/klog v0.2.0 W0923 20:27:48.648] go: downloading gopkg.in/yaml.v2 v2.2.2 W0923 20:27:48.660] go: downloading github.com/golang/protobuf v1.3.0 W0923 20:27:48.697] go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 20:27:48.759] go: extracting github.com/Azure/azure-storage-file-go v0.5.0 W0923 20:27:48.846] go: extracting gopkg.in/yaml.v2 v2.2.2 W0923 20:27:48.856] go: downloading golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 20:27:48.934] go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 20:27:49.138] go: extracting github.com/golang/protobuf v1.3.0 W0923 20:27:49.266] go: extracting k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 20:27:49.335] go: downloading github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 20:27:49.337] go: downloading golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 20:27:49.450] go: extracting github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 20:27:49.456] go: downloading github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 20:27:49.535] go: downloading k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 20:27:49.698] go: extracting github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 20:27:49.730] go: downloading k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 20:27:49.778] go: extracting google.golang.org/grpc v1.19.0 W0923 20:27:49.934] go: downloading github.com/Azure/go-autorest v11.5.1+incompatible W0923 20:27:50.214] go: extracting github.com/Azure/go-autorest v11.5.1+incompatible W0923 20:27:50.298] go: downloading k8s.io/client-go v11.0.0+incompatible W0923 20:27:50.517] go: extracting k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 20:27:50.569] go: extracting k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 20:27:50.660] go: downloading github.com/prometheus/common v0.2.0 W0923 20:27:50.763] go: downloading k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 20:27:50.862] go: extracting golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 20:27:50.963] go: extracting golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 20:27:51.018] go: extracting k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 20:27:51.027] go: downloading github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 20:27:51.072] go: extracting github.com/prometheus/common v0.2.0 W0923 20:27:51.830] go: downloading go.opencensus.io v0.18.0 W0923 20:27:51.866] go: extracting github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 20:27:51.949] go: downloading github.com/gogo/protobuf v1.2.1 W0923 20:27:53.231] go: extracting go.opencensus.io v0.18.0 W0923 20:27:53.322] go: downloading k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 20:27:53.754] go: extracting k8s.io/client-go v11.0.0+incompatible W0923 20:27:56.936] go: extracting k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 20:27:57.209] go: downloading github.com/google/uuid v1.1.1 W0923 20:27:57.221] go: downloading github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 20:27:57.230] go: downloading k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 20:27:57.438] go: extracting github.com/google/uuid v1.1.1 W0923 20:27:57.442] go: downloading github.com/hashicorp/golang-lru v0.5.1 W0923 20:27:57.558] go: extracting github.com/hashicorp/golang-lru v0.5.1 W0923 20:27:57.594] go: downloading gopkg.in/inf.v0 v0.9.1 W0923 20:27:57.721] go: extracting gopkg.in/inf.v0 v0.9.1 W0923 20:27:57.746] go: extracting github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 20:27:57.776] go: downloading github.com/Azure/azure-pipeline-go v0.2.1 W0923 20:27:57.874] go: extracting github.com/gogo/protobuf v1.2.1 W0923 20:27:58.030] go: extracting github.com/Azure/azure-pipeline-go v0.2.1 W0923 20:27:58.055] go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 20:27:58.306] go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 20:27:58.319] go: downloading google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 20:27:58.353] go: downloading github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 20:27:58.358] go: downloading contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 20:27:58.554] go: extracting contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 20:27:58.558] go: downloading github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 20:27:58.686] go: extracting github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 20:27:58.687] go: downloading golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 20:27:58.850] go: extracting github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 20:27:58.902] go: downloading google.golang.org/api v0.1.0 W0923 20:27:59.542] go: extracting k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 20:28:00.330] go: downloading github.com/davecgh/go-spew v1.1.1 W0923 20:28:00.605] go: extracting github.com/davecgh/go-spew v1.1.1 W0923 20:28:00.622] go: downloading golang.org/x/text v0.3.0 W0923 20:28:00.870] go: extracting golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 20:28:08.178] go: extracting google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 20:28:11.082] go: extracting golang.org/x/text v0.3.0 W0923 20:28:11.480] go: downloading github.com/googleapis/gnostic v0.2.0 W0923 20:28:11.484] go: downloading github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 20:28:11.488] go: downloading github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 20:28:11.770] go: extracting github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 20:28:11.770] go: downloading golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 20:28:11.770] go: extracting github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 20:28:11.771] go: downloading golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 20:28:11.846] go: extracting golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 20:28:11.878] go: downloading github.com/json-iterator/go v1.1.5 W0923 20:28:11.974] go: extracting golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 20:28:12.006] go: downloading github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 20:28:12.094] go: extracting github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 20:28:12.106] go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 20:28:12.123] go: extracting github.com/json-iterator/go v1.1.5 W0923 20:28:12.178] go: extracting github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 20:28:12.366] go: extracting github.com/googleapis/gnostic v0.2.0 W0923 20:28:15.710] go: extracting google.golang.org/api v0.1.0 W0923 20:28:16.937] go: downloading golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 20:28:17.210] go: extracting golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 20:28:17.211] go: extracting github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 20:28:17.923] go: extracting k8s.io/kubernetes v1.14.0 W0923 20:28:22.402] go: downloading github.com/satori/go.uuid v1.2.0 W0923 20:28:22.402] go: downloading github.com/marstr/guid v1.1.0 W0923 20:28:22.587] go: extracting github.com/satori/go.uuid v1.2.0 W0923 20:28:22.610] go: extracting github.com/marstr/guid v1.1.0 W0923 20:28:23.978] go: downloading k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 20:28:23.979] go: downloading k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 20:28:25.894] go: extracting k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 20:28:26.193] go: extracting k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 20:28:26.231] go: downloading github.com/spf13/pflag v1.0.3 W0923 20:28:26.410] go: extracting github.com/spf13/pflag v1.0.3 I0923 20:30:24.753] go test -v -timeout=10m ./test/integration W0923 20:30:24.853] go: downloading github.com/stretchr/testify v1.3.0 W0923 20:30:24.865] go: downloading github.com/pelletier/go-toml v1.4.0 W0923 20:30:27.344] go: extracting github.com/stretchr/testify v1.3.0 W0923 20:30:27.345] go: extracting github.com/pelletier/go-toml v1.4.0 W0923 20:30:27.360] go: downloading github.com/pmezard/go-difflib v1.0.0 W0923 20:30:27.438] go: extracting github.com/pmezard/go-difflib v1.0.0 I0923 20:30:42.006] === RUN TestIntegrationOnAzurePublicCloud I0923 20:30:44.956] I0923 20:30:44.956] WARNING: apt does not have a stable CLI interface. Use with caution in scripts. I0923 20:30:44.956] I0923 20:30:45.161] Get:1 https://download.docker.com/linux/debian stretch InRelease [44.8 kB] I0923 20:30:45.247] Ign:2 http://cdn-fastly.deb.debian.org/debian stretch InRelease I0923 20:30:45.249] Get:3 http://security-cdn.debian.org/debian-security stretch/updates InRelease [94.3 kB] I0923 20:30:45.273] Get:4 http://cdn-fastly.deb.debian.org/debian stretch-updates InRelease [91.0 kB] I0923 20:30:45.346] Get:5 http://cdn-fastly.deb.debian.org/debian stretch Release [118 kB] I0923 20:30:45.359] Get:6 http://cdn-fastly.deb.debian.org/debian stretch Release.gpg [2365 B] I0923 20:30:45.504] Get:7 https://download.docker.com/linux/debian stretch/stable amd64 Packages [10.7 kB] I0923 20:30:45.612] Get:8 http://security-cdn.debian.org/debian-security stretch/updates/main amd64 Packages [506 kB] I0923 20:30:45.764] Get:9 http://cdn-fastly.deb.debian.org/debian stretch-updates/main amd64 Packages [27.4 kB] I0923 20:30:45.997] Get:10 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 Packages [7086 kB] I0923 20:30:47.929] Fetched 7981 kB in 2s (2784 kB/s) I0923 20:30:49.014] Reading package lists... I0923 20:30:49.219] Building dependency tree... I0923 20:30:49.220] Reading state information... I0923 20:30:49.229] 24 packages can be upgraded. Run 'apt list --upgradable' to see them. I0923 20:30:49.242] I0923 20:30:49.242] WARNING: apt does not have a stable CLI interface. Use with caution in scripts. I0923 20:30:49.243] I0923 20:30:50.365] Reading package lists... I0923 20:30:50.610] Building dependency tree... I0923 20:30:50.610] Reading state information... I0923 20:30:50.719] procps is already the newest version (2:3.3.12-3+deb9u1). I0923 20:30:50.720] The following additional packages will be installed: I0923 20:30:50.720] libavahi-client3 libavahi-common-data libavahi-common3 libcups2 libldb1 I0923 20:30:50.720] libtalloc2 libtdb1 libtevent0 libwbclient0 python-crypto python-ldb I0923 20:30:50.721] python-samba python-talloc python-tdb samba-common samba-common-bin I0923 20:30:50.721] samba-libs I0923 20:30:50.721] Suggested packages: I0923 20:30:50.722] keyutils smbclient winbind cups-common python-crypto-dbg python-crypto-doc I0923 20:30:50.722] python-gpgme heimdal-clients I0923 20:30:51.049] The following NEW packages will be installed: I0923 20:30:51.049] cifs-utils libavahi-client3 libavahi-common-data libavahi-common3 libcups2 I0923 20:30:51.050] libldb1 libtalloc2 libtdb1 libtevent0 libwbclient0 python-crypto python-ldb I0923 20:30:51.050] python-samba python-talloc python-tdb samba-common samba-common-bin I0923 20:30:51.050] samba-libs I0923 20:30:51.266] 0 upgraded, 18 newly installed, 0 to remove and 24 not upgraded. I0923 20:30:51.267] Need to get 8646 kB of archives. I0923 20:30:51.270] After this operation, 38.4 MB of additional disk space will be used. I0923 20:30:51.270] Get:1 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libwbclient0 amd64 2:4.5.16+dfsg-1+deb9u2 [124 kB] I0923 20:30:51.317] Get:2 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-common all 2:4.5.16+dfsg-1+deb9u2 [174 kB] I0923 20:30:51.346] Get:3 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtalloc2 amd64 2.1.8-1 [36.3 kB] I0923 20:30:51.369] Get:4 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 cifs-utils amd64 2:6.7-1 [75.8 kB] I0923 20:30:51.410] Get:5 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-common-data amd64 0.6.32-2 [118 kB] I0923 20:30:51.454] Get:6 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-common3 amd64 0.6.32-2 [52.0 kB] I0923 20:30:51.458] Get:7 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libavahi-client3 amd64 0.6.32-2 [55.3 kB] I0923 20:30:51.458] Get:8 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libcups2 amd64 2.2.1-8+deb9u4 [307 kB] I0923 20:30:51.510] Get:9 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtdb1 amd64 1.3.11-2 [43.2 kB] I0923 20:30:51.510] Get:10 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libtevent0 amd64 0.9.31-1 [29.3 kB] I0923 20:30:51.510] Get:11 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 libldb1 amd64 2:1.1.27-1+deb9u1 [113 kB] I0923 20:30:51.552] Get:12 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-crypto amd64 2.6.1-7 [259 kB] I0923 20:30:51.566] Get:13 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-ldb amd64 2:1.1.27-1+deb9u1 [34.8 kB] I0923 20:30:51.609] Get:14 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-tdb amd64 1.3.11-2 [16.2 kB] I0923 20:30:51.609] Get:15 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-talloc amd64 2.1.8-1 [11.9 kB] I0923 20:30:51.609] Get:16 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 samba-libs amd64 2:4.5.16+dfsg-1+deb9u2 [5231 kB] I0923 20:30:51.756] Get:17 http://cdn-fastly.deb.debian.org/debian stretch/main amd64 python-samba amd64 2:4.5.16+dfsg-1+deb9u2 [1360 kB] I0923 20:30:51.874] 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] I0923 20:30:56.414] debconf: delaying package configuration, since apt-utils is not installed I0923 20:30:56.808] Fetched 8646 kB in 0s (10.5 MB/s) I0923 20:31:00.706] Selecting previously unselected package libwbclient0:amd64. I0923 20:31:00.980] (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.) I0923 20:31:00.983] Preparing to unpack .../00-libwbclient0_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0923 20:31:01.046] Unpacking libwbclient0:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:01.322] Selecting previously unselected package samba-common. I0923 20:31:01.322] Preparing to unpack .../01-samba-common_2%3a4.5.16+dfsg-1+deb9u2_all.deb ... I0923 20:31:01.356] Unpacking samba-common (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:01.522] Selecting previously unselected package libtalloc2:amd64. I0923 20:31:01.530] Preparing to unpack .../02-libtalloc2_2.1.8-1_amd64.deb ... I0923 20:31:01.558] Unpacking libtalloc2:amd64 (2.1.8-1) ... I0923 20:31:01.751] Selecting previously unselected package cifs-utils. I0923 20:31:01.760] Preparing to unpack .../03-cifs-utils_2%3a6.7-1_amd64.deb ... I0923 20:31:01.774] Unpacking cifs-utils (2:6.7-1) ... I0923 20:31:01.970] Selecting previously unselected package libavahi-common-data:amd64. I0923 20:31:01.970] Preparing to unpack .../04-libavahi-common-data_0.6.32-2_amd64.deb ... I0923 20:31:01.987] Unpacking libavahi-common-data:amd64 (0.6.32-2) ... I0923 20:31:02.222] Selecting previously unselected package libavahi-common3:amd64. I0923 20:31:02.230] Preparing to unpack .../05-libavahi-common3_0.6.32-2_amd64.deb ... I0923 20:31:02.245] Unpacking libavahi-common3:amd64 (0.6.32-2) ... I0923 20:31:02.424] Selecting previously unselected package libavahi-client3:amd64. I0923 20:31:02.427] Preparing to unpack .../06-libavahi-client3_0.6.32-2_amd64.deb ... I0923 20:31:02.450] Unpacking libavahi-client3:amd64 (0.6.32-2) ... I0923 20:31:02.629] Selecting previously unselected package libcups2:amd64. I0923 20:31:02.639] Preparing to unpack .../07-libcups2_2.2.1-8+deb9u4_amd64.deb ... I0923 20:31:02.662] Unpacking libcups2:amd64 (2.2.1-8+deb9u4) ... I0923 20:31:02.922] Selecting previously unselected package libtdb1:amd64. I0923 20:31:02.922] Preparing to unpack .../08-libtdb1_1.3.11-2_amd64.deb ... I0923 20:31:02.946] Unpacking libtdb1:amd64 (1.3.11-2) ... I0923 20:31:03.650] Selecting previously unselected package libtevent0:amd64. I0923 20:31:03.660] Preparing to unpack .../09-libtevent0_0.9.31-1_amd64.deb ... I0923 20:31:03.680] Unpacking libtevent0:amd64 (0.9.31-1) ... I0923 20:31:03.870] Selecting previously unselected package libldb1:amd64. I0923 20:31:03.870] Preparing to unpack .../10-libldb1_2%3a1.1.27-1+deb9u1_amd64.deb ... I0923 20:31:03.888] Unpacking libldb1:amd64 (2:1.1.27-1+deb9u1) ... I0923 20:31:04.094] Selecting previously unselected package python-crypto. I0923 20:31:04.094] Preparing to unpack .../11-python-crypto_2.6.1-7_amd64.deb ... I0923 20:31:04.121] Unpacking python-crypto (2.6.1-7) ... I0923 20:31:04.450] Selecting previously unselected package python-ldb. I0923 20:31:04.454] Preparing to unpack .../12-python-ldb_2%3a1.1.27-1+deb9u1_amd64.deb ... I0923 20:31:04.468] Unpacking python-ldb (2:1.1.27-1+deb9u1) ... I0923 20:31:04.653] Selecting previously unselected package python-tdb. I0923 20:31:04.656] Preparing to unpack .../13-python-tdb_1.3.11-2_amd64.deb ... I0923 20:31:04.674] Unpacking python-tdb (1.3.11-2) ... I0923 20:31:04.850] Selecting previously unselected package python-talloc. I0923 20:31:04.854] Preparing to unpack .../14-python-talloc_2.1.8-1_amd64.deb ... I0923 20:31:04.871] Unpacking python-talloc (2.1.8-1) ... I0923 20:31:05.060] Selecting previously unselected package samba-libs:amd64. I0923 20:31:05.070] Preparing to unpack .../15-samba-libs_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0923 20:31:05.115] Unpacking samba-libs:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:06.541] Selecting previously unselected package python-samba. I0923 20:31:06.544] Preparing to unpack .../16-python-samba_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0923 20:31:06.562] Unpacking python-samba (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:07.151] Selecting previously unselected package samba-common-bin. I0923 20:31:07.154] Preparing to unpack .../17-samba-common-bin_2%3a4.5.16+dfsg-1+deb9u2_amd64.deb ... I0923 20:31:07.170] Unpacking samba-common-bin (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:07.602] Setting up libtdb1:amd64 (1.3.11-2) ... I0923 20:31:07.666] Setting up libwbclient0:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:07.734] Setting up python-crypto (2.6.1-7) ... I0923 20:31:08.502] Setting up python-tdb (1.3.11-2) ... I0923 20:31:09.094] Setting up samba-common (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:09.394] debconf: unable to initialize frontend: Dialog I0923 20:31:09.394] 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.) I0923 20:31:09.395] debconf: falling back to frontend: Readline I0923 20:31:09.642] I0923 20:31:09.642] Creating config file /etc/samba/smb.conf with new version I0923 20:31:10.594] Processing triggers for libc-bin (2.24-11+deb9u4) ... I0923 20:31:11.054] Setting up libtalloc2:amd64 (2.1.8-1) ... I0923 20:31:11.113] Setting up libavahi-common-data:amd64 (0.6.32-2) ... I0923 20:31:11.164] Setting up python-talloc (2.1.8-1) ... I0923 20:31:11.222] Setting up cifs-utils (2:6.7-1) ... I0923 20:31:11.410] 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 I0923 20:31:11.454] Setting up libtevent0:amd64 (0.9.31-1) ... I0923 20:31:11.508] Setting up libavahi-common3:amd64 (0.6.32-2) ... I0923 20:31:11.570] Setting up libldb1:amd64 (2:1.1.27-1+deb9u1) ... I0923 20:31:11.614] Setting up libavahi-client3:amd64 (0.6.32-2) ... I0923 20:31:11.678] Setting up libcups2:amd64 (2.2.1-8+deb9u4) ... I0923 20:31:11.727] Setting up python-ldb (2:1.1.27-1+deb9u1) ... I0923 20:31:12.321] Setting up samba-libs:amd64 (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:13.080] Setting up python-samba (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:14.114] Setting up samba-common-bin (2:4.5.16+dfsg-1+deb9u2) ... I0923 20:31:14.190] Processing triggers for libc-bin (2.24-11+deb9u4) ... I0923 20:31:45.959] Begin to run integration test on AzurePublicCloud... I0923 20:31:45.974] I0923 20:31:45.974717 12091 main.go:82] set up prometheus server on [::]:10252 I0923 20:31:45.975] I0923 20:31:45.975064 12091 azurefile.go:84] I0923 20:31:45.975] DRIVER INFORMATION: I0923 20:31:45.975] ------------------- I0923 20:31:45.975] Build Date: "2019-09-23T20:26:47Z" I0923 20:31:45.975] Compiler: gc I0923 20:31:45.976] Driver Name: file.csi.azure.com I0923 20:31:45.976] Driver Version: v0.4.0 I0923 20:31:45.976] Git Commit: 6632b90c3e8938103e4b1fb142e688ca89779af2 I0923 20:31:45.976] Go Version: go1.12.9 I0923 20:31:45.976] Platform: linux/amd64 I0923 20:31:45.976] I0923 20:31:45.976] Streaming logs below: I0923 20:31:45.977] I0923 20:31:45.975096 12091 azure.go:31] AZURE_CREDENTIAL_FILE env var set as /tmp/azure.json I0923 20:31:45.977] I0923 20:31:45.975552 12091 azure_auth.go:86] azure: using client_id+client_secret to retrieve access token I0923 20:31:45.977] I0923 20:31:45.975932 12091 mount_linux.go:156] Detected OS without systemd I0923 20:31:45.977] I0923 20:31:45.975966 12091 driver.go:80] Enabling controller service capability: CREATE_DELETE_VOLUME I0923 20:31:45.977] I0923 20:31:45.975974 12091 driver.go:80] Enabling controller service capability: CREATE_DELETE_SNAPSHOT I0923 20:31:45.977] I0923 20:31:45.975981 12091 driver.go:99] Enabling volume access mode: SINGLE_NODE_WRITER I0923 20:31:45.977] I0923 20:31:45.976000 12091 driver.go:99] Enabling volume access mode: SINGLE_NODE_READER_ONLY I0923 20:31:45.978] I0923 20:31:45.976006 12091 driver.go:99] Enabling volume access mode: MULTI_NODE_READER_ONLY I0923 20:31:45.978] I0923 20:31:45.976012 12091 driver.go:99] Enabling volume access mode: MULTI_NODE_SINGLE_WRITER I0923 20:31:45.978] I0923 20:31:45.976017 12091 driver.go:99] Enabling volume access mode: MULTI_NODE_MULTI_WRITER I0923 20:31:45.978] I0923 20:31:45.976023 12091 driver.go:90] Enabling node service capability: UNKNOWN I0923 20:31:45.978] I0923 20:31:45.976139 12091 server.go:104] Listening for connections on address: &net.TCPAddr{IP:net.IP{0x7f, 0x0, 0x0, 0x1}, Port:10000, Zone:""} I0923 20:31:50.962] Create volume test: I0923 20:31:50.968] I0923 20:31:50.968147 12091 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume I0923 20:31:50.968] I0923 20:31:50.968172 12091 utils.go:112] GRPC request: name:"citest-1569270705" capacity_range: volume_capabilities: access_mode: > parameters: I0923 20:31:50.968] I0923 20:31:50.968191 12091 controllerserver.go:95] begin to create file share(citest-1569270705) on account() type(Standard_LRS) rg() location() size(2) I0923 20:31:51.445] I0923 20:31:51.442464 12091 azure_storageaccount.go:121] azure - no matching account found, begin to create a new account f2c9fd7cdde4111e990c0fe in resource group azurefile-csi-driver-test-033cd727-de41-11e9-8d52-fef56ee372d8, location: eastus2, accountType: Standard_LRS, accountKind: StorageV2 I0923 20:32:18.410] I0923 20:32:18.410296 12091 azure_storage.go:49] created share citest-1569270705 in account f2c9fd7cdde4111e990c0fe I0923 20:32:18.410] I0923 20:32:18.410342 12091 controllerserver.go:108] create file share citest-1569270705 on storage account f2c9fd7cdde4111e990c0fe successfully I0923 20:32:22.650] I0923 20:32:22.650539 12091 utils.go:118] GRPC response: volume: > I0923 20:32:37.656] Got volume id: #f2c9fd7cdde4111e990c0fe#citest-1569270705 I0923 20:32:37.662] I0923 20:32:37.662241 12091 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities I0923 20:32:37.662] I0923 20:32:37.662301 12091 utils.go:112] GRPC request: volume_id:"#f2c9fd7cdde4111e990c0fe#citest-1569270705" volume_capabilities: access_mode: > I0923 20:32:37.866] I0923 20:32:37.866086 12091 utils.go:118] GRPC response: I0923 20:32:37.866] "#f2c9fd7cdde4111e990c0fe#citest-1569270705" I0923 20:32:37.867] Mount volume test: I0923 20:32:37.873] I0923 20:32:37.873265 12091 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume I0923 20:32:37.873] I0923 20:32:37.873302 12091 utils.go:112] GRPC request: volume_id:"#f2c9fd7cdde4111e990c0fe#citest-1569270705" target_path:"/tmp/testmount1" volume_capability: access_mode: > I0923 20:32:37.873] I0923 20:32:37.873373 12091 nodeserver.go:76] target /tmp/testmount1 I0923 20:32:37.873] fstype I0923 20:32:37.874] I0923 20:32:37.874] readonly false I0923 20:32:37.874] volumeId #f2c9fd7cdde4111e990c0fe#citest-1569270705 I0923 20:32:37.874] context map[] I0923 20:32:37.874] mountflags [] I0923 20:32:37.982] I0923 20:32:37.981971 12091 mount_linux.go:138] Mounting cmd (mount) with arguments ([-t cifs -o username=f2c9fd7cdde4111e990c0fe,password=OLqR57spG5G/oYU5mmww6Pb67tpg1OMkTO/crev/nzhsoGWaImKXyCTaNs9nCZDHoRrptkQww/Brfs76r/qv+Q==,file_mode=0777,dir_mode=0777,vers=3.0 //f2c9fd7cdde4111e990c0fe.file.core.windows.net/citest-1569270705 /tmp/testmount1]) I0923 20:32:39.587] I0923 20:32:39.587107 12091 utils.go:118] GRPC response: I0923 20:32:39.587] #f2c9fd7cdde4111e990c0fe#citest-1569270705 I0923 20:32:41.589] Unmount volume test: I0923 20:32:41.596] I0923 20:32:41.596334 12091 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume I0923 20:32:41.596] I0923 20:32:41.596358 12091 utils.go:112] GRPC request: volume_id:"#f2c9fd7cdde4111e990c0fe#citest-1569270705" target_path:"/tmp/testmount1" I0923 20:32:41.597] I0923 20:32:41.596365 12091 mount_linux.go:203] Unmounting /tmp/testmount1 I0923 20:32:42.231] I0923 20:32:42.230927 12091 nodeserver.go:180] azurefile: volume /tmp/testmount1/#f2c9fd7cdde4111e990c0fe#citest-1569270705 has been unmounted. I0923 20:32:42.231] I0923 20:32:42.230966 12091 utils.go:118] GRPC response: I0923 20:32:42.231] #f2c9fd7cdde4111e990c0fe#citest-1569270705 I0923 20:32:44.233] Delete volume test: I0923 20:32:44.238] I0923 20:32:44.238521 12091 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume I0923 20:32:44.238] I0923 20:32:44.238552 12091 utils.go:112] GRPC request: volume_id:"#f2c9fd7cdde4111e990c0fe#citest-1569270705" I0923 20:32:44.648] I0923 20:32:44.648525 12091 controllerserver.go:149] azure file(citest-1569270705) under rg() account(f2c9fd7cdde4111e990c0fe) volumeID(#f2c9fd7cdde4111e990c0fe#citest-1569270705) is deleted successfully I0923 20:32:44.649] I0923 20:32:44.648567 12091 utils.go:118] GRPC response: I0923 20:32:44.649] #f2c9fd7cdde4111e990c0fe#citest-1569270705 I0923 20:32:59.657] I0923 20:32:59.656898 12091 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginInfo I0923 20:32:59.657] I0923 20:32:59.656928 12091 utils.go:112] GRPC request: I0923 20:32:59.657] I0923 20:32:59.656936 12091 identityserver.go:32] Using default GetPluginInfo I0923 20:32:59.657] I0923 20:32:59.656940 12091 utils.go:118] GRPC response: name:"file.csi.azure.com" vendor_version:"v0.4.0" I0923 20:32:59.657] "file.csi.azure.com" "v0.4.0" I0923 20:32:59.663] I0923 20:32:59.663245 12091 utils.go:111] GRPC call: /csi.v1.Node/NodeGetInfo I0923 20:32:59.663] I0923 20:32:59.663277 12091 utils.go:112] GRPC request: I0923 20:32:59.663] I0923 20:32:59.663286 12091 nodeserver.go:228] Using default NodeGetInfo I0923 20:32:59.664] I0923 20:32:59.663292 12091 utils.go:118] GRPC response: node_id:"CSINode" I0923 20:32:59.664] CSINode 0 (*csi.Topology)(nil) I0923 20:32:59.664] Integration test on AzurePublicCloud is complete. I0923 20:32:59.664] pkill -f azurefileplugin I0923 20:32:59.669] --- PASS: TestIntegrationOnAzurePublicCloud (137.66s) I0923 20:32:59.669] integration_test.go:67: Creating resource group azurefile-csi-driver-test-033cd727-de41-11e9-8d52-fef56ee372d8 in AzurePublicCloud I0923 20:32:59.669] === RUN TestIntegrationOnAzureChinaCloud I0923 20:33:00.995] I0923 20:33:00.995] WARNING: apt does not have a stable CLI interface. Use with caution in scripts. I0923 20:33:00.995] I0923 20:33:01.150] Hit:1 https://download.docker.com/linux/debian stretch InRelease I0923 20:33:01.210] Hit:2 http://security-cdn.debian.org/debian-security stretch/updates InRelease I0923 20:33:01.352] Ign:3 http://cdn-fastly.deb.debian.org/debian stretch InRelease I0923 20:33:01.479] Hit:4 http://cdn-fastly.deb.debian.org/debian stretch-updates InRelease I0923 20:33:01.625] Hit:5 http://cdn-fastly.deb.debian.org/debian stretch Release I0923 20:33:02.347] Reading package lists... I0923 20:33:02.459] Building dependency tree... I0923 20:33:02.459] Reading state information... I0923 20:33:02.466] 24 packages can be upgraded. Run 'apt list --upgradable' to see them. I0923 20:33:02.471] I0923 20:33:02.471] WARNING: apt does not have a stable CLI interface. Use with caution in scripts. I0923 20:33:02.471] I0923 20:33:03.096] Reading package lists... I0923 20:33:03.213] Building dependency tree... I0923 20:33:03.213] Reading state information... I0923 20:33:03.292] cifs-utils is already the newest version (2:6.7-1). I0923 20:33:03.292] procps is already the newest version (2:3.3.12-3+deb9u1). I0923 20:33:03.292] 0 upgraded, 0 newly installed, 0 to remove and 24 not upgraded. I0923 20:33:03.582] Begin to run integration test on AzureChinaCloud... I0923 20:33:03.597] I0923 20:33:03.597158 12512 main.go:82] set up prometheus server on [::]:10252 I0923 20:33:03.597] I0923 20:33:03.597439 12512 azurefile.go:84] I0923 20:33:03.597] DRIVER INFORMATION: I0923 20:33:03.597] ------------------- I0923 20:33:03.597] Build Date: "2019-09-23T20:26:47Z" I0923 20:33:03.598] Compiler: gc I0923 20:33:03.598] Driver Name: file.csi.azure.com I0923 20:33:03.598] Driver Version: v0.4.0 I0923 20:33:03.598] Git Commit: 6632b90c3e8938103e4b1fb142e688ca89779af2 I0923 20:33:03.598] Go Version: go1.12.9 I0923 20:33:03.599] Platform: linux/amd64 I0923 20:33:03.599] I0923 20:33:03.599] Streaming logs below: I0923 20:33:03.599] I0923 20:33:03.597467 12512 azure.go:31] AZURE_CREDENTIAL_FILE env var set as /tmp/azure.json I0923 20:33:03.599] I0923 20:33:03.597884 12512 azure_auth.go:86] azure: using client_id+client_secret to retrieve access token I0923 20:33:03.599] I0923 20:33:03.598109 12512 mount_linux.go:156] Detected OS without systemd I0923 20:33:03.600] I0923 20:33:03.598138 12512 driver.go:80] Enabling controller service capability: CREATE_DELETE_VOLUME I0923 20:33:03.600] I0923 20:33:03.598143 12512 driver.go:80] Enabling controller service capability: CREATE_DELETE_SNAPSHOT I0923 20:33:03.600] I0923 20:33:03.598147 12512 driver.go:99] Enabling volume access mode: SINGLE_NODE_WRITER I0923 20:33:03.600] I0923 20:33:03.598151 12512 driver.go:99] Enabling volume access mode: SINGLE_NODE_READER_ONLY I0923 20:33:03.601] I0923 20:33:03.598154 12512 driver.go:99] Enabling volume access mode: MULTI_NODE_READER_ONLY I0923 20:33:03.601] I0923 20:33:03.598157 12512 driver.go:99] Enabling volume access mode: MULTI_NODE_SINGLE_WRITER I0923 20:33:03.601] I0923 20:33:03.598160 12512 driver.go:99] Enabling volume access mode: MULTI_NODE_MULTI_WRITER I0923 20:33:03.601] I0923 20:33:03.598164 12512 driver.go:90] Enabling node service capability: UNKNOWN I0923 20:33:03.601] I0923 20:33:03.598270 12512 server.go:104] Listening for connections on address: &net.TCPAddr{IP:net.IP{0x7f, 0x0, 0x0, 0x1}, Port:10000, Zone:""} I0923 20:33:28.584] Create volume test: I0923 20:33:28.589] I0923 20:33:28.589483 12512 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume I0923 20:33:28.589] I0923 20:33:28.589516 12512 utils.go:112] GRPC request: name:"citest-1569270783" capacity_range: volume_capabilities: access_mode: > parameters: I0923 20:33:28.590] I0923 20:33:28.589531 12512 controllerserver.go:95] begin to create file share(citest-1569270783) on account() type(Standard_LRS) rg() location() size(2) I0923 20:33:29.523] E0923 20:33:29.522675 12512 utils.go:116] GRPC error: failed to create file share(citest-1569270783) on account() type(Standard_LRS) rg() location() size(2), error: could not get storage key for storage account : could not list storage accounts for account type Standard_LRS: azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.chinacloudapi.cn/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azurefile-csi-driver-test-554a6c4d-de41-11e9-8d52-fef56ee372d8/providers/Microsoft.Storage/storageAccounts?api-version=2018-07-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '72f988bf-86f1-41af-91ab-2d7cd011db47' not found. This may happen if there are no active subscriptions for the tenant. Check with your subscription administrator.\r\nTrace ID: 9cda0ebd-eb2c-40fd-83c6-57cb396c4500\r\nCorrelation ID: d6d16241-4aba-4176-adce-efebc5be56e0\r\nTimestamp: 2019-09-23 20:33:29Z","error_codes":[90002],"timestamp":"2019-09-23 20:33:29Z","trace_id":"9cda0ebd-eb2c-40fd-83c6-57cb396c4500","correlation_id":"d6d16241-4aba-4176-adce-efebc5be56e0","error_uri":"https://login.chinacloudapi.cn/error?code=90002"} I0923 20:33:29.524] failed to create file share(citest-1569270783) on account() type(Standard_LRS) rg() location() size(2), error: could not get storage key for storage account : could not list storage accounts for account type Standard_LRS: azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.chinacloudapi.cn/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azurefile-csi-driver-test-554a6c4d-de41-11e9-8d52-fef56ee372d8/providers/Microsoft.Storage/storageAccounts?api-version=2018-07-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '72f988bf-86f1-41af-91ab-2d7cd011db47' not found. This may happen if there are no active subscriptions for the tenant. Check with your subscription administrator.\r\nTrace ID: 9cda0ebd-eb2c-40fd-83c6-57cb396c4500\r\nCorrelation ID: d6d16241-4aba-4176-adce-efebc5be56e0\r\nTimestamp: 2019-09-23 20:33:29Z","error_codes":[90002],"timestamp":"2019-09-23 20:33:29Z","trace_id":"9cda0ebd-eb2c-40fd-83c6-57cb396c4500","correlation_id":"d6d16241-4aba-4176-adce-efebc5be56e0","error_uri":"https://login.chinacloudapi.cn/error?code=90002"} I0923 20:33:29.524] I0923 20:33:29.524] Please use -h,--help for more information I0923 20:33:44.528] Got volume id: I0923 20:33:44.533] I0923 20:33:44.533824 12512 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities I0923 20:33:44.534] I0923 20:33:44.533859 12512 utils.go:112] GRPC request: volume_capabilities: access_mode: > I0923 20:33:44.534] E0923 20:33:44.533867 12512 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request I0923 20:33:44.534] Volume ID missing in request I0923 20:33:44.534] I0923 20:33:44.535] Please use -h,--help for more information I0923 20:33:44.535] pkill -f azurefileplugin I0923 20:33:44.538] --- FAIL: TestIntegrationOnAzureChinaCloud (44.87s) I0923 20:33:44.539] integration_test.go:67: Creating resource group azurefile-csi-driver-test-554a6c4d-de41-11e9-8d52-fef56ee372d8 in AzureChinaCloud I0923 20:33:44.539] integration_test.go:69: I0923 20:33:44.539] Error Trace: integration_test.go:69 I0923 20:33:44.539] integration_test.go:57 I0923 20:33:44.539] Error: Received unexpected error: I0923 20:33:44.540] azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.chinacloudapi.cn/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourcegroups/azurefile-csi-driver-test-554a6c4d-de41-11e9-8d52-fef56ee372d8?api-version=2018-05-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '72f988bf-86f1-41af-91ab-2d7cd011db47' not found. This may happen if there are no active subscriptions for the tenant. Check with your subscription administrator.\r\nTrace ID: 0f3716ac-726c-4d7f-b0e4-6f5acce65100\r\nCorrelation ID: 26842357-d6e6-493f-af7c-9d3e4ef98e37\r\nTimestamp: 2019-09-23 20:33:00Z","error_codes":[90002],"timestamp":"2019-09-23 20:33:00Z","trace_id":"0f3716ac-726c-4d7f-b0e4-6f5acce65100","correlation_id":"26842357-d6e6-493f-af7c-9d3e4ef98e37","error_uri":"https://login.chinacloudapi.cn/error?code=90002"} I0923 20:33:44.540] Test: TestIntegrationOnAzureChinaCloud I0923 20:33:44.540] integration_test.go:89: Integration test failed exit status 3 I0923 20:33:44.541] FAIL I0923 20:33:44.541] FAIL github.com/kubernetes-sigs/azurefile-csi-driver/test/integration 182.544s I0923 20:33:44.569] Makefile:54: recipe for target 'integration-test' failed W0923 20:33:44.574] make: *** [integration-test] Error 1 W0923 20:33:44.574] Traceback (most recent call last): W0923 20:33:44.574] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W0923 20:33:44.574] main(ARGS.env, ARGS.cmd + ARGS.args) W0923 20:33:44.574] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W0923 20:33:44.575] check(*cmd) W0923 20:33:44.575] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W0923 20:33:44.575] subprocess.check_call(cmd) W0923 20:33:44.575] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W0923 20:33:44.575] raise CalledProcessError(retcode, cmd) W0923 20:33:44.575] subprocess.CalledProcessError: Command '('make', 'integration-test')' returned non-zero exit status 2 E0923 20:33:44.575] Command failed I0923 20:33:44.576] process 456 exited with code 1 after 7.0m E0923 20:33:44.576] FAIL: pull-azurefile-csi-driver-integration I0923 20:33:44.576] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 20:33:45.200] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 20:33:45.232] process 12548 exited with code 0 after 0.0m I0923 20:33:45.232] Call: gcloud config get-value account I0923 20:33:45.478] process 12560 exited with code 0 after 0.0m I0923 20:33:45.478] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 20:33:45.478] Upload result and artifacts... I0923 20:33:45.478] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/1176231311723991043 W0923 20:33:45.479] Missing local artifacts : /workspace/_artifacts W0923 20:33:45.479] metadata path /workspace/_artifacts/metadata.json does not exist W0923 20:33:45.479] metadata not found or invalid, init with empty metadata I0923 20:33:45.479] Call: git rev-parse HEAD I0923 20:33:45.481] process 12572 exited with code 0 after 0.0m I0923 20:33:45.482] Call: git rev-parse HEAD I0923 20:33:45.484] process 12573 exited with code 0 after 0.0m I0923 20:33:45.484] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-integration/jobResultsCache.json I0923 20:33:46.515] process 12574 exited with code 0 after 0.0m I0923 20:33:46.516] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-integration/jobResultsCache.json#1569270045828202' I0923 20:33:47.743] process 12716 exited with code 0 after 0.0m I0923 20:33:47.744] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569270045828202 cp /tmp/gsutil_gkF_OE gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-integration/jobResultsCache.json I0923 20:33:49.124] process 12860 exited with code 0 after 0.0m I0923 20:33:49.124] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/jobResultsCache.json I0923 20:33:50.105] process 13038 exited with code 0 after 0.0m I0923 20:33:50.105] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/jobResultsCache.json#1569270048410624' I0923 20:33:51.384] process 13180 exited with code 0 after 0.0m I0923 20:33:51.385] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569270048410624 cp /tmp/gsutil_lX0Pbg gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/jobResultsCache.json I0923 20:33:52.756] process 13324 exited with code 0 after 0.0m I0923 20:33:52.757] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_giujNf gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/1176231311723991043/finished.json I0923 20:33:54.043] process 13502 exited with code 0 after 0.0m I0923 20:33:54.043] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_BDCJWt gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-integration/latest-build.txt I0923 20:33:55.456] process 13680 exited with code 0 after 0.0m I0923 20:33:55.456] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_RfCPFt gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-integration/latest-build.txt I0923 20:33:56.836] process 13858 exited with code 0 after 0.0m