This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2019-10-08 18:13
Elapsed6m20s
Revision
Buildergke-prow-ssd-pool-1a225945-mhkp
Refs master:daea6716
120:a69da9e6
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/7df4c531-1a20-4cf6-8a28-2dbb8b4cc45f/targets/test'}}
pod36ce35fd-e9f7-11e9-88db-c69ba5cc8812
resultstorehttps://source.cloud.google.com/results/invocations/7df4c531-1a20-4cf6-8a28-2dbb8b4cc45f/targets/test
infra-commit2d58443f4
pod36ce35fd-e9f7-11e9-88db-c69ba5cc8812
reposigs.k8s.io/azurefile-csi-driver
repo-commitb3281e37de6301c6c4e68a4320ba077eacd5533d
repos{u'sigs.k8s.io/azurefile-csi-driver': u'master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:a69da9e617673da3ab7928bcd26a5625af88625e'}

No Test Failures!


Error lines from build-log.txt

... skipping 497 lines ...
I1008 18:17:44.364] I1008 18:17:44.328678    8397 utils.go:112] GRPC request: 
I1008 18:17:44.364] I1008 18:17:44.328822    8397 nodeserver.go:228] Using default NodeGetInfo
I1008 18:17:44.371] I1008 18:17:44.328878    8397 utils.go:118] GRPC response: node_id:"CSINode" 
I1008 18:17:44.371] •
I1008 18:17:44.373] ------------------------------
I1008 18:17:44.374] Node Service NodePublishVolume 
I1008 18:17:44.374]   should fail when no volume id is provided
I1008 18:17:44.374]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:158
I1008 18:17:44.374] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.376] STEP: creating mount and staging directories
I1008 18:17:44.376] I1008 18:17:44.351061    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.381] I1008 18:17:44.351097    8397 utils.go:112] GRPC request: 
I1008 18:17:44.381] I1008 18:17:44.351119    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:17:44.382] I1008 18:17:44.351237    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:17:44.382] I1008 18:17:44.356660    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 18:17:44.382] I1008 18:17:44.356793    8397 utils.go:112] GRPC request: 
I1008 18:17:44.382] I1008 18:17:44.356863    8397 nodeserver.go:219] Using default NodeGetCapabilities
I1008 18:17:44.382] I1008 18:17:44.356904    8397 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 18:17:44.383] I1008 18:17:44.358375    8397 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 18:17:44.383] I1008 18:17:44.358469    8397 utils.go:112] GRPC request: 
I1008 18:17:44.383] E1008 18:17:44.358500    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 18:17:44.383] •
I1008 18:17:44.383] ------------------------------
I1008 18:17:44.404] Node Service NodePublishVolume 
I1008 18:17:44.405]   should fail when no target path is provided
I1008 18:17:44.405]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:172
I1008 18:17:44.410] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.410] STEP: creating mount and staging directories
I1008 18:17:44.411] I1008 18:17:44.363027    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.411] I1008 18:17:44.363158    8397 utils.go:112] GRPC request: 
I1008 18:17:44.412] I1008 18:17:44.363228    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:17:44.412] I1008 18:17:44.363267    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:17:44.413] I1008 18:17:44.364157    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 18:17:44.413] I1008 18:17:44.364227    8397 utils.go:112] GRPC request: 
I1008 18:17:44.414] I1008 18:17:44.364257    8397 nodeserver.go:219] Using default NodeGetCapabilities
I1008 18:17:44.414] I1008 18:17:44.364319    8397 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 18:17:44.414] I1008 18:17:44.370988    8397 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 18:17:44.414] I1008 18:17:44.371013    8397 utils.go:112] GRPC request: volume_id:"id" 
I1008 18:17:44.415] E1008 18:17:44.371021    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 18:17:44.415] •
I1008 18:17:44.415] ------------------------------
I1008 18:17:44.415] Node Service NodePublishVolume 
I1008 18:17:44.415]   should fail when no volume capability is provided
I1008 18:17:44.416]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:187
I1008 18:17:44.418] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.418] STEP: creating mount and staging directories
I1008 18:17:44.419] I1008 18:17:44.376764    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.420] I1008 18:17:44.376790    8397 utils.go:112] GRPC request: 
I1008 18:17:44.421] I1008 18:17:44.376799    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:17:44.421] I1008 18:17:44.376805    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:17:44.422] I1008 18:17:44.377328    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 18:17:44.422] I1008 18:17:44.377344    8397 utils.go:112] GRPC request: 
I1008 18:17:44.423] I1008 18:17:44.377353    8397 nodeserver.go:219] Using default NodeGetCapabilities
I1008 18:17:44.424] I1008 18:17:44.377360    8397 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 18:17:44.425] I1008 18:17:44.377806    8397 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 18:17:44.427] I1008 18:17:44.377819    8397 utils.go:112] GRPC request: volume_id:"id" target_path:"/tmp/csi" 
I1008 18:17:44.427] E1008 18:17:44.377849    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 18:17:44.427] •
I1008 18:17:44.428] ------------------------------
I1008 18:17:44.429] Node Service NodeUnpublishVolume 
I1008 18:17:44.429]   should fail when no volume id is provided
I1008 18:17:44.430]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:205
I1008 18:17:44.431] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.431] STEP: creating mount and staging directories
I1008 18:17:44.432] I1008 18:17:44.378253    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.433] I1008 18:17:44.378266    8397 utils.go:112] GRPC request: 
I1008 18:17:44.434] I1008 18:17:44.378274    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:17:44.434] I1008 18:17:44.378280    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:17:44.435] I1008 18:17:44.380881    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 18:17:44.436] I1008 18:17:44.380899    8397 utils.go:112] GRPC request: 
I1008 18:17:44.437] I1008 18:17:44.380907    8397 nodeserver.go:219] Using default NodeGetCapabilities
I1008 18:17:44.438] I1008 18:17:44.380913    8397 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 18:17:44.438] I1008 18:17:44.385010    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 18:17:44.439] I1008 18:17:44.385030    8397 utils.go:112] GRPC request: 
I1008 18:17:44.439] E1008 18:17:44.385038    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 18:17:44.440] •
I1008 18:17:44.440] ------------------------------
I1008 18:17:44.441] Node Service NodeUnpublishVolume 
I1008 18:17:44.442]   should fail when no target path is provided
I1008 18:17:44.442]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:217
I1008 18:17:44.444] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.444] STEP: creating mount and staging directories
I1008 18:17:44.444] I1008 18:17:44.385953    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.446] I1008 18:17:44.385969    8397 utils.go:112] GRPC request: 
I1008 18:17:44.446] I1008 18:17:44.385977    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:17:44.452] I1008 18:17:44.385984    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:17:44.453] I1008 18:17:44.386450    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 18:17:44.453] I1008 18:17:44.386465    8397 utils.go:112] GRPC request: 
I1008 18:17:44.453] I1008 18:17:44.386473    8397 nodeserver.go:219] Using default NodeGetCapabilities
I1008 18:17:44.454] I1008 18:17:44.386480    8397 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 18:17:44.454] I1008 18:17:44.389959    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 18:17:44.455] I1008 18:17:44.389985    8397 utils.go:112] GRPC request: volume_id:"id" 
I1008 18:17:44.467] E1008 18:17:44.390051    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
I1008 18:17:44.475] •
I1008 18:17:44.487] ------------------------------
I1008 18:17:44.487] Node Service NodeStageVolume 
I1008 18:17:44.487]   should fail when no volume id is provided
I1008 18:17:44.489]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 18:17:44.490] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.490] STEP: creating mount and staging directories
I1008 18:17:44.490] I1008 18:17:44.391460    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.490] I1008 18:17:44.391495    8397 utils.go:112] GRPC request: 
I1008 18:17:44.491] I1008 18:17:44.391504    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 18:17:44.494] 
I1008 18:17:44.494] S [SKIPPING] in Spec Setup (BeforeEach) [0.013 seconds]
I1008 18:17:44.494] Node Service
I1008 18:17:44.494] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:17:44.495]   NodeStageVolume
I1008 18:17:44.496]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 18:17:44.496]     should fail when no volume id is provided [BeforeEach]
I1008 18:17:44.496]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 18:17:44.496] 
I1008 18:17:44.496]     NodeStageVolume not supported
I1008 18:17:44.497] 
I1008 18:17:44.498]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 18:17:44.498] ------------------------------
I1008 18:17:44.498] Node Service NodeStageVolume 
I1008 18:17:44.498]   should fail when no staging target path is provided
I1008 18:17:44.499]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 18:17:44.499] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.500] STEP: creating mount and staging directories
I1008 18:17:44.500] I1008 18:17:44.408093    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.500] I1008 18:17:44.408123    8397 utils.go:112] GRPC request: 
I1008 18:17:44.502] I1008 18:17:44.408133    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 18:17:44.505] 
I1008 18:17:44.505] S [SKIPPING] in Spec Setup (BeforeEach) [0.004 seconds]
I1008 18:17:44.505] Node Service
I1008 18:17:44.505] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:17:44.505]   NodeStageVolume
I1008 18:17:44.506]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 18:17:44.507]     should fail when no staging target path is provided [BeforeEach]
I1008 18:17:44.507]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 18:17:44.507] 
I1008 18:17:44.507]     NodeStageVolume not supported
I1008 18:17:44.507] 
I1008 18:17:44.507]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 18:17:44.508] ------------------------------
I1008 18:17:44.508] Node Service NodeStageVolume 
I1008 18:17:44.508]   should fail when no volume capability is provided
I1008 18:17:44.508]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 18:17:44.508] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.509] STEP: creating mount and staging directories
I1008 18:17:44.509] I1008 18:17:44.412759    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.509] I1008 18:17:44.412784    8397 utils.go:112] GRPC request: 
I1008 18:17:44.509] I1008 18:17:44.412793    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 18:17:44.511] 
I1008 18:17:44.511] S [SKIPPING] in Spec Setup (BeforeEach) [0.004 seconds]
I1008 18:17:44.511] Node Service
I1008 18:17:44.512] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:17:44.512]   NodeStageVolume
I1008 18:17:44.512]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 18:17:44.512]     should fail when no volume capability is provided [BeforeEach]
I1008 18:17:44.513]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 18:17:44.513] 
I1008 18:17:44.514]     NodeStageVolume not supported
I1008 18:17:44.514] 
I1008 18:17:44.514]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 18:17:44.515] ------------------------------
I1008 18:17:44.516] Node Service NodeUnstageVolume 
I1008 18:17:44.516]   should fail when no volume id is provided
I1008 18:17:44.516]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 18:17:44.517] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.519] STEP: creating mount and staging directories
I1008 18:17:44.520] I1008 18:17:44.417210    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.520] I1008 18:17:44.417228    8397 utils.go:112] GRPC request: 
I1008 18:17:44.520] I1008 18:17:44.417237    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 18:17:44.524] 
I1008 18:17:44.524] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1008 18:17:44.524] Node Service
I1008 18:17:44.524] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:17:44.524]   NodeUnstageVolume
I1008 18:17:44.525]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 18:17:44.526]     should fail when no volume id is provided [BeforeEach]
I1008 18:17:44.526]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 18:17:44.526] 
I1008 18:17:44.526]     NodeUnstageVolume not supported
I1008 18:17:44.537] 
I1008 18:17:44.537]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 18:17:44.538] ------------------------------
I1008 18:17:44.538] Node Service NodeUnstageVolume 
I1008 18:17:44.538]   should fail when no staging target path is provided
I1008 18:17:44.538]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 18:17:44.539] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:17:44.539] STEP: creating mount and staging directories
I1008 18:17:44.539] I1008 18:17:44.420462    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:17:44.540] I1008 18:17:44.420479    8397 utils.go:112] GRPC request: 
I1008 18:17:44.540] I1008 18:17:44.420488    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 18:17:44.542] 
I1008 18:17:44.542] S [SKIPPING] in Spec Setup (BeforeEach) [0.004 seconds]
I1008 18:17:44.542] Node Service
I1008 18:17:44.542] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:17:44.543]   NodeUnstageVolume
I1008 18:17:44.543]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 18:17:44.543]     should fail when no staging target path is provided [BeforeEach]
I1008 18:17:44.544]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 18:17:44.544] 
I1008 18:17:44.544]     NodeUnstageVolume not supported
I1008 18:17:44.544] 
I1008 18:17:44.545]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 18:17:44.545] ------------------------------
... skipping 32 lines ...
I1008 18:18:04.592] 
I1008 18:18:04.592] readonly false
I1008 18:18:04.592] volumeId #fecf6ff53e9f711e9becd42#sanity-node-full-765159fc-4c598b32
I1008 18:18:04.592] context map[]
I1008 18:18:04.592] mountflags []
I1008 18:18:04.660] I1008 18:18:04.659183    8397 mount_linux.go:138] Mounting cmd (mount) with arguments ([-t cifs -o username=fecf6ff53e9f711e9becd42,password=pDe3mmBOUQ9PafxNMS5XJgYpeNkziVBiViumk+vIx/XHtGGShgH/oC2tzNFpiccrvluOdXOoQDpeYpcR/P7ZoA==,file_mode=0777,dir_mode=0777,vers=3.0 //fecf6ff53e9f711e9becd42.file.core.windows.net/sanity-node-full-765159fc-4c598b32 /tmp/csi])
I1008 18:18:04.667] E1008 18:18:04.665785    8397 mount_linux.go:143] Mount failed: exit status 32
I1008 18:18:04.668] Mounting command: mount
I1008 18:18:04.669] Mounting arguments: -t cifs -o username=fecf6ff53e9f711e9becd42,password=pDe3mmBOUQ9PafxNMS5XJgYpeNkziVBiViumk+vIx/XHtGGShgH/oC2tzNFpiccrvluOdXOoQDpeYpcR/P7ZoA==,file_mode=0777,dir_mode=0777,vers=3.0 //fecf6ff53e9f711e9becd42.file.core.windows.net/sanity-node-full-765159fc-4c598b32 /tmp/csi
I1008 18:18:04.670] Output: mount: unknown filesystem type 'cifs'
I1008 18:18:04.670] 
I1008 18:18:04.670] E1008 18:18:04.665954    8397 utils.go:116] GRPC error: mount failed: exit status 32
I1008 18:18:04.670] Mounting command: mount
I1008 18:18:04.671] Mounting arguments: -t cifs -o username=fecf6ff53e9f711e9becd42,password=pDe3mmBOUQ9PafxNMS5XJgYpeNkziVBiViumk+vIx/XHtGGShgH/oC2tzNFpiccrvluOdXOoQDpeYpcR/P7ZoA==,file_mode=0777,dir_mode=0777,vers=3.0 //fecf6ff53e9f711e9becd42.file.core.windows.net/sanity-node-full-765159fc-4c598b32 /tmp/csi
I1008 18:18:04.672] Output: mount: unknown filesystem type 'cifs'
I1008 18:18:04.672] 
I1008 18:18:04.672] cleanup: deleting sanity-node-full-765159FC-4C598B32 = #fecf6ff53e9f711e9becd42#sanity-node-full-765159fc-4c598b32
I1008 18:18:04.672] I1008 18:18:04.667735    8397 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 18:18:04.674] I1008 18:18:04.667804    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-node-full-765159fc-4c598b32" target_path:"/tmp/csi" 
I1008 18:18:04.674] I1008 18:18:04.667832    8397 mount_linux.go:203] Unmounting /tmp/csi
I1008 18:18:04.682] E1008 18:18:04.679862    8397 utils.go:116] GRPC error: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 18:18:04.684] Unmounting arguments: /tmp/csi
I1008 18:18:04.685] Output: umount: /tmp/csi: mountpoint not found
I1008 18:18:04.685] 
I1008 18:18:04.685] cleanup: warning: NodeUnpublishVolume: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 18:18:04.685] Unmounting arguments: /tmp/csi
I1008 18:18:04.687] Output: umount: /tmp/csi: mountpoint not found
I1008 18:18:04.688] 
I1008 18:18:04.688] I1008 18:18:04.681424    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 18:18:04.688] I1008 18:18:04.681443    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-node-full-765159fc-4c598b32" 
I1008 18:18:05.111] I1008 18:18:05.108164    8397 controllerserver.go:149] azure file(sanity-node-full-765159fc-4c598b32) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#sanity-node-full-765159fc-4c598b32) is deleted successfully
... skipping 2 lines ...
I1008 18:18:05.112] • Failure [20.684 seconds]
I1008 18:18:05.112] Node Service
I1008 18:18:05.112] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:05.113]   should work [It]
I1008 18:18:05.113]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:353
I1008 18:18:05.113] 
I1008 18:18:05.113]   Expected error:
I1008 18:18:05.113]       <*status.statusError | 0xc0000dad70>: {
I1008 18:18:05.113]           Code: 2,
I1008 18:18:05.114]           Message: "mount failed: exit status 32\nMounting command: mount\nMounting arguments: -t cifs -o username=fecf6ff53e9f711e9becd42,password=pDe3mmBOUQ9PafxNMS5XJgYpeNkziVBiViumk+vIx/XHtGGShgH/oC2tzNFpiccrvluOdXOoQDpeYpcR/P7ZoA==,file_mode=0777,dir_mode=0777,vers=3.0 //fecf6ff53e9f711e9becd42.file.core.windows.net/sanity-node-full-765159fc-4c598b32 /tmp/csi\nOutput: mount: unknown filesystem type 'cifs'\n\n",
I1008 18:18:05.114]           Details: nil,
I1008 18:18:05.115]           XXX_NoUnkeyedLiteral: {},
I1008 18:18:05.115]           XXX_unrecognized: nil,
I1008 18:18:05.115]           XXX_sizecache: 0,
I1008 18:18:05.115]       }
I1008 18:18:05.115]       rpc error: code = Unknown desc = mount failed: exit status 32
I1008 18:18:05.115]       Mounting command: mount
I1008 18:18:05.116]       Mounting arguments: -t cifs -o username=fecf6ff53e9f711e9becd42,password=pDe3mmBOUQ9PafxNMS5XJgYpeNkziVBiViumk+vIx/XHtGGShgH/oC2tzNFpiccrvluOdXOoQDpeYpcR/P7ZoA==,file_mode=0777,dir_mode=0777,vers=3.0 //fecf6ff53e9f711e9becd42.file.core.windows.net/sanity-node-full-765159fc-4c598b32 /tmp/csi
I1008 18:18:05.116]       Output: mount: unknown filesystem type 'cifs'
I1008 18:18:05.116]       
I1008 18:18:05.116]       
I1008 18:18:05.116]   not to have occurred
I1008 18:18:05.116] 
I1008 18:18:05.116]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
I1008 18:18:05.117] ------------------------------
I1008 18:18:05.117] DeleteSnapshot [Controller Server] 
I1008 18:18:05.117]   should fail when no snapshot id is provided
I1008 18:18:05.117]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1439
I1008 18:18:05.117] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:05.117] STEP: creating mount and staging directories
I1008 18:18:05.118] I1008 18:18:05.109578    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:05.118] I1008 18:18:05.109645    8397 utils.go:112] GRPC request: 
I1008 18:18:05.118] I1008 18:18:05.109674    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:05.118] I1008 18:18:05.109735    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:05.119] I1008 18:18:05.110874    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 18:18:05.119] I1008 18:18:05.110951    8397 utils.go:112] GRPC request: 
I1008 18:18:05.119] E1008 18:18:05.110977    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
I1008 18:18:05.119] •
I1008 18:18:05.119] ------------------------------
I1008 18:18:05.119] DeleteSnapshot [Controller Server] 
I1008 18:18:05.120]   should succeed when an invalid snapshot id is used
I1008 18:18:05.120]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1455
I1008 18:18:05.120] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:05.120] STEP: creating mount and staging directories
I1008 18:18:05.120] I1008 18:18:05.111836    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:05.120] I1008 18:18:05.111856    8397 utils.go:112] GRPC request: 
I1008 18:18:05.121] I1008 18:18:05.111865    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:05.121] I1008 18:18:05.111872    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:05.121] I1008 18:18:05.112391    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 18:18:05.121] I1008 18:18:05.112410    8397 utils.go:112] GRPC request: snapshot_id:"reallyfakesnapshotid" 
I1008 18:18:05.122] E1008 18:18:05.112427    8397 controllerserver.go:342] getFileShareInfo(reallyfakesnapshotid) failed with error: error parsing volume id: "reallyfakesnapshotid", should at least contain two #
I1008 18:18:05.122] I1008 18:18:05.112439    8397 controllerserver.go:295] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I1008 18:18:05.122] I1008 18:18:05.112447    8397 utils.go:118] GRPC response: 
I1008 18:18:05.122] •
I1008 18:18:05.123] ------------------------------
I1008 18:18:05.123] DeleteSnapshot [Controller Server] 
I1008 18:18:05.123]   should return appropriate values (no optional values added)
I1008 18:18:05.123]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1462
... skipping 202 lines ...
I1008 18:18:06.236] I1008 18:18:06.199253    8397 identityserver.go:32] Using default GetPluginInfo
I1008 18:18:06.236] I1008 18:18:06.199259    8397 utils.go:118] GRPC response: name:"file.csi.azure.com" vendor_version:"e2e-b3281e37de6301c6c4e68a4320ba077eacd5533d" 
I1008 18:18:06.236] STEP: verifying name size and characters
I1008 18:18:06.236] •
I1008 18:18:06.237] ------------------------------
I1008 18:18:06.237] CreateSnapshot [Controller Server] 
I1008 18:18:06.237]   should fail when no name is provided
I1008 18:18:06.237]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1502
I1008 18:18:06.237] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:06.238] STEP: creating mount and staging directories
I1008 18:18:06.238] I1008 18:18:06.200272    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:06.238] I1008 18:18:06.200287    8397 utils.go:112] GRPC request: 
I1008 18:18:06.238] I1008 18:18:06.200294    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:06.238] I1008 18:18:06.200301    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:06.239] I1008 18:18:06.200815    8397 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 18:18:06.239] I1008 18:18:06.200832    8397 utils.go:112] GRPC request: source_volume_id:"testId" 
I1008 18:18:06.239] I1008 18:18:06.200842    8397 controllerserver.go:214] CreateSnapshot called with request {testId  map[] map[] {} [] 0}
I1008 18:18:06.240] E1008 18:18:06.200862    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
I1008 18:18:06.240] •
I1008 18:18:06.240] ------------------------------
I1008 18:18:06.240] CreateSnapshot [Controller Server] 
I1008 18:18:06.240]   should fail when no source volume id is provided
I1008 18:18:06.240]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1519
I1008 18:18:06.241] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:06.241] STEP: creating mount and staging directories
I1008 18:18:06.241] I1008 18:18:06.201347    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:06.241] I1008 18:18:06.201363    8397 utils.go:112] GRPC request: 
I1008 18:18:06.241] I1008 18:18:06.201369    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:06.242] I1008 18:18:06.201375    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:06.242] I1008 18:18:06.201854    8397 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 18:18:06.242] I1008 18:18:06.201878    8397 utils.go:112] GRPC request: name:"name" 
I1008 18:18:06.242] I1008 18:18:06.201887    8397 controllerserver.go:214] CreateSnapshot called with request { name map[] map[] {} [] 0}
I1008 18:18:06.243] E1008 18:18:06.201901    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
I1008 18:18:06.243] •
I1008 18:18:06.243] ------------------------------
I1008 18:18:06.243] CreateSnapshot [Controller Server] 
I1008 18:18:06.243]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1008 18:18:06.244]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1536
I1008 18:18:06.244] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:06.244] STEP: creating mount and staging directories
I1008 18:18:06.244] I1008 18:18:06.202457    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:06.244] I1008 18:18:06.202470    8397 utils.go:112] GRPC request: 
I1008 18:18:06.245] I1008 18:18:06.202477    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 28 lines ...
I1008 18:18:06.980] I1008 18:18:06.978947    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#createsnapshot-volume-1" 
I1008 18:18:07.096] I1008 18:18:07.093902    8397 controllerserver.go:149] azure file(createsnapshot-volume-1) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#createsnapshot-volume-1) is deleted successfully
I1008 18:18:07.097] I1008 18:18:07.093935    8397 utils.go:118] GRPC response: 
I1008 18:18:07.097] •
I1008 18:18:07.097] ------------------------------
I1008 18:18:07.097] CreateSnapshot [Controller Server] 
I1008 18:18:07.097]   should fail when requesting to create a snapshot with already existing name and different SourceVolumeId.
I1008 18:18:07.098]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1566
I1008 18:18:07.098] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:07.098] STEP: creating mount and staging directories
I1008 18:18:07.098] I1008 18:18:07.094765    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:07.098] I1008 18:18:07.094787    8397 utils.go:112] GRPC request: 
I1008 18:18:07.099] I1008 18:18:07.094796    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 20 lines ...
I1008 18:18:07.972] I1008 18:18:07.969927    8397 controllerserver.go:108] create file share createsnapshot-volume-3 on storage account fecf6ff53e9f711e9becd42 successfully
I1008 18:18:08.007] I1008 18:18:08.006059    8397 utils.go:118] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"#fecf6ff53e9f711e9becd42#createsnapshot-volume-3" > 
I1008 18:18:08.008] STEP: creating a snapshot with the same name but different volume source id
I1008 18:18:08.008] I1008 18:18:08.006717    8397 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 18:18:08.009] I1008 18:18:08.006740    8397 utils.go:112] GRPC request: source_volume_id:"#fecf6ff53e9f711e9becd42#createsnapshot-volume-3" name:"CreateSnapshot-snapshot-2" 
I1008 18:18:08.011] I1008 18:18:08.006752    8397 controllerserver.go:214] CreateSnapshot called with request {#fecf6ff53e9f711e9becd42#createsnapshot-volume-3 CreateSnapshot-snapshot-2 map[] map[] {} [] 0}
I1008 18:18:08.134] E1008 18:18:08.132305    8397 utils.go:116] GRPC error: rpc error: code = AlreadyExists desc = snapshot(CreateSnapshot-snapshot-2) already exists, while the current file share name(createsnapshot-volume-2) does not equal to createsnapshot-volume-3, SourceVolumeId(#fecf6ff53e9f711e9becd42#createsnapshot-volume-3)
I1008 18:18:08.137] STEP: cleaning up deleting the snapshot
I1008 18:18:08.137] I1008 18:18:08.133177    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 18:18:08.137] I1008 18:18:08.133199    8397 utils.go:112] GRPC request: snapshot_id:"#fecf6ff53e9f711e9becd42#createsnapshot-volume-2#2019-10-08T18:18:07.0000000Z" 
I1008 18:18:08.269] I1008 18:18:08.267935    8397 controllerserver.go:313] delete snapshot(2019-10-08T18:18:07.0000000Z) successfully
I1008 18:18:08.277] I1008 18:18:08.267971    8397 utils.go:118] GRPC response: 
I1008 18:18:08.278] STEP: cleaning up deleting the volume
I1008 18:18:08.279] I1008 18:18:08.268792    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 18:18:08.280] I1008 18:18:08.268814    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#createsnapshot-volume-2" 
I1008 18:18:08.413] I1008 18:18:08.410902    8397 controllerserver.go:149] azure file(createsnapshot-volume-2) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#createsnapshot-volume-2) is deleted successfully
I1008 18:18:08.413] I1008 18:18:08.410935    8397 utils.go:118] GRPC response: 
I1008 18:18:08.414] •
I1008 18:18:08.414] ------------------------------
I1008 18:18:08.415] CreateSnapshot [Controller Server] 
I1008 18:18:08.416]   should not fail when creating snapshot with maximum-length name
I1008 18:18:08.416]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1601
I1008 18:18:08.417] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:08.418] STEP: creating mount and staging directories
I1008 18:18:08.419] I1008 18:18:08.414942    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:08.420] I1008 18:18:08.415060    8397 utils.go:112] GRPC request: 
I1008 18:18:08.421] I1008 18:18:08.415133    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 85 lines ...
I1008 18:18:09.447] 
I1008 18:18:09.447]     ListVolumes not supported
I1008 18:18:09.447] 
I1008 18:18:09.447]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:157
I1008 18:18:09.447] ------------------------------
I1008 18:18:09.448] Controller Service CreateVolume 
I1008 18:18:09.448]   should fail when no name is provided
I1008 18:18:09.448]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:186
I1008 18:18:09.449] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:09.449] STEP: creating mount and staging directories
I1008 18:18:09.449] I1008 18:18:09.427307    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:09.449] I1008 18:18:09.427328    8397 utils.go:112] GRPC request: 
I1008 18:18:09.450] I1008 18:18:09.427337    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:09.450] I1008 18:18:09.427343    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:09.450] I1008 18:18:09.427911    8397 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 18:18:09.450] I1008 18:18:09.427929    8397 utils.go:112] GRPC request: 
I1008 18:18:09.451] E1008 18:18:09.427937    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
I1008 18:18:09.451] •
I1008 18:18:09.451] ------------------------------
I1008 18:18:09.451] Controller Service CreateVolume 
I1008 18:18:09.451]   should fail when no volume capabilities are provided
I1008 18:18:09.452]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:202
I1008 18:18:09.452] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:09.452] STEP: creating mount and staging directories
I1008 18:18:09.452] I1008 18:18:09.428458    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:09.452] I1008 18:18:09.428472    8397 utils.go:112] GRPC request: 
I1008 18:18:09.453] I1008 18:18:09.428479    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:09.453] I1008 18:18:09.428486    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:09.453] I1008 18:18:09.429024    8397 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 18:18:09.453] I1008 18:18:09.429039    8397 utils.go:112] GRPC request: name:"sanity-controller-create-no-volume-capabilities-765159FC-4C598B32" 
I1008 18:18:09.454] E1008 18:18:09.429046    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities must be provided
I1008 18:18:09.454] •
I1008 18:18:09.454] ------------------------------
I1008 18:18:09.454] Controller Service CreateVolume 
I1008 18:18:09.454]   should return appropriate values SingleNodeWriter NoCapacity Type:Mount
I1008 18:18:09.455]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:220
I1008 18:18:09.455] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 40 lines ...
I1008 18:18:10.004] I1008 18:18:10.002323    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-controller-create-single-with-capacity-765159fc-4c598b32" 
I1008 18:18:10.151] I1008 18:18:10.148938    8397 controllerserver.go:149] azure file(sanity-controller-create-single-with-capacity-765159fc-4c598b32) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#sanity-controller-create-single-with-capacity-765159fc-4c598b32) is deleted successfully
I1008 18:18:10.151] I1008 18:18:10.148983    8397 utils.go:118] GRPC response: 
I1008 18:18:10.152] •
I1008 18:18:10.152] ------------------------------
I1008 18:18:10.152] Controller Service CreateVolume 
I1008 18:18:10.152]   should not fail when requesting to create a volume with already existing name and same capacity.
I1008 18:18:10.152]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:311
I1008 18:18:10.154] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:10.155] STEP: creating mount and staging directories
I1008 18:18:10.155] I1008 18:18:10.149997    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:10.155] I1008 18:18:10.150020    8397 utils.go:112] GRPC request: 
I1008 18:18:10.155] I1008 18:18:10.150028    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 19 lines ...
I1008 18:18:10.704] I1008 18:18:10.702908    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-controller-create-twice-765159fc-4c598b32" 
I1008 18:18:10.831] I1008 18:18:10.828925    8397 controllerserver.go:149] azure file(sanity-controller-create-twice-765159fc-4c598b32) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#sanity-controller-create-twice-765159fc-4c598b32) is deleted successfully
I1008 18:18:10.831] I1008 18:18:10.828956    8397 utils.go:118] GRPC response: 
I1008 18:18:10.832] •
I1008 18:18:10.832] ------------------------------
I1008 18:18:10.832] Controller Service CreateVolume 
I1008 18:18:10.832]   should fail when requesting to create a volume with already existing name and different capacity.
I1008 18:18:10.832]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:385
I1008 18:18:10.833] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:10.833] STEP: creating mount and staging directories
I1008 18:18:10.833] I1008 18:18:10.829663    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:10.833] I1008 18:18:10.829683    8397 utils.go:112] GRPC request: 
I1008 18:18:10.836] I1008 18:18:10.829708    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 10 lines ...
I1008 18:18:11.098] I1008 18:18:11.097191    8397 utils.go:112] GRPC request: name:"sanity-controller-create-twice-different-765159FC-4C598B32" capacity_range:<required_bytes:21474836480 limit_bytes:21474836480 > volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 18:18:11.098] I1008 18:18:11.097212    8397 controllerserver.go:95] begin to create file share(sanity-controller-create-twice-different-765159fc-4c598b32) on account() type() rg() location() size(20)
I1008 18:18:11.170] I1008 18:18:11.169565    8397 azure_storageaccount.go:103] found a matching account fecf6ff53e9f711e9becd42 type Standard_LRS location eastus2
I1008 18:18:11.316] I1008 18:18:11.315918    8397 azure_file.go:68] file share(sanity-controller-create-twice-different-765159fc-4c598b32) under account(fecf6ff53e9f711e9becd42) already exists
I1008 18:18:11.317] I1008 18:18:11.315959    8397 azure_storage.go:49] created share sanity-controller-create-twice-different-765159fc-4c598b32 in account fecf6ff53e9f711e9becd42
I1008 18:18:11.317] I1008 18:18:11.315976    8397 controllerserver.go:108] create file share sanity-controller-create-twice-different-765159fc-4c598b32 on storage account fecf6ff53e9f711e9becd42 successfully
I1008 18:18:11.353] E1008 18:18:11.351675    8397 utils.go:116] GRPC error: rpc error: code = AlreadyExists desc = the request volume already exists, but its capacity(10) is different from (20)
I1008 18:18:11.353] STEP: cleaning up deleting the volume
I1008 18:18:11.354] I1008 18:18:11.352519    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 18:18:11.354] I1008 18:18:11.352538    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-controller-create-twice-different-765159fc-4c598b32" 
I1008 18:18:11.539] I1008 18:18:11.537140    8397 controllerserver.go:149] azure file(sanity-controller-create-twice-different-765159fc-4c598b32) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#sanity-controller-create-twice-different-765159fc-4c598b32) is deleted successfully
I1008 18:18:11.540] I1008 18:18:11.537174    8397 utils.go:118] GRPC response: 
I1008 18:18:11.540] •
I1008 18:18:11.540] ------------------------------
I1008 18:18:11.544] Controller Service CreateVolume 
I1008 18:18:11.545]   should not fail when creating volume with maximum-length name
I1008 18:18:11.545]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:460
I1008 18:18:11.545] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:11.545] STEP: creating mount and staging directories
I1008 18:18:11.545] I1008 18:18:11.538063    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:11.546] I1008 18:18:11.538086    8397 utils.go:112] GRPC request: 
I1008 18:18:11.546] I1008 18:18:11.538095    8397 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 11 lines ...
I1008 18:18:11.770] I1008 18:18:11.769348    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa" 
I1008 18:18:11.954] I1008 18:18:11.947922    8397 controllerserver.go:149] azure file(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) is deleted successfully
I1008 18:18:11.955] I1008 18:18:11.947955    8397 utils.go:118] GRPC response: 
I1008 18:18:11.955] •
I1008 18:18:11.955] ------------------------------
I1008 18:18:11.955] Controller Service DeleteVolume 
I1008 18:18:11.955]   should fail when no volume id is provided
I1008 18:18:11.955]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:519
I1008 18:18:11.956] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:11.956] STEP: creating mount and staging directories
I1008 18:18:11.956] I1008 18:18:11.948825    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:11.956] I1008 18:18:11.948850    8397 utils.go:112] GRPC request: 
I1008 18:18:11.957] I1008 18:18:11.948859    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:11.957] I1008 18:18:11.948866    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:11.957] I1008 18:18:11.949447    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 18:18:11.959] I1008 18:18:11.949466    8397 utils.go:112] GRPC request: 
I1008 18:18:11.960] E1008 18:18:11.949473    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 18:18:11.960] •
I1008 18:18:11.960] ------------------------------
I1008 18:18:11.960] Controller Service DeleteVolume 
I1008 18:18:11.960]   should succeed when an invalid volume id is used
I1008 18:18:11.961]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:534
I1008 18:18:11.961] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:11.961] STEP: creating mount and staging directories
I1008 18:18:11.961] I1008 18:18:11.949967    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:11.961] I1008 18:18:11.949981    8397 utils.go:112] GRPC request: 
I1008 18:18:11.962] I1008 18:18:11.949988    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:11.962] I1008 18:18:11.949995    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:11.962] I1008 18:18:11.950430    8397 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 18:18:11.968] I1008 18:18:11.950445    8397 utils.go:112] GRPC request: volume_id:"reallyfakevolumeid" 
I1008 18:18:11.969] E1008 18:18:11.950462    8397 controllerserver.go:342] getFileShareInfo(reallyfakevolumeid) failed with error: error parsing volume id: "reallyfakevolumeid", should at least contain two #
I1008 18:18:11.969] I1008 18:18:11.950473    8397 controllerserver.go:137] failed to get share url with (reallyfakevolumeid): error parsing volume id: "reallyfakevolumeid", should at least contain two #, returning with success
I1008 18:18:11.970] I1008 18:18:11.950480    8397 utils.go:118] GRPC response: 
I1008 18:18:11.970] •
I1008 18:18:11.970] ------------------------------
I1008 18:18:11.970] Controller Service DeleteVolume 
I1008 18:18:11.970]   should return appropriate values (no optional values added)
I1008 18:18:11.970]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:546
... skipping 17 lines ...
I1008 18:18:12.197] I1008 18:18:12.195988    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-controller-create-appropriate-765159fc-4c598b32" 
I1008 18:18:12.320] I1008 18:18:12.314897    8397 controllerserver.go:149] azure file(sanity-controller-create-appropriate-765159fc-4c598b32) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#sanity-controller-create-appropriate-765159fc-4c598b32) is deleted successfully
I1008 18:18:12.321] I1008 18:18:12.314928    8397 utils.go:118] GRPC response: 
I1008 18:18:12.321] •
I1008 18:18:12.321] ------------------------------
I1008 18:18:12.321] Controller Service ValidateVolumeCapabilities 
I1008 18:18:12.328]   should fail when no volume id is provided
I1008 18:18:12.328]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:592
I1008 18:18:12.328] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.328] STEP: creating mount and staging directories
I1008 18:18:12.329] I1008 18:18:12.318076    8397 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 18:18:12.329] I1008 18:18:12.318107    8397 utils.go:112] GRPC request: 
I1008 18:18:12.329] E1008 18:18:12.318116    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 18:18:12.329] •
I1008 18:18:12.329] ------------------------------
I1008 18:18:12.330] Controller Service ValidateVolumeCapabilities 
I1008 18:18:12.330]   should fail when no volume capabilities are provided
I1008 18:18:12.330]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:604
I1008 18:18:12.330] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.330] STEP: creating mount and staging directories
I1008 18:18:12.331] I1008 18:18:12.319002    8397 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 18:18:12.331] I1008 18:18:12.319019    8397 utils.go:112] GRPC request: volume_id:"id" 
I1008 18:18:12.331] E1008 18:18:12.319027    8397 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities missing in request
I1008 18:18:12.331] •
I1008 18:18:12.337] ------------------------------
I1008 18:18:12.338] Controller Service ValidateVolumeCapabilities 
I1008 18:18:12.338]   should return appropriate values (no optional values added)
I1008 18:18:12.338]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:618
I1008 18:18:12.338] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 16 lines ...
I1008 18:18:12.716] I1008 18:18:12.714034    8397 utils.go:112] GRPC request: volume_id:"#fecf6ff53e9f711e9becd42#sanity-controller-validate-765159fc-4c598b32" 
I1008 18:18:12.844] I1008 18:18:12.842910    8397 controllerserver.go:149] azure file(sanity-controller-validate-765159fc-4c598b32) under rg() account(fecf6ff53e9f711e9becd42) volumeID(#fecf6ff53e9f711e9becd42#sanity-controller-validate-765159fc-4c598b32) is deleted successfully
I1008 18:18:12.860] I1008 18:18:12.842939    8397 utils.go:118] GRPC response: 
I1008 18:18:12.860] •
I1008 18:18:12.860] ------------------------------
I1008 18:18:12.860] Controller Service ValidateVolumeCapabilities 
I1008 18:18:12.860]   should fail when the requested volume does not exist
I1008 18:18:12.861]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:687
I1008 18:18:12.861] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.861] STEP: creating mount and staging directories
I1008 18:18:12.861] I1008 18:18:12.843897    8397 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 18:18:12.861] I1008 18:18:12.843921    8397 utils.go:112] GRPC request: volume_id:"some-vol-id" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 18:18:12.862] E1008 18:18:12.843936    8397 utils.go:116] GRPC error: rpc error: code = NotFound desc = error getting volume(some-vol-id) info: error parsing volume id: "some-vol-id", should at least contain two #
I1008 18:18:12.862] •
I1008 18:18:12.862] ------------------------------
I1008 18:18:12.862] Controller Service ControllerPublishVolume 
I1008 18:18:12.862]   should fail when no volume id is provided
I1008 18:18:12.862]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 18:18:12.862] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.862] STEP: creating mount and staging directories
I1008 18:18:12.863] I1008 18:18:12.844469    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.863] I1008 18:18:12.844484    8397 utils.go:112] GRPC request: 
I1008 18:18:12.863] I1008 18:18:12.844492    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.863] I1008 18:18:12.844499    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.863] 
I1008 18:18:12.863] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 18:18:12.864] Controller Service
I1008 18:18:12.864] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.864]   ControllerPublishVolume
I1008 18:18:12.864]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 18:18:12.864]     should fail when no volume id is provided [BeforeEach]
I1008 18:18:12.864]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 18:18:12.864] 
I1008 18:18:12.864]     ControllerPublishVolume not supported
I1008 18:18:12.865] 
I1008 18:18:12.865]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.865] ------------------------------
I1008 18:18:12.865] Controller Service ControllerPublishVolume 
I1008 18:18:12.865]   should fail when no node id is provided
I1008 18:18:12.865]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 18:18:12.865] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.865] STEP: creating mount and staging directories
I1008 18:18:12.866] I1008 18:18:12.845547    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.866] I1008 18:18:12.845565    8397 utils.go:112] GRPC request: 
I1008 18:18:12.866] I1008 18:18:12.845573    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.866] I1008 18:18:12.845588    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.867] 
I1008 18:18:12.867] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 18:18:12.867] Controller Service
I1008 18:18:12.867] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.867]   ControllerPublishVolume
I1008 18:18:12.868]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 18:18:12.868]     should fail when no node id is provided [BeforeEach]
I1008 18:18:12.868]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 18:18:12.868] 
I1008 18:18:12.869]     ControllerPublishVolume not supported
I1008 18:18:12.869] 
I1008 18:18:12.869]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.869] ------------------------------
I1008 18:18:12.869] Controller Service ControllerPublishVolume 
I1008 18:18:12.869]   should fail when no volume capability is provided
I1008 18:18:12.870]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 18:18:12.870] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.870] STEP: creating mount and staging directories
I1008 18:18:12.870] I1008 18:18:12.847969    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.871] I1008 18:18:12.847987    8397 utils.go:112] GRPC request: 
I1008 18:18:12.871] I1008 18:18:12.847994    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.871] I1008 18:18:12.848001    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.871] 
I1008 18:18:12.871] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 18:18:12.872] Controller Service
I1008 18:18:12.872] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.872]   ControllerPublishVolume
I1008 18:18:12.872]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 18:18:12.872]     should fail when no volume capability is provided [BeforeEach]
I1008 18:18:12.873]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 18:18:12.873] 
I1008 18:18:12.873]     ControllerPublishVolume not supported
I1008 18:18:12.873] 
I1008 18:18:12.873]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.873] ------------------------------
... skipping 17 lines ...
I1008 18:18:12.877] 
I1008 18:18:12.877]     ControllerPublishVolume not supported
I1008 18:18:12.877] 
I1008 18:18:12.877]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.878] ------------------------------
I1008 18:18:12.878] Controller Service ControllerPublishVolume 
I1008 18:18:12.878]   should fail when the volume does not exist
I1008 18:18:12.878]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 18:18:12.878] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.879] STEP: creating mount and staging directories
I1008 18:18:12.879] I1008 18:18:12.849941    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.879] I1008 18:18:12.849956    8397 utils.go:112] GRPC request: 
I1008 18:18:12.879] I1008 18:18:12.849963    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.880] I1008 18:18:12.849969    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.880] 
I1008 18:18:12.880] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 18:18:12.880] Controller Service
I1008 18:18:12.880] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.880]   ControllerPublishVolume
I1008 18:18:12.881]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 18:18:12.881]     should fail when the volume does not exist [BeforeEach]
I1008 18:18:12.881]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 18:18:12.881] 
I1008 18:18:12.881]     ControllerPublishVolume not supported
I1008 18:18:12.882] 
I1008 18:18:12.882]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.882] ------------------------------
I1008 18:18:12.882] Controller Service ControllerPublishVolume 
I1008 18:18:12.882]   should fail when the node does not exist
I1008 18:18:12.883]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 18:18:12.883] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.883] STEP: creating mount and staging directories
I1008 18:18:12.883] I1008 18:18:12.853130    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.884] I1008 18:18:12.853146    8397 utils.go:112] GRPC request: 
I1008 18:18:12.884] I1008 18:18:12.853154    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.884] I1008 18:18:12.853159    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.884] 
I1008 18:18:12.885] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1008 18:18:12.885] Controller Service
I1008 18:18:12.885] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.885]   ControllerPublishVolume
I1008 18:18:12.885]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 18:18:12.886]     should fail when the node does not exist [BeforeEach]
I1008 18:18:12.886]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 18:18:12.886] 
I1008 18:18:12.886]     ControllerPublishVolume not supported
I1008 18:18:12.886] 
I1008 18:18:12.887]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.887] ------------------------------
I1008 18:18:12.887] Controller Service ControllerPublishVolume 
I1008 18:18:12.887]   should fail when the volume is already published but is incompatible
I1008 18:18:12.887]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 18:18:12.888] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.888] STEP: creating mount and staging directories
I1008 18:18:12.888] I1008 18:18:12.854124    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.888] I1008 18:18:12.854138    8397 utils.go:112] GRPC request: 
I1008 18:18:12.889] I1008 18:18:12.854144    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.889] I1008 18:18:12.854150    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.889] 
I1008 18:18:12.890] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 18:18:12.890] Controller Service
I1008 18:18:12.890] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.890]   ControllerPublishVolume
I1008 18:18:12.890]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 18:18:12.891]     should fail when the volume is already published but is incompatible [BeforeEach]
I1008 18:18:12.891]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 18:18:12.891] 
I1008 18:18:12.891]     ControllerPublishVolume not supported
I1008 18:18:12.891] 
I1008 18:18:12.892]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 18:18:12.892] ------------------------------
I1008 18:18:12.892] Controller Service ControllerUnpublishVolume 
I1008 18:18:12.892]   should fail when no volume id is provided
I1008 18:18:12.893]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 18:18:12.893] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 18:18:12.893] STEP: creating mount and staging directories
I1008 18:18:12.893] I1008 18:18:12.855168    8397 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 18:18:12.893] I1008 18:18:12.855183    8397 utils.go:112] GRPC request: 
I1008 18:18:12.894] I1008 18:18:12.855190    8397 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 18:18:12.894] I1008 18:18:12.855195    8397 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 18:18:12.894] 
I1008 18:18:12.895] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 18:18:12.895] Controller Service
I1008 18:18:12.895] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 18:18:12.895]   ControllerUnpublishVolume
I1008 18:18:12.895]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1059
I1008 18:18:12.896]     should fail when no volume id is provided [BeforeEach]
I1008 18:18:12.896]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 18:18:12.896] 
I1008 18:18:12.896]     ControllerUnpublishVolume not supported
I1008 18:18:12.896] 
I1008 18:18:12.897]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 18:18:12.897] ------------------------------
... skipping 20 lines ...
I1008 18:18:12.902]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 18:18:12.902] ------------------------------
I1008 18:18:12.902] 
I1008 18:18:12.902] 
I1008 18:18:12.903] Summarizing 1 Failure:
I1008 18:18:12.903] 
I1008 18:18:12.903] [Fail] Node Service [It] should work 
I1008 18:18:12.903] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
I1008 18:18:12.903] 
I1008 18:18:12.903] Ran 34 of 57 Specs in 29.578 seconds
I1008 18:18:12.904] FAIL! -- 33 Passed | 1 Failed | 0 Pending | 23 Skipped
I1008 18:18:12.904] --- FAIL: TestSanity (29.65s)
I1008 18:18:12.904] FAIL
I1008 18:18:12.904] pkill -f azurefileplugin
I1008 18:18:12.915] test/sanity/run-test.sh: line 19:  8397 Terminated              _output/azurefileplugin --endpoint "$endpoint" --nodeid "$nodeid" -v=5
I1008 18:18:12.915] Deleting CSI sanity test binary
I1008 18:18:13.065] 2019/10/08 18:18:13 Deleting resource group azurefile-csi-driver-test-d80d3197-e9f7-11e9-b877-42f9eb082384
I1008 18:19:59.753] --- FAIL: TestSanity (169.47s)
I1008 18:19:59.754]     sanity_test.go:78: Sanity test failed exit status 1
I1008 18:19:59.754] FAIL
I1008 18:19:59.754] FAIL	github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity	169.584s
I1008 18:19:59.802] Makefile:54: recipe for target 'sanity-test' failed
W1008 18:19:59.808] make: *** [sanity-test] Error 1
W1008 18:19:59.809] Traceback (most recent call last):
W1008 18:19:59.810]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module>
W1008 18:19:59.810]     main(ARGS.env, ARGS.cmd + ARGS.args)
W1008 18:19:59.810]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main
W1008 18:19:59.810]     check(*cmd)
W1008 18:19:59.810]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check
W1008 18:19:59.811]     subprocess.check_call(cmd)
W1008 18:19:59.811]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W1008 18:19:59.811]     raise CalledProcessError(retcode, cmd)
W1008 18:19:59.811] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2
E1008 18:19:59.811] Command failed
I1008 18:19:59.811] process 456 exited with code 1 after 5.8m
E1008 18:19:59.812] FAIL: pull-azurefile-csi-driver-sanity
I1008 18:19:59.812] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1008 18:20:00.438] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1008 18:20:00.512] process 8425 exited with code 0 after 0.0m
I1008 18:20:00.512] Call:  gcloud config get-value account
I1008 18:20:00.899] process 8437 exited with code 0 after 0.0m
I1008 18:20:00.899] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
... skipping 28 lines ...