W0923 19:30:24.558] ************************************************************************** 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 19:30:24.558] 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:bfa06f513d7a80aa38e890022d2755c04b499a37 --upload=gs://kubernetes-upstream/pr-logs --timeout=460 --scenario=execute -- make sanity-test I0923 19:30:24.558] Bootstrap pull-azurefile-csi-driver-sanity... I0923 19:30:24.561] Builder: 8c929eb6-de38-11e9-b3d3-b20db476995a I0923 19:30:24.561] Image: gcr.io/k8s-testimages/kubekins-e2e:v20190903-df3b2f0-master I0923 19:30:24.562] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176217218837385216 I0923 19:30:24.562] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 19:30:25.099] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 19:30:25.130] process 32 exited with code 0 after 0.0m I0923 19:30:25.130] Call: gcloud config get-value account I0923 19:30:25.378] process 44 exited with code 0 after 0.0m I0923 19:30:25.379] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 19:30:25.379] Root: /go/src I0923 19:30:25.379] cd to /go/src I0923 19:30:25.379] Checkout: /go/src/github.com/kubernetes-sigs/azurefile-csi-driver master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:bfa06f513d7a80aa38e890022d2755c04b499a37 to /go/src/github.com/kubernetes-sigs/azurefile-csi-driver I0923 19:30:25.380] Call: git init github.com/kubernetes-sigs/azurefile-csi-driver I0923 19:30:25.383] Initialized empty Git repository in /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/.git/ I0923 19:30:25.384] process 56 exited with code 0 after 0.0m I0923 19:30:25.384] Call: git config --local user.name 'K8S Bootstrap' I0923 19:30:25.386] process 57 exited with code 0 after 0.0m I0923 19:30:25.387] Call: git config --local user.email k8s_bootstrap@localhost I0923 19:30:25.389] process 58 exited with code 0 after 0.0m I0923 19:30:25.390] Call: git fetch --quiet --tags https://github.com/kubernetes-sigs/azurefile-csi-driver master +refs/pull/120/head:refs/pr/120 I0923 19:30:30.493] process 59 exited with code 0 after 0.1m I0923 19:30:30.493] Call: git checkout -B test daea67167aecc01a8bfa2b24f33c4a08998cdc74 W0923 19:30:30.925] Switched to a new branch 'test' I0923 19:30:30.927] process 71 exited with code 0 after 0.0m I0923 19:30:30.928] Call: git show -s --format=format:%ct HEAD I0923 19:30:30.939] process 72 exited with code 0 after 0.0m I0923 19:30:30.939] Call: git merge --no-ff -m 'Merge +refs/pull/120/head:refs/pr/120' bfa06f513d7a80aa38e890022d2755c04b499a37 I0923 19:30:31.296] Auto-merging test/utils/credentials/credentials_test.go I0923 19:30:31.296] Auto-merging test/utils/credentials/credentials.go I0923 19:30:31.297] Removing test/e2e/run-test.sh I0923 19:30:31.297] Removing hack/template/azure.json I0923 19:30:31.297] Merge made by the 'recursive' strategy. I0923 19:30:31.299] Makefile | 39 ++++++++--- I0923 19:30:31.300] hack/template/azure.json | 9 --- I0923 19:30:31.300] hack/verify-golint.sh | 7 +- I0923 19:30:31.300] test/e2e/README.md | 22 +++++-- I0923 19:30:31.300] test/e2e/dynamic_provisioning.go | 17 ----- I0923 19:30:31.300] test/e2e/pre_provisioning.go | 23 +------ I0923 19:30:31.300] test/e2e/run-test.sh | 31 --------- I0923 19:30:31.300] test/e2e/suite_test.go | 84 ++++++++++++++++++++++++ I0923 19:30:31.301] test/integration/integration_test.go | 7 +- I0923 19:30:31.301] test/sanity/sanity_test.go | 10 +-- I0923 19:30:31.301] test/{ => utils}/azure/azure_helpers.go | 0 I0923 19:30:31.301] test/{ => utils}/credentials/credentials.go | 20 ++++-- I0923 19:30:31.301] test/{ => utils}/credentials/credentials_test.go | 6 +- I0923 19:30:31.301] 13 files changed, 159 insertions(+), 116 deletions(-) I0923 19:30:31.301] delete mode 100644 hack/template/azure.json I0923 19:30:31.302] delete mode 100755 test/e2e/run-test.sh I0923 19:30:31.302] rename test/{ => utils}/azure/azure_helpers.go (100%) I0923 19:30:31.302] rename test/{ => utils}/credentials/credentials.go (88%) I0923 19:30:31.302] rename test/{ => utils}/credentials/credentials_test.go (98%) I0923 19:30:31.302] process 73 exited with code 0 after 0.0m I0923 19:30:31.302] Configure environment... I0923 19:30:31.303] Call: git show -s --format=format:%ct HEAD I0923 19:30:31.306] process 75 exited with code 0 after 0.0m I0923 19:30:31.307] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 19:30:31.917] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 19:30:32.022] process 76 exited with code 0 after 0.0m I0923 19:30:32.023] Call: gcloud config get-value account I0923 19:30:32.270] process 88 exited with code 0 after 0.0m I0923 19:30:32.271] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 19:30:32.271] Start 1176217218837385216 at unknown... I0923 19:30:32.273] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_D0osFr gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176217218837385216/started.json I0923 19:30:33.805] process 100 exited with code 0 after 0.0m I0923 19:30:33.806] 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/1176217218837385216' cp /tmp/gsutil_5oOzOG gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/1176217218837385216.txt I0923 19:30:35.119] process 278 exited with code 0 after 0.0m I0923 19:30:35.119] Call: /workspace/./test-infra/jenkins/../scenarios/execute.py make sanity-test W0923 19:30:35.140] Run: ('make', 'sanity-test') W0923 19:30:35.187] go: finding github.com/elazarl/goproxy/ext v0.0.0-20190410145444-c548f45dcf1d W0923 19:30:35.188] go: finding github.com/PuerkitoBio/purell v1.1.1 W0923 19:30:35.190] go: finding github.com/googleapis/gnostic v0.2.0 W0923 19:30:35.190] go: finding github.com/kubernetes-csi/external-snapshotter v1.0.1 W0923 19:30:35.192] go: finding github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 19:30:35.193] go: finding github.com/coreos/pkg v0.0.0-20180928190104-399ea9e2e55f W0923 19:30:35.193] go: finding github.com/peterbourgon/diskv v2.0.1+incompatible W0923 19:30:35.196] go: finding github.com/gregjones/httpcache v0.0.0-20190212212710-3befbb6ad0cc I0923 19:30:35.297] 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=7e281ada5cc3702bb22dc17168128379c1e4d552 -X github.com/kubernetes-sigs/azurefile-csi-driver/pkg/azurefile.buildDate=2019-09-23T19:30:35Z -s -w -extldflags '-static'" -o _output/azurefileplugin ./pkg/azurefileplugin W0923 19:30:35.408] go: finding contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 19:30:35.437] go: finding k8s.io/cli-runtime v0.0.0-20190314001948-2899ed30580f W0923 19:30:36.491] go: finding github.com/coreos/go-semver v0.3.0 W0923 19:30:36.545] go: finding k8s.io/kube-aggregator v0.0.0-20190401185513-77f9841f26eb W0923 19:30:36.558] go: finding github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 19:30:36.662] go: finding github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 19:30:36.673] go: finding github.com/emicklei/go-restful v2.9.3+incompatible W0923 19:30:36.816] go: finding k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 19:30:36.874] go: finding github.com/grpc-ecosystem/go-grpc-prometheus v1.2.0 W0923 19:30:36.966] go: finding go.uber.org/atomic v1.3.2 W0923 19:30:36.984] go: finding k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 19:30:37.695] go: finding github.com/coreos/go-systemd v0.0.0-20190321100706-95778dfbb74e W0923 19:30:37.735] go: finding k8s.io/component-base v0.0.0-20190313120452-4727f38490bc W0923 19:30:37.984] go: finding github.com/mailru/easyjson v0.0.0-20190312143242-1de009706dbe W0923 19:30:38.108] go: finding github.com/xiang90/probing v0.0.0-20190116061207-43a291ad63a2 W0923 19:30:38.200] go: finding github.com/fatih/camelcase v1.0.0 W0923 19:30:38.359] go: finding github.com/golang/protobuf v1.3.0 W0923 19:30:38.557] go: finding google.golang.org/api v0.1.0 W0923 19:30:38.980] go: finding github.com/go-openapi/swag v0.19.0 W0923 19:30:39.040] go: finding github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 19:30:39.229] go: finding github.com/coreos/bbolt v1.3.2 W0923 19:30:39.287] go: finding github.com/stretchr/testify v1.3.0 W0923 19:30:39.447] go: finding github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 19:30:40.043] go: finding github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 19:30:40.155] go: finding google.golang.org/grpc v1.19.0 W0923 19:30:40.225] go: finding github.com/opencontainers/go-digest v1.0.0-rc1 W0923 19:30:40.504] go: finding github.com/coreos/etcd v3.3.12+incompatible W0923 19:30:40.688] go: finding github.com/pmezard/go-difflib v1.0.0 W0923 19:30:40.827] go: finding github.com/grpc-ecosystem/go-grpc-middleware v1.0.0 W0923 19:30:41.260] go: finding gopkg.in/yaml.v2 v2.2.2 W0923 19:30:41.548] go: finding github.com/container-storage-interface/spec v1.1.0 W0923 19:30:41.601] go: finding go.uber.org/multierr v1.1.0 W0923 19:30:41.649] go: finding go.opencensus.io v0.18.0 W0923 19:30:41.911] go: finding golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 19:30:42.050] go: finding github.com/docker/distribution v0.0.0-20170905204447-5db89f0ca686 W0923 19:30:42.236] go: finding github.com/gogo/protobuf v1.2.1 W0923 19:30:42.760] go: finding github.com/json-iterator/go v1.1.5 W0923 19:30:42.896] go: finding github.com/go-openapi/spec v0.19.0 W0923 19:30:42.939] go: finding k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 19:30:43.101] go: finding github.com/go-openapi/jsonreference v0.19.0 W0923 19:30:43.104] go: finding github.com/client9/misspell v0.3.4 W0923 19:30:43.108] go: finding google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 19:30:43.359] go: finding golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 19:30:43.375] go: finding golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 19:30:44.320] go: finding github.com/gorilla/websocket v1.4.0 W0923 19:30:44.395] go: finding github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 19:30:44.423] go: finding github.com/pkg/errors v0.8.1 W0923 19:30:44.451] go: finding github.com/prometheus/client_golang v0.9.2 W0923 19:30:44.697] go: finding gopkg.in/inf.v0 v0.9.1 W0923 19:30:44.747] go: finding github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 19:30:44.879] go: finding github.com/imdario/mergo v0.3.7 W0923 19:30:45.280] go: finding github.com/openzipkin/zipkin-go v0.1.1 W0923 19:30:45.324] go: finding k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 19:30:45.631] go: finding golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 19:30:45.636] go: finding github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 19:30:45.723] go: finding go.uber.org/zap v1.9.1 W0923 19:30:45.942] go: finding github.com/Azure/go-autorest v11.5.1+incompatible W0923 19:30:46.018] go: finding github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 19:30:46.103] go: finding github.com/evanphx/json-patch v3.0.0+incompatible W0923 19:30:46.251] go: finding k8s.io/cluster-bootstrap v0.0.0-20190313124217-0fa624df11e9 W0923 19:30:46.404] go: finding github.com/google/btree v1.0.0 W0923 19:30:46.831] go: finding gopkg.in/check.v1 v0.0.0-20161208181325-20d25e280405 W0923 19:30:47.058] go: finding k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 19:30:47.082] go: finding github.com/aws/aws-sdk-go v1.16.26 W0923 19:30:47.203] go: finding k8s.io/klog v0.2.0 W0923 19:30:47.339] go: finding k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 19:30:47.421] go: finding gopkg.in/warnings.v0 v0.1.1 W0923 19:30:47.497] go: finding sigs.k8s.io/kustomize v2.0.3+incompatible W0923 19:30:47.587] go: finding k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 19:30:47.659] go: finding golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 19:30:47.739] go: finding github.com/hashicorp/golang-lru v0.5.1 W0923 19:30:48.491] go: finding github.com/satori/go.uuid v1.2.0 W0923 19:30:48.525] go: finding github.com/davecgh/go-spew v1.1.1 W0923 19:30:48.748] go: finding github.com/spf13/afero v1.2.2 W0923 19:30:48.995] go: finding github.com/docker/spdystream v0.0.0-20181023171402-6480d4af844c W0923 19:30:49.011] go: finding golang.org/x/lint v0.0.0-20190227174305-5b3e6a55c961 W0923 19:30:49.242] go: finding github.com/google/go-cmp v0.2.0 W0923 19:30:49.587] go: finding github.com/vmware/govmomi v0.20.1 W0923 19:30:49.919] go: finding github.com/prometheus/common v0.2.0 W0923 19:30:49.927] go: finding golang.org/x/tools v0.0.0-20190226205152-f727befe758c W0923 19:30:50.032] go: finding github.com/golang/mock v1.1.1 W0923 19:30:50.071] go: finding k8s.io/kubernetes v1.14.0 W0923 19:30:50.410] go: finding cloud.google.com/go v0.34.0 W0923 19:30:50.550] go: finding k8s.io/client-go v11.0.0+incompatible W0923 19:30:50.589] go: finding github.com/google/uuid v1.1.1 W0923 19:30:50.717] go: finding github.com/go-openapi/jsonpointer v0.19.0 W0923 19:30:51.303] go: finding github.com/golang/glog v0.0.0-20160126235308-23def4e6c14b W0923 19:30:51.431] go: finding github.com/kr/logfmt v0.0.0-20140226030751-b84e30acd515 W0923 19:30:51.475] go: finding github.com/stretchr/objx v0.1.1 W0923 19:30:51.779] go: finding github.com/PuerkitoBio/urlesc v0.0.0-20170810143723-de5bf2ad4578 W0923 19:30:52.029] go: finding github.com/jonboulle/clockwork v0.1.0 W0923 19:30:52.255] go: finding github.com/pelletier/go-toml v1.4.0 W0923 19:30:52.615] go: finding github.com/onsi/gomega v1.4.3 W0923 19:30:52.827] go: finding github.com/go-logfmt/logfmt v0.3.0 W0923 19:30:52.961] go: finding gopkg.in/square/go-jose.v2 v2.3.0 W0923 19:30:53.031] go: finding github.com/BurntSushi/toml v0.3.1 W0923 19:30:53.053] go: finding github.com/alecthomas/template v0.0.0-20160405071501-a0175ee3bccc W0923 19:30:53.236] go: finding google.golang.org/appengine v1.4.0 W0923 19:30:53.355] go: finding go.etcd.io/bbolt v1.3.2 W0923 19:30:53.627] go: finding golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 19:30:53.958] go: finding github.com/marstr/guid v1.1.0 W0923 19:30:53.993] go: finding github.com/apache/thrift v0.0.0-20180902110319-2566ecd5d999 W0923 19:30:54.168] go: finding github.com/mwitkow/go-conntrack v0.0.0-20161129095857-cc309e4a2223 W0923 19:30:54.460] go: finding gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 W0923 19:30:54.789] go: finding github.com/rogpeppe/go-charset v0.0.0-20180617210344-2471d30d28b4 W0923 19:30:54.821] go: finding github.com/go-stack/stack v1.8.0 W0923 19:30:54.829] go: finding github.com/soheilhy/cmux v0.1.4 W0923 19:30:54.873] go: finding github.com/sirupsen/logrus v1.2.0 W0923 19:30:55.039] go: finding github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 19:30:55.638] go: finding github.com/inconshreveable/mousetrap v1.0.0 W0923 19:30:55.970] go: finding github.com/Azure/azure-storage-file-go v0.5.0 W0923 19:30:55.995] go: finding github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 19:30:56.247] go: finding github.com/onsi/ginkgo v1.7.0 W0923 19:30:56.285] go: finding github.com/alecthomas/units v0.0.0-20151022065526-2efee857e7cf W0923 19:30:56.287] go: finding k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 19:30:56.703] go: finding github.com/GoogleCloudPlatform/k8s-cloud-provider v0.0.0-20181220005116-f8e995905100 W0923 19:30:57.635] go: finding github.com/julienschmidt/httprouter v1.2.0 W0923 19:30:57.693] go: finding github.com/elazarl/goproxy v0.0.0-20190410145444-c548f45dcf1d W0923 19:30:57.770] go: finding github.com/dnaeon/go-vcr v1.0.1 W0923 19:30:57.778] go: finding github.com/fsnotify/fsnotify v1.4.7 W0923 19:30:57.806] go: finding github.com/spf13/pflag v1.0.3 W0923 19:30:57.807] go: finding github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 19:30:57.919] go: finding golang.org/x/text v0.3.0 W0923 19:30:58.161] go: finding honnef.co/go/tools v0.0.0-20190102054323-c2f93a96b099 W0923 19:30:58.532] go: finding github.com/Azure/azure-pipeline-go v0.2.1 W0923 19:30:58.883] go: finding github.com/jmespath/go-jmespath v0.0.0-20180206201540-c2b33e8439af W0923 19:30:59.062] go: finding github.com/hpcloud/tail v1.0.0 W0923 19:30:59.195] go: finding sigs.k8s.io/yaml v1.1.0 W0923 19:30:59.197] go: finding gopkg.in/gcfg.v1 v1.2.0 W0923 19:30:59.289] go: finding github.com/kr/pretty v0.1.0 W0923 19:30:59.707] go: finding github.com/spf13/cobra v0.0.3 W0923 19:30:59.740] go: finding github.com/ghodss/yaml v1.0.0 W0923 19:30:59.823] go: finding gopkg.in/alecthomas/kingpin.v2 v2.2.6 W0923 19:31:00.534] go: finding github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 19:31:00.551] go: finding github.com/kr/pty v1.1.5 W0923 19:31:00.589] go: finding github.com/go-kit/kit v0.8.0 W0923 19:31:00.643] go: finding github.com/kisielk/errcheck v1.1.0 W0923 19:31:00.687] go: finding k8s.io/csi-translation-lib v0.0.0-20190313124639-7f5cabc6aac8 W0923 19:31:00.703] go: finding golang.org/x/exp v0.0.0-20190121172915-509febef88a4 W0923 19:31:00.927] go: finding github.com/ugorji/go v1.1.4 W0923 19:31:01.253] go: finding github.com/konsorten/go-windows-terminal-sequences v1.0.1 W0923 19:31:01.558] go: finding github.com/kr/text v0.1.0 W0923 19:31:01.574] go: finding gopkg.in/fsnotify.v1 v1.4.7 W0923 19:31:01.695] go: finding github.com/tmc/grpc-websocket-proxy v0.0.0-20190109142713-0ad062ec5ee5 W0923 19:31:01.884] go: finding github.com/kisielk/gotool v1.0.0 W0923 19:31:02.211] go: finding github.com/grpc-ecosystem/grpc-gateway v1.5.0 W0923 19:31:07.013] go: downloading github.com/prometheus/client_golang v0.9.2 W0923 19:31:07.014] go: downloading k8s.io/klog v0.2.0 W0923 19:31:07.014] go: downloading github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 19:31:07.015] go: downloading github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 19:31:07.018] go: downloading k8s.io/kubernetes v1.14.0 W0923 19:31:07.018] go: downloading k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 19:31:07.019] go: downloading google.golang.org/grpc v1.19.0 W0923 19:31:07.022] go: downloading github.com/Azure/azure-storage-file-go v0.5.0 W0923 19:31:07.064] go: downloading github.com/container-storage-interface/spec v1.1.0 W0923 19:31:07.104] go: extracting github.com/pborman/uuid v0.0.0-20180906182336-adf5a7427709 W0923 19:31:07.120] go: extracting k8s.io/klog v0.2.0 W0923 19:31:07.125] go: downloading golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 19:31:07.147] go: extracting github.com/prometheus/client_golang v0.9.2 W0923 19:31:07.167] go: downloading sigs.k8s.io/yaml v1.1.0 W0923 19:31:07.197] go: extracting github.com/Azure/azure-storage-file-go v0.5.0 W0923 19:31:07.213] go: downloading github.com/google/uuid v1.1.1 W0923 19:31:07.216] go: extracting github.com/container-storage-interface/spec v1.1.0 W0923 19:31:07.219] go: extracting sigs.k8s.io/yaml v1.1.0 W0923 19:31:07.222] go: downloading github.com/golang/protobuf v1.3.0 W0923 19:31:07.223] go: downloading github.com/Azure/azure-pipeline-go v0.2.1 W0923 19:31:07.273] go: extracting github.com/google/uuid v1.1.1 W0923 19:31:07.281] go: extracting github.com/Azure/azure-pipeline-go v0.2.1 W0923 19:31:07.354] go: extracting k8s.io/legacy-cloud-providers v0.0.0-20190624091455-d8621ceb9c64 W0923 19:31:07.386] go: downloading k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 19:31:07.546] go: extracting google.golang.org/grpc v1.19.0 W0923 19:31:07.559] go: extracting github.com/golang/protobuf v1.3.0 W0923 19:31:07.601] go: downloading github.com/Azure/go-autorest v11.5.1+incompatible W0923 19:31:07.635] go: downloading golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 19:31:07.635] go: downloading gopkg.in/yaml.v2 v2.2.2 W0923 19:31:07.637] go: downloading k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 19:31:07.744] go: extracting gopkg.in/yaml.v2 v2.2.2 W0923 19:31:07.752] go: downloading k8s.io/client-go v11.0.0+incompatible W0923 19:31:07.758] go: extracting github.com/Azure/go-autorest v11.5.1+incompatible W0923 19:31:07.778] go: downloading k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 19:31:08.005] go: extracting k8s.io/apimachinery v0.0.0-20190313205120-d7deff9243b1 W0923 19:31:08.042] go: extracting golang.org/x/net v0.0.0-20190301231341-16b79f2e4e95 W0923 19:31:08.090] go: downloading github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 19:31:08.262] go: extracting github.com/prometheus/procfs v0.0.0-20190306233201-d0f344d83b0c W0923 19:31:08.268] go: extracting k8s.io/cloud-provider v0.0.0-20190308083404-05ccf96446f8 W0923 19:31:08.273] go: downloading github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 19:31:08.345] go: extracting github.com/mattn/go-ieproxy v0.0.0-20190610004146-91bb50d98149 W0923 19:31:08.471] go: extracting golang.org/x/crypto v0.0.0-20190228161510-8dd112bcdc25 W0923 19:31:08.574] go: extracting k8s.io/client-go v11.0.0+incompatible W0923 19:31:08.582] go: extracting k8s.io/api v0.0.0-20190313235455-40a48860b5ab W0923 19:31:08.589] go: downloading github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 19:31:08.653] go: downloading github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 19:31:08.713] go: downloading github.com/gogo/protobuf v1.2.1 W0923 19:31:08.850] go: downloading github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 19:31:08.869] go: extracting github.com/prometheus/client_model v0.0.0-20190129233127-fd36f4220a90 W0923 19:31:08.875] go: downloading k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 19:31:08.920] go: extracting github.com/google/gofuzz v0.0.0-20170612174753-24818f796faf W0923 19:31:08.924] go: downloading gopkg.in/inf.v0 v0.9.1 W0923 19:31:08.962] go: extracting k8s.io/utils v0.0.0-20190221042446-c2654d5206da W0923 19:31:08.969] go: extracting gopkg.in/inf.v0 v0.9.1 W0923 19:31:08.973] go: downloading google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 19:31:08.983] go: downloading github.com/prometheus/common v0.2.0 W0923 19:31:09.066] go: extracting github.com/rubiojr/go-vhd v0.0.0-20160810183302-0bfd3b39853c W0923 19:31:09.089] go: downloading golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 19:31:09.141] go: extracting github.com/prometheus/common v0.2.0 W0923 19:31:09.148] go: extracting golang.org/x/time v0.0.0-20181108054448-85acf8d2951c W0923 19:31:09.154] go: downloading github.com/json-iterator/go v1.1.5 W0923 19:31:09.157] go: downloading golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 19:31:09.326] go: extracting github.com/json-iterator/go v1.1.5 W0923 19:31:09.349] go: downloading github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 19:31:09.422] go: extracting github.com/modern-go/reflect2 v0.0.0-20180701023420-4b7aa43c6742 W0923 19:31:09.431] go: downloading github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 19:31:09.495] go: extracting github.com/golang/groupcache v0.0.0-20190129154638-5b532d6fd5ef W0923 19:31:09.507] go: downloading k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 19:31:10.215] go: extracting golang.org/x/sys v0.0.0-20190308023053-584f3b12f43e W0923 19:31:10.398] go: extracting github.com/gogo/protobuf v1.2.1 W0923 19:31:10.409] go: extracting k8s.io/kube-openapi v0.0.0-20190306001800-15615b16d372 W0923 19:31:10.456] go: downloading go.opencensus.io v0.18.0 W0923 19:31:10.587] go: downloading contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 19:31:10.587] go: downloading github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 19:31:10.750] go: extracting contrib.go.opencensus.io/exporter/ocagent v0.2.0 W0923 19:31:10.797] go: downloading github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 19:31:10.822] go: extracting github.com/dgrijalva/jwt-go v3.2.0+incompatible W0923 19:31:10.832] go: downloading github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 19:31:10.882] go: extracting github.com/modern-go/concurrent v0.0.0-20180306012644-bacd9c7ef1dd W0923 19:31:10.887] go: downloading github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 19:31:10.901] go: extracting go.opencensus.io v0.18.0 W0923 19:31:10.917] go: extracting github.com/matttproud/golang_protobuf_extensions v1.0.1 W0923 19:31:10.937] go: downloading github.com/davecgh/go-spew v1.1.1 W0923 19:31:10.986] go: extracting github.com/census-instrumentation/opencensus-proto v0.1.0 W0923 19:31:11.013] go: downloading golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 19:31:11.036] go: extracting github.com/davecgh/go-spew v1.1.1 W0923 19:31:11.037] go: downloading google.golang.org/api v0.1.0 W0923 19:31:11.047] go: downloading github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 19:31:11.091] go: extracting github.com/beorn7/perks v0.0.0-20180321164747-3a771d992973 W0923 19:31:11.094] go: downloading github.com/googleapis/gnostic v0.2.0 W0923 19:31:11.121] go: extracting golang.org/x/oauth2 v0.0.0-20190226205417-e64efc72b421 W0923 19:31:11.131] go: downloading github.com/hashicorp/golang-lru v0.5.1 W0923 19:31:11.165] go: extracting github.com/hashicorp/golang-lru v0.5.1 W0923 19:31:11.169] go: downloading golang.org/x/text v0.3.0 W0923 19:31:11.627] go: extracting github.com/googleapis/gnostic v0.2.0 W0923 19:31:13.211] go: extracting google.golang.org/genproto v0.0.0-20190307195333-5fe7a883aa19 W0923 19:31:15.398] go: extracting golang.org/x/text v0.3.0 W0923 19:31:18.545] go: extracting google.golang.org/api v0.1.0 W0923 19:31:19.116] go: downloading golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 19:31:19.167] go: extracting github.com/Azure/azure-sdk-for-go v21.4.0+incompatible W0923 19:31:19.174] go: extracting golang.org/x/sync v0.0.0-20190227155943-e225da77a7e6 W0923 19:31:20.443] go: downloading github.com/marstr/guid v1.1.0 W0923 19:31:20.443] go: downloading github.com/satori/go.uuid v1.2.0 W0923 19:31:20.505] go: extracting github.com/marstr/guid v1.1.0 W0923 19:31:20.512] go: extracting github.com/satori/go.uuid v1.2.0 W0923 19:31:20.901] go: extracting k8s.io/kubernetes v1.14.0 W0923 19:31:22.412] go: downloading k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 19:31:22.413] go: downloading k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed W0923 19:31:23.122] go: extracting k8s.io/apiserver v0.0.0-20190313205120-8b27c41bdbb1 W0923 19:31:23.202] go: downloading github.com/spf13/pflag v1.0.3 W0923 19:31:23.278] go: extracting github.com/spf13/pflag v1.0.3 W0923 19:31:23.863] go: extracting k8s.io/apiextensions-apiserver v0.0.0-20190315093550-53c4693659ed I0923 19:31:56.057] go test -v -timeout=10m ./test/sanity W0923 19:31:56.157] go: downloading github.com/stretchr/testify v1.3.0 W0923 19:31:56.157] go: downloading github.com/pelletier/go-toml v1.4.0 W0923 19:31:57.605] go: extracting github.com/stretchr/testify v1.3.0 W0923 19:31:57.605] go: extracting github.com/pelletier/go-toml v1.4.0 W0923 19:31:57.615] go: downloading github.com/pmezard/go-difflib v1.0.0 W0923 19:31:57.977] go: extracting github.com/pmezard/go-difflib v1.0.0 I0923 19:32:04.116] === RUN TestSanity I0923 19:32:06.670] ./test/sanity/run-tests-all-clouds.sh: line 27: sudo: command not found I0923 19:32:06.670] Installing CSI sanity test binary... I0923 19:32:06.672] Cloning into 'csi-test'... I0923 19:32:08.780] Note: checking out '5b1e3786b7c8f7ca514b40e882a0b5dc36e4c842'. I0923 19:32:08.780] I0923 19:32:08.781] You are in 'detached HEAD' state. You can look around, make experimental I0923 19:32:08.781] changes and commit them, and you can discard any commits you make in this I0923 19:32:08.781] state without impacting any branches by performing another checkout. I0923 19:32:08.781] I0923 19:32:08.781] If you want to create a new branch to retain commits you create, you may I0923 19:32:08.781] do so (now or later) by using -b with the checkout command again. Example: I0923 19:32:08.782] I0923 19:32:08.782] git checkout -b I0923 19:32:08.782] I0923 19:32:08.904] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver/csi-test/cmd/csi-sanity /go/src/github.com/kubernetes-sigs/azurefile-csi-driver I0923 19:32:08.905] make[1]: Entering directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver/csi-test/cmd/csi-sanity' I0923 19:32:08.935] go test -ldflags "-w -X github.com/kubernetes-csi/csi-test/cmd/csi-sanity.VERSION=v1.1.0-HEAD -extldflags '-z relro -z now'" -c -o csi-sanity I0923 19:32:08.959] go: downloading github.com/kubernetes-csi/csi-test v1.1.0 I0923 19:32:11.939] go: extracting github.com/kubernetes-csi/csi-test v1.1.0 I0923 19:32:11.947] go: finding github.com/kubernetes-csi/csi-test v1.1.0 I0923 19:32:11.978] go: downloading github.com/onsi/ginkgo v1.7.0 I0923 19:32:11.980] go: downloading github.com/onsi/gomega v1.4.3 I0923 19:32:12.105] go: extracting github.com/onsi/gomega v1.4.3 I0923 19:32:12.150] go: extracting github.com/onsi/ginkgo v1.7.0 I0923 19:32:12.175] go: downloading github.com/hpcloud/tail v1.0.0 I0923 19:32:12.224] go: extracting github.com/hpcloud/tail v1.0.0 I0923 19:32:12.226] go: downloading gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 I0923 19:32:12.227] go: downloading gopkg.in/fsnotify.v1 v1.4.7 I0923 19:32:12.703] go: extracting gopkg.in/fsnotify.v1 v1.4.7 I0923 19:32:12.725] go: extracting gopkg.in/tomb.v1 v1.0.0-20141024135613-dd632973f1e7 I0923 19:32:16.253] make[1]: Leaving directory '/go/src/github.com/kubernetes-sigs/azurefile-csi-driver/csi-test/cmd/csi-sanity' I0923 19:32:16.254] /go/src/github.com/kubernetes-sigs/azurefile-csi-driver I0923 19:32:16.254] ./test/sanity/run-tests-all-clouds.sh: line 29: sudo: command not found I0923 19:33:03.462] --- FAIL: TestSanity (59.35s) I0923 19:33:03.462] sanity_test.go:47: Creating resource group azurefile-csi-driver-test-d2695120-de38-11e9-b6e2-6e12a412a673 in AzurePublicCloud I0923 19:33:03.462] sanity_test.go:77: Sanity test failed exit status 127 I0923 19:33:03.462] sanity_test.go:53: Deleting resource group azurefile-csi-driver-test-d2695120-de38-11e9-b6e2-6e12a412a673 I0923 19:33:03.463] FAIL I0923 19:33:03.463] FAIL github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity 59.353s I0923 19:33:03.494] Makefile:40: recipe for target 'sanity-test' failed W0923 19:33:03.499] make: *** [sanity-test] Error 1 W0923 19:33:03.499] Traceback (most recent call last): W0923 19:33:03.499] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in W0923 19:33:03.499] main(ARGS.env, ARGS.cmd + ARGS.args) W0923 19:33:03.499] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W0923 19:33:03.499] check(*cmd) W0923 19:33:03.499] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W0923 19:33:03.500] subprocess.check_call(cmd) W0923 19:33:03.500] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W0923 19:33:03.500] raise CalledProcessError(retcode, cmd) W0923 19:33:03.500] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2 E0923 19:33:03.500] Command failed I0923 19:33:03.500] process 456 exited with code 1 after 2.5m E0923 19:33:03.500] FAIL: pull-azurefile-csi-driver-sanity I0923 19:33:03.501] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0923 19:33:04.336] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I0923 19:33:04.370] process 11363 exited with code 0 after 0.0m I0923 19:33:04.370] Call: gcloud config get-value account I0923 19:33:04.613] process 11375 exited with code 0 after 0.0m I0923 19:33:04.613] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I0923 19:33:04.613] Upload result and artifacts... I0923 19:33:04.613] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176217218837385216 W0923 19:33:04.614] Missing local artifacts : /workspace/_artifacts W0923 19:33:04.614] metadata path /workspace/_artifacts/metadata.json does not exist W0923 19:33:04.614] metadata not found or invalid, init with empty metadata I0923 19:33:04.614] Call: git rev-parse HEAD I0923 19:33:04.616] process 11387 exited with code 0 after 0.0m I0923 19:33:04.617] Call: git rev-parse HEAD I0923 19:33:04.619] process 11388 exited with code 0 after 0.0m I0923 19:33:04.619] Call: gsutil stat gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/jobResultsCache.json W0923 19:33:05.613] No URLs matched: gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 19:33:05.613] process 11389 exited with code 1 after 0.0m I0923 19:33:05.614] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_hJZ5cS gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 19:33:06.937] process 11531 exited with code 0 after 0.0m I0923 19:33:06.938] Call: gsutil stat gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/jobResultsCache.json W0923 19:33:07.892] No URLs matched: gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 19:33:07.892] process 11709 exited with code 1 after 0.0m I0923 19:33:07.893] Call: gsutil -q -h Content-Type:application/json -h x-goog-if-generation-match:0 cp /tmp/gsutil_BIgKSb gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/jobResultsCache.json I0923 19:33:09.311] process 11851 exited with code 0 after 0.0m I0923 19:33:09.312] Call: gsutil -q -h Content-Type:application/json cp /tmp/gsutil_zz9FM0 gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/1176217218837385216/finished.json I0923 19:33:10.580] process 12029 exited with code 0 after 0.0m I0923 19:33:10.581] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_ZpMZZG gs://kubernetes-upstream/pr-logs/directory/pull-azurefile-csi-driver-sanity/latest-build.txt I0923 19:33:11.993] process 12207 exited with code 0 after 0.0m I0923 19:33:11.994] Call: gsutil -q -h Content-Type:text/plain -h 'Cache-Control:private, max-age=0, no-transform' cp /tmp/gsutil_i8UOxy gs://kubernetes-upstream/pr-logs/pull/kubernetes-sigs_azurefile-csi-driver/120/pull-azurefile-csi-driver-sanity/latest-build.txt I0923 19:33:13.296] process 12385 exited with code 0 after 0.0m