W0923 20:26:33.976] ************************************************************************** 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.977] Args: --job=pull-azurefile-csi-driver-sanity --service-account=/etc/service-account/service-account.json --upload=gs://kubernetes-jenkins/logs --job=pull-azurefile-csi-driver-sanity --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 sanity-test I0923 20:26:33.977] Bootstrap pull-azurefile-csi-driver-sanity... I0923 20:26:33.980] Builder: 59d8c2ec-de40-11e9-b3d3-b20db476995a I0923 20:26:33.980] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190903-df3b2f0-master I0923 20:26:33.981] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176231311723991042 I0923 20:26:33.981] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 20:26:34.607] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 20:26:34.641] process 32 exited with code 0 after 0.0m I0923 20:26:34.642] Call: gcloud config get-value account I0923 20:26:34.914] process 44 exited with code 0 after 0.0m I0923 20:26:34.914] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 20:26:34.915] Root: /go/src I0923 20:26:34.915] cd to /go/src I0923 20:26:34.915] 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.915] Call: git init github.com/kubernetes-sigs/azurefile-csi-driver I0923 20:26:34.919] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/.git/ I0923 20:26:34.919] process 56 exited with code 0 after 0.0m I0923 20:26:34.919] Call: git config --local user.name 'K8S Bootstrap' I0923 20:26:34.922] process 57 exited with code 0 after 0.0m I0923 20:26:34.922] Call: git config --local user.email k8s_bootstrap@localhost I0923 20:26:34.925] process 58 exited with code 0 after 0.0m I0923 20:26:34.925] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azurefile-csi-driver master +refs/pull/120/head:refs/pr/120 I0923 20:26:42.793] process 59 exited with code 0 after 0.1m I0923 20:26:42.793] Call: git checkout -B test daea67167aecc01a8bfa2b24f33c4a08998cdc74 W0923 20:26:43.246] Switched to a new branch 'test' I0923 20:26:43.248] process 71 exited with code 0 after 0.0m I0923 20:26:43.248] Call: git show -s --format=format:%ct HEAD I0923 20:26:43.261] process 72 exited with code 0 after 0.0m I0923 20:26:43.261] Call: git merge --no-ff -m 'Merge +refs/pull/120/head:refs/pr/120' 570298b9ba25107121c6f79cf327e6f9c6a99dbc I0923 20:26:43.564] Auto-merging test/utils/credentials/credentials_test.go I0923 20:26:43.565] Auto-merging test/utils/credentials/credentials.go I0923 20:26:43.565] Removing test/e2e/run-test.sh I0923 20:26:43.565] Removing hack/template/azure.json I0923 20:26:43.565] Merge made by the 'recursive' strategy. I0923 20:26:43.568] Makefile | 60 +++++++++++------ I0923 20:26:43.569] hack/template/azure.json | 9 --- I0923 20:26:43.569] hack/verify-golint.sh | 7 +- I0923 20:26:43.569] test/e2e/README.md | 22 +++++-- I0923 20:26:43.569] test/e2e/dynamic_provisioning.go | 17 ----- I0923 20:26:43.569] test/e2e/pre_provisioning.go | 23 +------ I0923 20:26:43.569] test/e2e/run-test.sh | 31 --------- I0923 20:26:43.570] test/e2e/suite_test.go | 84 ++++++++++++++++++++++++ I0923 20:26:43.570] test/integration/integration_test.go | 7 +- I0923 20:26:43.570] test/integration/run-tests-all-clouds.sh | 4 +- I0923 20:26:43.570] test/sanity/run-tests-all-clouds.sh | 4 +- I0923 20:26:43.570] test/sanity/sanity_test.go | 10 +-- I0923 20:26:43.570] test/{ => utils}/azure/azure_helpers.go | 0 I0923 20:26:43.571] test/{ => utils}/credentials/credentials.go | 22 +++++-- I0923 20:26:43.571] test/{ => utils}/credentials/credentials_test.go | 6 +- I0923 20:26:43.571] 15 files changed, 175 insertions(+), 131 deletions(-) I0923 20:26:43.571] delete mode 100644 hack/template/azure.json I0923 20:26:43.571] delete mode 100755 test/e2e/run-test.sh I0923 20:26:43.571] rename test/{ => utils}/azure/azure_helpers.go (100%) I0923 20:26:43.571] rename test/{ => utils}/credentials/credentials.go (87%) I0923 20:26:43.572] rename test/{ => utils}/credentials/credentials_test.go (98%) I0923 20:26:43.572] process 73 exited with code 0 after 0.0m I0923 20:26:43.572] Configure environment... I0923 20:26:43.572] Call: git show -s --format=format:%ct HEAD I0923 20:26:43.576] process 75 exited with code 0 after 0.0m I0923 20:26:43.576] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 20:26:44.317] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 20:26:44.430] process 76 exited with code 0 after 0.0m I0923 20:26:44.431] Call: gcloud config get-value account I0923 20:26:44.697] process 88 exited with code 0 after 0.0m I0923 20:26:44.697] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 20:26:44.697] Start 1176231311723991042 at unknown... I0923 20:26:44.699] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_qXvQTK gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176231311723991042/started.json I0923 20:26:46.225] process 100 exited with code 0 after 0.0m I0923 20:26:46.226] 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-sanity/1176231311723991042' cp /tmp/gsutil_Tv7bQY gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/1176231311723991042.txt I0923 20:26:47.770] process 278 exited with code 0 after 0.0m I0923 20:26:47.770] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make sanity-test W0923 20:26:47.791] Run: ('make', 'sanity-test') W0923 20:26:47.867] go: finding github.com/gogo/protobuf v1.2.1 W0923 20:26:47.869] go: finding github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0923 20:26:47.871] go: finding github.com/coreos/go-systemd v0.0.0-20190321100706-95778dfbb74e W0923 20:26:47.871] go: finding github.com/Azure/go-autorest v11.5.1+incompatible W0923 20:26:47.873] go: finding github.com/json-iterator/go v1.1.5 W0923 20:26:47.875] go: finding github.com/gorilla/websocket v1.4.0 W0923 20:26:47.876] go: finding github.com/go-openapi/swag v0.19.0 W0923 20:26:47.877] go: finding github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0923 20:26:47.879] go: finding github.com/PuerkitoBio/purell v1.1.1 I0923 20:26:47.980] 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:48.184] go: finding google.golang.org/api v0.1.0 W0923 20:26:49.452] go: finding golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 20:26:49.491] go: finding github.com/stretchr/testify v1.3.0 W0923 20:26:49.962] go: finding github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 20:26:50.136] go: finding github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 20:26:50.338] go: finding k8s.io/client-go v11.0.0+incompatible W0923 20:26:50.508] go: finding gopkg.in/inf.v0 v0.9.1 W0923 20:26:50.694] go: finding github.com/kubernetes-csi/external-snapshotter v1.0.1 W0923 20:26:50.776] go: finding github.com/coreos/go-semver v0.3.0 W0923 20:26:51.836] go: finding k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 20:26:52.053] go: finding sigs.k8s.io/kustomize v2.0.3+incompatible W0923 20:26:52.380] go: finding k8s.io/kubernetes v1.14.0 W0923 20:26:52.462] go: finding github.com/davecgh/go-spew v1.1.1 W0923 20:26:52.690] go: finding gopkg.in/square/go-jose.v2 v2.3.0 W0923 20:26:53.206] go: finding google.golang.org/grpc v1.19.0 W0923 20:26:53.278] go: finding github.com/go-openapi/spec v0.19.0 W0923 20:26:54.114] go: finding go.opencensus.io v0.18.0 W0923 20:26:54.224] go: finding github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0923 20:26:54.500] go: finding golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 20:26:55.028] go: finding gopkg.in/yaml.v2 v2.2.2 W0923 20:26:55.300] go: finding github.com/xiang90/probing v0.0.0-20190116061207-43a291ad63a2 W0923 20:26:55.595] go: finding github.com/grpc-ecosystem/go-grpc-prometheus v1.2.0 W0923 20:26:55.915] go: finding golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 20:26:56.076] go: finding k8s.io/klog v0.2.0 W0923 20:26:56.518] go: finding go.uber.org/multierr v1.1.0 W0923 20:26:56.709] go: finding github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 20:26:56.822] go: finding k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 20:26:57.517] go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 20:26:57.615] go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b W0923 20:26:57.750] go: finding github.com/spf13/pflag v1.0.3 W0923 20:26:58.165] go: finding github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 20:26:58.328] go: finding github.com/coreos/bbolt v1.3.2 W0923 20:26:58.602] go: finding github.com/golang/protobuf v1.3.0 W0923 20:26:59.165] go: finding github.com/prometheus/client_golang v0.9.2 W0923 20:26:59.384] go: finding google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 20:27:00.126] go: finding golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 20:27:00.428] go: finding cloud.google.com/go v0.34.0 W0923 20:27:00.618] go: finding github.com/openzipkin/zipkin-go v0.1.1 W0923 20:27:00.847] go: finding golang.org/x/lint v0.0.0-20190227174305-5b3e6a55c961 W0923 20:27:01.626] go: finding github.com/ghodss/yaml v1.0.0 W0923 20:27:02.768] go: finding contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 20:27:02.854] go: finding go.uber.org/zap v1.9.1 W0923 20:27:03.803] go: finding k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0923 20:27:04.230] go: finding honnef.co/go/tools v0.0.0-20190102054323-c2f93a96b099 W0923 20:27:04.860] go: finding github.com/imdario/mergo v0.3.7 W0923 20:27:05.834] go: finding github.com/stretchr/objx v0.1.1 W0923 20:27:06.174] go: finding github.com/grpc-ecosystem/grpc-gateway v1.5.0 W0923 20:27:06.360] go: finding sigs.k8s.io/yaml v1.1.0 W0923 20:27:06.594] go: finding github.com/go-openapi/jsonreference v0.19.0 W0923 20:27:07.004] go: finding go.uber.org/atomic v1.3.2 W0923 20:27:10.020] go: finding golang.org/x/text v0.3.0 W0923 20:27:10.504] go: finding github.com/kisielk/errcheck v1.1.0 W0923 20:27:10.507] go: finding github.com/soheilhy/cmux v0.1.4 W0923 20:27:10.887] go: finding github.com/google/btree v1.0.0 W0923 20:27:10.889] go: finding k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0923 20:27:11.358] go: finding github.com/elazarl/goproxy/ext v0.0.0-20190410145444-c548f45dcf1d W0923 20:27:11.582] go: finding github.com/elazarl/goproxy v0.0.0-20190410145444-c548f45dcf1d W0923 20:27:11.702] go: finding github.com/prometheus/common v0.2.0 W0923 20:27:11.751] go: finding k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 20:27:11.954] go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 20:27:12.156] go: finding golang.org/x/exp v0.0.0-20190121172915-509febef88a4 W0923 20:27:12.207] go: finding golang.org/x/tools v0.0.0-20190226205152-f727befe758c W0923 20:27:12.292] go: finding github.com/BurntSushi/toml v0.3.1 W0923 20:27:12.411] go: finding github.com/hashicorp/golang-lru v0.5.1 W0923 20:27:13.790] go: finding github.com/apache/thrift v0.0.0-20180902110319-2566ecd5d999 W0923 20:27:13.940] go: finding github.com/pelletier/go-toml v1.4.0 W0923 20:27:14.000] go: finding github.com/google/go-cmp v0.2.0 W0923 20:27:14.057] go: finding github.com/spf13/afero v1.2.2 W0923 20:27:14.502] go: finding github.com/ugorji/go v1.1.4 W0923 20:27:14.706] go: finding github.com/inconshreveable/mousetrap v1.0.0 W0923 20:27:15.056] go: finding github.com/kisielk/gotool v1.0.0 W0923 20:27:15.509] go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc W0923 20:27:16.896] go: finding github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 20:27:16.947] go: finding google.golang.org/appengine v1.4.0 W0923 20:27:17.000] go: finding github.com/pmezard/go-difflib v1.0.0 W0923 20:27:17.118] go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 20:27:17.275] go: finding github.com/evanphx/json-patch v3.0.0+incompatible W0923 20:27:17.653] go: finding github.com/fatih/camelcase v1.0.0 W0923 20:27:20.394] go: finding github.com/container-storage-interface/spec v1.1.0 W0923 20:27:20.446] go: finding github.com/coreos/etcd v3.3.12+incompatible W0923 20:27:20.467] go: finding github.com/marstr/guid v1.1.0 W0923 20:27:20.583] go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf W0923 20:27:20.595] go: finding github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc W0923 20:27:20.611] go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 W0923 20:27:22.050] go: finding github.com/onsi/ginkgo v1.7.0 W0923 20:27:22.586] go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 W0923 20:27:22.923] go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 W0923 20:27:22.930] go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 20:27:23.465] go: finding github.com/julienschmidt/httprouter v1.2.0 W0923 20:27:24.686] go: finding github.com/grpc-ecosystem/go-grpc-middleware v1.0.0 W0923 20:27:24.716] go: finding github.com/pkg/errors v0.8.1 W0923 20:27:24.730] go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 20:27:24.950] go: finding github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 20:27:24.978] go: finding github.com/go-stack/stack v1.8.0 W0923 20:27:24.983] go: finding github.com/golang/mock v1.1.1 W0923 20:27:25.170] go: finding github.com/jonboulle/clockwork v0.1.0 W0923 20:27:25.857] go: finding k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 20:27:26.062] go: finding github.com/go-openapi/jsonpointer v0.19.0 W0923 20:27:26.965] go: finding github.com/go-kit/kit v0.8.0 W0923 20:27:27.129] go: finding github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 20:27:27.405] go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 W0923 20:27:27.492] go: finding github.com/onsi/gomega v1.4.3 W0923 20:27:27.502] go: finding github.com/Azure/azure-storage-file-go v0.5.0 W0923 20:27:27.508] go: finding github.com/spf13/cobra v0.0.3 W0923 20:27:27.708] go: finding github.com/go-logfmt/logfmt v0.3.0 W0923 20:27:27.758] go: finding github.com/opencontainers/go-digest v1.0.0-rc1 W0923 20:27:28.518] go: finding k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0923 20:27:28.714] go: finding github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0923 20:27:29.034] go: finding github.com/googleapis/gnostic v0.2.0 W0923 20:27:29.286] go: finding github.com/google/uuid v1.1.1 W0923 20:27:30.292] go: finding github.com/Azure/azure-pipeline-go v0.2.1 W0923 20:27:30.373] go: finding github.com/emicklei/go-restful v2.9.3+incompatible W0923 20:27:30.569] go: finding github.com/client9/misspell v0.3.4 W0923 20:27:30.574] go: finding github.com/kr/pretty v0.1.0 W0923 20:27:30.720] go: finding github.com/peterbourgon/diskv v2.0.1+incompatible W0923 20:27:30.858] go: finding github.com/hpcloud/tail v1.0.0 W0923 20:27:31.283] go: finding github.com/rogpeppe/go-charset v0.0.0-20180617210344-2471d30d28b4 W0923 20:27:31.354] go: finding github.com/kr/pty v1.1.5 W0923 20:27:31.636] go: finding github.com/fsnotify/fsnotify v1.4.7 W0923 20:27:32.952] go: finding github.com/coreos/pkg v0.0.0-20180928190104-399ea9e2e55f W0923 20:27:33.010] go: finding k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 20:27:33.072] go: finding github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 20:27:33.136] go: finding go.etcd.io/bbolt v1.3.2 W0923 20:27:33.462] go: finding github.com/sirupsen/logrus v1.2.0 W0923 20:27:33.814] go: finding github.com/tmc/grpc-websocket-proxy v0.0.0-20190109142713-0ad062ec5ee5 W0923 20:27:33.937] go: finding golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 20:27:33.972] go: finding k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 20:27:34.734] go: finding k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0923 20:27:34.994] go: finding github.com/kr/text v0.1.0 W0923 20:27:35.137] go: finding github.com/dnaeon/go-vcr v1.0.1 W0923 20:27:35.665] go: finding k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0923 20:27:35.707] go: finding gopkg.in/gcfg.v1 v1.2.0 W0923 20:27:36.114] go: finding k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 20:27:36.436] go: finding golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 20:27:36.709] go: finding github.com/satori/go.uuid v1.2.0 W0923 20:27:36.714] go: finding gopkg.in/fsnotify.v1 v1.4.7 W0923 20:27:36.961] go: finding k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 20:27:37.206] go: finding github.com/vmware/govmomi v0.20.1 W0923 20:27:37.560] go: finding github.com/aws/aws-sdk-go v1.16.26 W0923 20:27:37.900] go: finding github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 20:27:39.017] go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 W0923 20:27:39.091] go: finding github.com/GoogleCloudPlatform/k8s-cloud-provider v0.0.0-20181220005116-f8e995905100 W0923 20:27:39.334] go: finding gopkg.in/warnings.v0 v0.1.1 W0923 20:27:39.486] go: finding gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0923 20:27:48.694] go: finding github.com/jmespath/go-jmespath v0.0.0-20180206201540-c2b33e8439af W0923 20:27:51.002] go: downloading k8s.io/klog v0.2.0 W0923 20:27:51.002] go: downloading github.com/prometheus/client_golang v0.9.2 W0923 20:27:51.014] go: downloading github.com/golang/protobuf v1.3.0 W0923 20:27:51.018] go: downloading google.golang.org/grpc v1.19.0 W0923 20:27:51.018] go: downloading k8s.io/kubernetes v1.14.0 W0923 20:27:51.018] go: downloading golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 20:27:51.025] go: downloading github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 20:27:51.044] go: downloading sigs.k8s.io/yaml v1.1.0 W0923 20:27:51.222] go: extracting k8s.io/klog v0.2.0 W0923 20:27:51.262] go: extracting sigs.k8s.io/yaml v1.1.0 W0923 20:27:51.386] go: extracting github.com/prometheus/client_golang v0.9.2 W0923 20:27:51.499] go: downloading github.com/prometheus/common v0.2.0 W0923 20:27:52.090] go: extracting github.com/golang/protobuf v1.3.0 W0923 20:27:52.562] go: extracting github.com/prometheus/common v0.2.0 W0923 20:27:52.754] go: downloading github.com/Azure/azure-storage-file-go v0.5.0 W0923 20:27:52.893] go: downloading github.com/container-storage-interface/spec v1.1.0 W0923 20:27:53.309] go: extracting github.com/Azure/azure-storage-file-go v0.5.0 W0923 20:27:53.367] go: extracting github.com/container-storage-interface/spec v1.1.0 W0923 20:27:53.607] go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 20:27:53.614] go: downloading github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 20:27:53.894] go: extracting google.golang.org/grpc v1.19.0 W0923 20:27:53.974] go: extracting github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 20:27:53.974] go: downloading golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 20:27:54.070] go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 20:27:54.150] go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 20:27:54.698] go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 20:27:54.726] go: downloading github.com/Azure/azure-pipeline-go v0.2.1 W0923 20:27:54.899] go: downloading github.com/google/uuid v1.1.1 W0923 20:27:54.902] go: downloading gopkg.in/yaml.v2 v2.2.2 W0923 20:27:54.918] go: downloading k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 20:27:55.022] go: extracting github.com/Azure/azure-pipeline-go v0.2.1 W0923 20:27:55.174] go: extracting github.com/google/uuid v1.1.1 W0923 20:27:55.179] go: downloading github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 20:27:55.362] go: extracting gopkg.in/yaml.v2 v2.2.2 W0923 20:27:55.390] go: downloading google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 20:27:55.446] go: extracting github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 20:27:55.934] go: extracting golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 20:27:56.028] go: extracting k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 20:27:56.219] go: downloading k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 20:27:56.476] go: downloading github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 20:27:56.486] go: downloading k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 20:27:56.486] go: downloading k8s.io/client-go v11.0.0+incompatible W0923 20:27:56.697] go: extracting github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 20:27:57.675] go: extracting k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 20:27:58.250] go: extracting k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 20:27:58.625] go: downloading k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 20:27:58.770] go: extracting golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 20:27:59.022] go: extracting k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 20:27:59.125] go: downloading github.com/Azure/go-autorest v11.5.1+incompatible W0923 20:27:59.595] go: downloading github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 20:27:59.846] go: extracting k8s.io/client-go v11.0.0+incompatible W0923 20:28:00.322] go: extracting github.com/Azure/go-autorest v11.5.1+incompatible W0923 20:28:00.549] go: downloading k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 20:28:00.590] go: extracting github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 20:28:02.494] go: extracting k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 20:28:02.662] go: downloading github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 20:28:02.666] go: downloading github.com/gogo/protobuf v1.2.1 W0923 20:28:02.667] go: downloading github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 20:28:02.818] go: extracting github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 20:28:02.847] go: downloading github.com/hashicorp/golang-lru v0.5.1 W0923 20:28:02.973] go: extracting github.com/hashicorp/golang-lru v0.5.1 W0923 20:28:02.980] go: downloading contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 20:28:02.994] go: extracting github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 20:28:03.051] go: downloading golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 20:28:03.122] go: extracting contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 20:28:03.126] go: downloading github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 20:28:03.126] go: extracting golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 20:28:03.126] go: downloading go.opencensus.io v0.18.0 W0923 20:28:03.366] go: extracting github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 20:28:03.434] go: downloading golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 20:28:03.702] go: extracting golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 20:28:03.814] go: extracting go.opencensus.io v0.18.0 W0923 20:28:04.046] go: downloading github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 20:28:04.389] go: extracting github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 20:28:04.389] go: downloading golang.org/x/text v0.3.0 W0923 20:28:05.298] go: extracting google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 20:28:07.374] go: extracting github.com/gogo/protobuf v1.2.1 W0923 20:28:07.374] go: downloading gopkg.in/inf.v0 v0.9.1 W0923 20:28:08.006] go: extracting gopkg.in/inf.v0 v0.9.1 W0923 20:28:08.255] go: downloading google.golang.org/api v0.1.0 W0923 20:28:11.746] go: extracting golang.org/x/text v0.3.0 W0923 20:28:12.476] go: downloading golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 20:28:12.482] go: downloading github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 20:28:12.489] go: downloading k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 20:28:12.670] go: extracting github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 20:28:12.787] go: downloading github.com/davecgh/go-spew v1.1.1 W0923 20:28:12.926] go: extracting github.com/davecgh/go-spew v1.1.1 W0923 20:28:14.028] go: extracting k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 20:28:14.030] go: extracting golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 20:28:14.098] go: downloading github.com/googleapis/gnostic v0.2.0 W0923 20:28:14.313] go: downloading github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 20:28:14.314] go: downloading github.com/json-iterator/go v1.1.5 W0923 20:28:14.455] go: extracting github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 20:28:14.474] go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 20:28:14.525] go: extracting github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 20:28:14.594] go: extracting github.com/json-iterator/go v1.1.5 W0923 20:28:15.138] go: extracting github.com/googleapis/gnostic v0.2.0 W0923 20:28:18.853] go: extracting k8s.io/kubernetes v1.14.0 W0923 20:28:18.870] go: extracting github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 20:28:22.702] go: extracting google.golang.org/api v0.1.0 W0923 20:28:22.771] go: downloading github.com/marstr/guid v1.1.0 W0923 20:28:22.777] go: downloading github.com/satori/go.uuid v1.2.0 W0923 20:28:22.989] go: extracting github.com/satori/go.uuid v1.2.0 W0923 20:28:22.990] go: extracting github.com/marstr/guid v1.1.0 W0923 20:28:24.626] go: downloading k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 20:28:24.628] go: downloading k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 20:28:24.675] go: downloading golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 20:28:25.486] go: extracting golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 20:28:26.302] go: extracting k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 20:28:26.522] go: downloading github.com/spf13/pflag v1.0.3 W0923 20:28:26.731] go: extracting github.com/spf13/pflag v1.0.3 W0923 20:28:27.778] go: extracting k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed I0923 20:30:24.729] go test -v -timeout=10m ./test/sanity "-ginkgo.noColor" W0923 20:30:24.835] go: downloading github.com/stretchr/testify v1.3.0 W0923 20:30:24.835] go: downloading github.com/pelletier/go-toml v1.4.0 W0923 20:30:27.344] go: extracting github.com/pelletier/go-toml v1.4.0 W0923 20:30:27.345] go: extracting github.com/stretchr/testify v1.3.0 W0923 20:30:27.359] go: downloading github.com/pmezard/go-difflib v1.0.0 W0923 20:30:27.429] go: extracting github.com/pmezard/go-difflib v1.0.0 I0923 20:30:42.186] flag provided but not defined: -ginkgo.noColor I0923 20:30:42.194] Usage of /tmp/go-build347200931/b001/sanity.test: I0923 20:30:42.194] -test.bench regexp I0923 20:30:42.195] run only benchmarks matching regexp I0923 20:30:42.195] -test.benchmem I0923 20:30:42.195] print memory allocations for benchmarks I0923 20:30:42.195] -test.benchtime d I0923 20:30:42.196] run each benchmark for duration d (default 1s) I0923 20:30:42.196] -test.blockprofile file I0923 20:30:42.196] write a goroutine blocking profile to file I0923 20:30:42.197] -test.blockprofilerate rate I0923 20:30:42.197] set blocking profile rate (see runtime.SetBlockProfileRate) (default 1) I0923 20:30:42.197] -test.count n I0923 20:30:42.198] run tests and benchmarks n times (default 1) I0923 20:30:42.198] -test.coverprofile file I0923 20:30:42.198] write a coverage profile to file I0923 20:30:42.199] -test.cpu list I0923 20:30:42.199] comma-separated list of cpu counts to run each test with I0923 20:30:42.199] -test.cpuprofile file I0923 20:30:42.199] write a cpu profile to file I0923 20:30:42.199] -test.failfast I0923 20:30:42.200] do not start new tests after the first test failure I0923 20:30:42.200] -test.list regexp I0923 20:30:42.200] list tests, examples, and benchmarks matching regexp then exit I0923 20:30:42.200] -test.memprofile file I0923 20:30:42.200] write an allocation profile to file I0923 20:30:42.201] -test.memprofilerate rate I0923 20:30:42.201] set memory allocation profiling rate (see runtime.MemProfileRate) I0923 20:30:42.201] -test.mutexprofile string I0923 20:30:42.201] write a mutex contention profile to the named file after execution I0923 20:30:42.202] -test.mutexprofilefraction int I0923 20:30:42.202] if >= 0, calls runtime.SetMutexProfileFraction() (default 1) I0923 20:30:42.202] -test.outputdir dir I0923 20:30:42.202] write profiles to dir I0923 20:30:42.202] -test.parallel n I0923 20:30:42.203] run at most n tests in parallel (default 8) I0923 20:30:42.203] -test.run regexp I0923 20:30:42.203] run only tests and examples matching regexp I0923 20:30:42.203] -test.short I0923 20:30:42.204] run smaller test suite to save time I0923 20:30:42.204] -test.testlogfile file I0923 20:30:42.204] write test action log to file (for use only by cmd/go) I0923 20:30:42.204] -test.timeout d I0923 20:30:42.205] panic test binary after duration d (default 0, timeout disabled) I0923 20:30:42.205] -test.trace file I0923 20:30:42.205] write an execution trace to file I0923 20:30:42.206] -test.v I0923 20:30:42.206] verbose: print additional output I0923 20:30:42.206] FAIL github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity 0.011s I0923 20:30:42.230] Makefile:50: recipe for target 'sanity-test' failed W0923 20:30:42.234] make: *** [sanity-test] Error 1 W0923 20:30:42.234] Traceback (most recent call last): W0923 20:30:42.235] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W0923 20:30:42.235] main(ARGS.env, ARGS.cmd + ARGS.args) W0923 20:30:42.235] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W0923 20:30:42.235] check(*cmd) W0923 20:30:42.235] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W0923 20:30:42.236] subprocess.check_call(cmd) W0923 20:30:42.236] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W0923 20:30:42.236] raise CalledProcessError(retcode, cmd) W0923 20:30:42.236] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2 E0923 20:30:42.236] Command failed I0923 20:30:42.237] process 456 exited with code 1 after 3.9m E0923 20:30:42.237] FAIL: pull-azurefile-csi-driver-sanity I0923 20:30:42.237] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 20:30:42.955] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 20:30:42.992] process 10245 exited with code 0 after 0.0m I0923 20:30:43.002] Call: gcloud config get-value account I0923 20:30:43.297] process 10257 exited with code 0 after 0.0m I0923 20:30:43.297] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 20:30:43.298] Upload result and artifacts... I0923 20:30:43.298] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176231311723991042 W0923 20:30:43.298] Missing local artifacts : /workspace/_artifacts W0923 20:30:43.298] metadata path /workspace/_artifacts/metadata.json does not exist W0923 20:30:43.298] metadata not found or invalid, init with empty metadata I0923 20:30:43.299] Call: git rev-parse HEAD I0923 20:30:43.301] process 10269 exited with code 0 after 0.0m I0923 20:30:43.301] Call: git rev-parse HEAD I0923 20:30:43.303] process 10270 exited with code 0 after 0.0m I0923 20:30:43.304] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 20:30:44.437] process 10271 exited with code 0 after 0.0m I0923 20:30:44.438] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/jobResultsCache.json#1569270048262636' I0923 20:30:46.196] process 10413 exited with code 0 after 0.0m I0923 20:30:46.197] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569270048262636 cp /tmp/gsutil_KcnHMi gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 20:30:48.639] process 10557 exited with code 0 after 0.0m I0923 20:30:48.639] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 20:30:50.522] process 10735 exited with code 0 after 0.0m I0923 20:30:50.523] Call: gsutil -q cat 'gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/jobResultsCache.json#1569270052328383' I0923 20:30:52.492] process 10877 exited with code 0 after 0.0m I0923 20:30:52.493] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:1569270052328383 cp /tmp/gsutil_AoVlL0 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 20:30:57.317] process 11021 exited with code 0 after 0.1m I0923 20:30:57.318] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_hKpdai gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176231311723991042/finished.json I0923 20:31:01.191] process 11199 exited with code 0 after 0.1m I0923 20:31:01.192] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_kyHFNf gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/latest-build.txt I0923 20:31:03.657] process 11377 exited with code 0 after 0.0m I0923 20:31:03.658] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_AfIuQK gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/latest-build.txt I0923 20:31:06.402] process 11555 exited with code 0 after 0.0m