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 19:43
Elapsed3m59s
Revision
Buildergke-prow-ssd-pool-1a225945-fgds
Refs master:daea6716
120:0469787c
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/ef0e29f3-0acd-47f2-a576-6c5d6a0e2adb/targets/test'}}
podca8e8af5-ea03-11e9-8b53-8aac75a47c5e
resultstorehttps://source.cloud.google.com/results/invocations/ef0e29f3-0acd-47f2-a576-6c5d6a0e2adb/targets/test
infra-commitf59e90136
podca8e8af5-ea03-11e9-8b53-8aac75a47c5e
reposigs.k8s.io/azurefile-csi-driver
repo-commita6f33408a519d5771c0f8d9f694fa056d9bdcc54
repos{u'sigs.k8s.io/azurefile-csi-driver': u'master:daea67167aecc01a8bfa2b24f33c4a08998cdc74,120:0469787cb0acb4b7cc06e086bb0ed29cbd962be8'}

No Test Failures!


Error lines from build-log.txt

... skipping 534 lines ...
I1008 19:45:05.199] Running Suite: CSI Driver Test Suite
I1008 19:45:05.199] ====================================
I1008 19:45:05.200] Random Seed: 1570563905
I1008 19:45:05.200] Will run 57 of 57 specs
I1008 19:45:05.200] 
I1008 19:45:05.200] DeleteSnapshot [Controller Server] 
I1008 19:45:05.200]   should fail when no snapshot id is provided
I1008 19:45:05.200]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1439
I1008 19:45:05.201] STEP: connecting to CSI driver
I1008 19:45:05.203] I1008 19:45:05.203183    8746 main.go:82] set up prometheus server on [::]:10252
I1008 19:45:05.204] I1008 19:45:05.203917    8746 azurefile.go:84] 
I1008 19:45:05.204] DRIVER INFORMATION:
I1008 19:45:05.204] -------------------
... skipping 22 lines ...
I1008 19:45:06.203] I1008 19:45:06.203378    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:06.204] I1008 19:45:06.203502    8746 utils.go:112] GRPC request: 
I1008 19:45:06.204] I1008 19:45:06.203534    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:06.204] I1008 19:45:06.203555    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:06.205] I1008 19:45:06.205454    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 19:45:06.206] I1008 19:45:06.205985    8746 utils.go:112] GRPC request: 
I1008 19:45:06.206] E1008 19:45:06.206452    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
I1008 19:45:06.207] •
I1008 19:45:06.207] ------------------------------
I1008 19:45:06.208] DeleteSnapshot [Controller Server] 
I1008 19:45:06.208]   should succeed when an invalid snapshot id is used
I1008 19:45:06.208]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1455
I1008 19:45:06.208] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:06.209] STEP: creating mount and staging directories
I1008 19:45:06.210] I1008 19:45:06.209542    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:06.210] I1008 19:45:06.209610    8746 utils.go:112] GRPC request: 
I1008 19:45:06.210] I1008 19:45:06.209631    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:06.210] I1008 19:45:06.209646    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:06.210] I1008 19:45:06.210428    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 19:45:06.210] I1008 19:45:06.210509    8746 utils.go:112] GRPC request: snapshot_id:"reallyfakesnapshotid" 
I1008 19:45:06.211] E1008 19:45:06.210547    8746 controllerserver.go:342] getFileShareInfo(reallyfakesnapshotid) failed with error: error parsing volume id: "reallyfakesnapshotid", should at least contain two #
I1008 19:45:06.211] I1008 19:45:06.210597    8746 controllerserver.go:295] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I1008 19:45:06.211] I1008 19:45:06.210637    8746 utils.go:118] GRPC response: 
I1008 19:45:06.211] •
I1008 19:45:06.211] ------------------------------
I1008 19:45:06.211] DeleteSnapshot [Controller Server] 
I1008 19:45:06.211]   should return appropriate values (no optional values added)
I1008 19:45:06.211]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1462
... skipping 123 lines ...
I1008 19:45:27.636] 
I1008 19:45:27.636]     ListVolumes not supported
I1008 19:45:27.636] 
I1008 19:45:27.636]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:157
I1008 19:45:27.636] ------------------------------
I1008 19:45:27.636] Controller Service CreateVolume 
I1008 19:45:27.636]   should fail when no name is provided
I1008 19:45:27.636]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:186
I1008 19:45:27.636] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:27.637] STEP: creating mount and staging directories
I1008 19:45:27.637] I1008 19:45:27.633675    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:27.637] I1008 19:45:27.633735    8746 utils.go:112] GRPC request: 
I1008 19:45:27.637] I1008 19:45:27.633756    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:27.637] I1008 19:45:27.633771    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:27.637] I1008 19:45:27.634146    8746 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 19:45:27.637] I1008 19:45:27.634189    8746 utils.go:112] GRPC request: 
I1008 19:45:27.638] E1008 19:45:27.634208    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
I1008 19:45:27.638] •
I1008 19:45:27.638] ------------------------------
I1008 19:45:27.638] Controller Service CreateVolume 
I1008 19:45:27.638]   should fail when no volume capabilities are provided
I1008 19:45:27.638]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:202
I1008 19:45:27.638] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:27.638] STEP: creating mount and staging directories
I1008 19:45:27.638] I1008 19:45:27.634635    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:27.639] I1008 19:45:27.634670    8746 utils.go:112] GRPC request: 
I1008 19:45:27.639] I1008 19:45:27.634689    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:27.639] I1008 19:45:27.634704    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:27.639] I1008 19:45:27.634977    8746 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 19:45:27.639] I1008 19:45:27.635012    8746 utils.go:112] GRPC request: name:"sanity-controller-create-no-volume-capabilities-B28CC495-3241FF6F" 
I1008 19:45:27.639] E1008 19:45:27.635043    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities must be provided
I1008 19:45:27.639] •
I1008 19:45:27.640] ------------------------------
I1008 19:45:27.640] Controller Service CreateVolume 
I1008 19:45:27.640]   should return appropriate values SingleNodeWriter NoCapacity Type:Mount
I1008 19:45:27.640]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:220
I1008 19:45:27.640] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 40 lines ...
I1008 19:45:28.822] I1008 19:45:28.821190    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-controller-create-single-with-capacity-b28cc495-3241ff6f" 
I1008 19:45:28.968] I1008 19:45:28.966283    8746 controllerserver.go:149] azure file(sanity-controller-create-single-with-capacity-b28cc495-3241ff6f) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#sanity-controller-create-single-with-capacity-b28cc495-3241ff6f) is deleted successfully
I1008 19:45:28.969] I1008 19:45:28.966393    8746 utils.go:118] GRPC response: 
I1008 19:45:28.969] •
I1008 19:45:28.969] ------------------------------
I1008 19:45:28.969] Controller Service CreateVolume 
I1008 19:45:28.969]   should not fail when requesting to create a volume with already existing name and same capacity.
I1008 19:45:28.969]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:311
I1008 19:45:28.969] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:28.970] STEP: creating mount and staging directories
I1008 19:45:28.970] I1008 19:45:28.967173    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:28.970] I1008 19:45:28.967247    8746 utils.go:112] GRPC request: 
I1008 19:45:28.970] I1008 19:45:28.967268    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 19 lines ...
I1008 19:45:29.588] I1008 19:45:29.586973    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-controller-create-twice-b28cc495-3241ff6f" 
I1008 19:45:29.731] I1008 19:45:29.731108    8746 controllerserver.go:149] azure file(sanity-controller-create-twice-b28cc495-3241ff6f) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#sanity-controller-create-twice-b28cc495-3241ff6f) is deleted successfully
I1008 19:45:29.731] I1008 19:45:29.731144    8746 utils.go:118] GRPC response: 
I1008 19:45:29.732] •
I1008 19:45:29.732] ------------------------------
I1008 19:45:29.732] Controller Service CreateVolume 
I1008 19:45:29.732]   should fail when requesting to create a volume with already existing name and different capacity.
I1008 19:45:29.732]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:385
I1008 19:45:29.732] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:29.732] STEP: creating mount and staging directories
I1008 19:45:29.732] I1008 19:45:29.731922    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:29.732] I1008 19:45:29.731941    8746 utils.go:112] GRPC request: 
I1008 19:45:29.733] I1008 19:45:29.731949    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 10 lines ...
I1008 19:45:29.965] I1008 19:45:29.965181    8746 utils.go:112] GRPC request: name:"sanity-controller-create-twice-different-B28CC495-3241FF6F" capacity_range:<required_bytes:21474836480 limit_bytes:21474836480 > volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 19:45:29.965] I1008 19:45:29.965262    8746 controllerserver.go:95] begin to create file share(sanity-controller-create-twice-different-b28cc495-3241ff6f) on account() type() rg() location() size(20)
I1008 19:45:30.036] I1008 19:45:30.036300    8746 azure_storageaccount.go:103] found a matching account f2125ae01ea0411e9ba4042 type Standard_LRS location eastus2
I1008 19:45:30.185] I1008 19:45:30.185400    8746 azure_file.go:68] file share(sanity-controller-create-twice-different-b28cc495-3241ff6f) under account(f2125ae01ea0411e9ba4042) already exists
I1008 19:45:30.186] I1008 19:45:30.185435    8746 azure_storage.go:49] created share sanity-controller-create-twice-different-b28cc495-3241ff6f in account f2125ae01ea0411e9ba4042
I1008 19:45:30.186] I1008 19:45:30.185461    8746 controllerserver.go:108] create file share sanity-controller-create-twice-different-b28cc495-3241ff6f on storage account f2125ae01ea0411e9ba4042 successfully
I1008 19:45:30.221] E1008 19:45:30.221092    8746 utils.go:116] GRPC error: rpc error: code = AlreadyExists desc = the request volume already exists, but its capacity(10) is different from (20)
I1008 19:45:30.221] STEP: cleaning up deleting the volume
I1008 19:45:30.221] I1008 19:45:30.221737    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 19:45:30.222] I1008 19:45:30.221778    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-controller-create-twice-different-b28cc495-3241ff6f" 
I1008 19:45:30.365] I1008 19:45:30.364845    8746 controllerserver.go:149] azure file(sanity-controller-create-twice-different-b28cc495-3241ff6f) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#sanity-controller-create-twice-different-b28cc495-3241ff6f) is deleted successfully
I1008 19:45:30.365] I1008 19:45:30.364971    8746 utils.go:118] GRPC response: 
I1008 19:45:30.365] •
I1008 19:45:30.365] ------------------------------
I1008 19:45:30.366] Controller Service CreateVolume 
I1008 19:45:30.366]   should not fail when creating volume with maximum-length name
I1008 19:45:30.366]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:460
I1008 19:45:30.366] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:30.366] STEP: creating mount and staging directories
I1008 19:45:30.366] I1008 19:45:30.365704    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:30.366] I1008 19:45:30.365753    8746 utils.go:112] GRPC request: 
I1008 19:45:30.366] I1008 19:45:30.365773    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 11 lines ...
I1008 19:45:30.614] I1008 19:45:30.613606    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa" 
I1008 19:45:30.730] I1008 19:45:30.730442    8746 controllerserver.go:149] azure file(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) is deleted successfully
I1008 19:45:30.731] I1008 19:45:30.730475    8746 utils.go:118] GRPC response: 
I1008 19:45:30.731] •
I1008 19:45:30.731] ------------------------------
I1008 19:45:30.731] Controller Service DeleteVolume 
I1008 19:45:30.731]   should fail when no volume id is provided
I1008 19:45:30.731]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:519
I1008 19:45:30.731] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:30.732] STEP: creating mount and staging directories
I1008 19:45:30.732] I1008 19:45:30.731455    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:30.732] I1008 19:45:30.731638    8746 utils.go:112] GRPC request: 
I1008 19:45:30.732] I1008 19:45:30.731677    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:30.732] I1008 19:45:30.731720    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:30.732] I1008 19:45:30.732331    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 19:45:30.732] I1008 19:45:30.732404    8746 utils.go:112] GRPC request: 
I1008 19:45:30.733] E1008 19:45:30.732429    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 19:45:30.733] •
I1008 19:45:30.733] ------------------------------
I1008 19:45:30.733] Controller Service DeleteVolume 
I1008 19:45:30.733]   should succeed when an invalid volume id is used
I1008 19:45:30.733]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:534
I1008 19:45:30.733] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:30.733] STEP: creating mount and staging directories
I1008 19:45:30.734] I1008 19:45:30.732912    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:30.734] I1008 19:45:30.732926    8746 utils.go:112] GRPC request: 
I1008 19:45:30.734] I1008 19:45:30.732932    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:30.734] I1008 19:45:30.732936    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:30.734] I1008 19:45:30.733742    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 19:45:30.734] I1008 19:45:30.733762    8746 utils.go:112] GRPC request: volume_id:"reallyfakevolumeid" 
I1008 19:45:30.734] E1008 19:45:30.733829    8746 controllerserver.go:342] getFileShareInfo(reallyfakevolumeid) failed with error: error parsing volume id: "reallyfakevolumeid", should at least contain two #
I1008 19:45:30.735] I1008 19:45:30.733843    8746 controllerserver.go:137] failed to get share url with (reallyfakevolumeid): error parsing volume id: "reallyfakevolumeid", should at least contain two #, returning with success
I1008 19:45:30.735] I1008 19:45:30.733851    8746 utils.go:118] GRPC response: 
I1008 19:45:30.735] •
I1008 19:45:30.735] ------------------------------
I1008 19:45:30.735] Controller Service DeleteVolume 
I1008 19:45:30.735]   should return appropriate values (no optional values added)
I1008 19:45:30.735]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:546
... skipping 17 lines ...
I1008 19:45:30.956] I1008 19:45:30.955324    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-controller-create-appropriate-b28cc495-3241ff6f" 
I1008 19:45:31.127] I1008 19:45:31.125205    8746 controllerserver.go:149] azure file(sanity-controller-create-appropriate-b28cc495-3241ff6f) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#sanity-controller-create-appropriate-b28cc495-3241ff6f) is deleted successfully
I1008 19:45:31.128] I1008 19:45:31.125233    8746 utils.go:118] GRPC response: 
I1008 19:45:31.128] •
I1008 19:45:31.128] ------------------------------
I1008 19:45:31.128] Controller Service ValidateVolumeCapabilities 
I1008 19:45:31.128]   should fail when no volume id is provided
I1008 19:45:31.128]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:592
I1008 19:45:31.129] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.129] STEP: creating mount and staging directories
I1008 19:45:31.129] I1008 19:45:31.126087    8746 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 19:45:31.129] I1008 19:45:31.126103    8746 utils.go:112] GRPC request: 
I1008 19:45:31.129] E1008 19:45:31.126126    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 19:45:31.129] •
I1008 19:45:31.130] ------------------------------
I1008 19:45:31.130] Controller Service ValidateVolumeCapabilities 
I1008 19:45:31.130]   should fail when no volume capabilities are provided
I1008 19:45:31.130]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:604
I1008 19:45:31.130] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.130] STEP: creating mount and staging directories
I1008 19:45:31.130] I1008 19:45:31.126558    8746 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 19:45:31.131] I1008 19:45:31.126569    8746 utils.go:112] GRPC request: volume_id:"id" 
I1008 19:45:31.131] E1008 19:45:31.126573    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities missing in request
I1008 19:45:31.131] •
I1008 19:45:31.131] ------------------------------
I1008 19:45:31.131] Controller Service ValidateVolumeCapabilities 
I1008 19:45:31.131]   should return appropriate values (no optional values added)
I1008 19:45:31.131]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:618
I1008 19:45:31.132] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 16 lines ...
I1008 19:45:31.513] I1008 19:45:31.512726    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-controller-validate-b28cc495-3241ff6f" 
I1008 19:45:31.630] I1008 19:45:31.629893    8746 controllerserver.go:149] azure file(sanity-controller-validate-b28cc495-3241ff6f) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#sanity-controller-validate-b28cc495-3241ff6f) is deleted successfully
I1008 19:45:31.631] I1008 19:45:31.629922    8746 utils.go:118] GRPC response: 
I1008 19:45:31.631] •
I1008 19:45:31.631] ------------------------------
I1008 19:45:31.631] Controller Service ValidateVolumeCapabilities 
I1008 19:45:31.631]   should fail when the requested volume does not exist
I1008 19:45:31.632]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:687
I1008 19:45:31.632] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.632] STEP: creating mount and staging directories
I1008 19:45:31.632] I1008 19:45:31.630770    8746 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 19:45:31.633] I1008 19:45:31.630828    8746 utils.go:112] GRPC request: volume_id:"some-vol-id" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 19:45:31.633] E1008 19:45:31.630851    8746 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 19:45:31.633] •
I1008 19:45:31.633] ------------------------------
I1008 19:45:31.633] Controller Service ControllerPublishVolume 
I1008 19:45:31.633]   should fail when no volume id is provided
I1008 19:45:31.633]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 19:45:31.634] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.634] STEP: creating mount and staging directories
I1008 19:45:31.634] I1008 19:45:31.631275    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.634] I1008 19:45:31.631299    8746 utils.go:112] GRPC request: 
I1008 19:45:31.634] I1008 19:45:31.631329    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.634] I1008 19:45:31.631346    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.634] 
I1008 19:45:31.634] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.634] Controller Service
I1008 19:45:31.635] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.635]   ControllerPublishVolume
I1008 19:45:31.635]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 19:45:31.635]     should fail when no volume id is provided [BeforeEach]
I1008 19:45:31.635]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 19:45:31.635] 
I1008 19:45:31.635]     ControllerPublishVolume not supported
I1008 19:45:31.635] 
I1008 19:45:31.635]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.636] ------------------------------
I1008 19:45:31.636] Controller Service ControllerPublishVolume 
I1008 19:45:31.636]   should fail when no node id is provided
I1008 19:45:31.636]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 19:45:31.636] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.636] STEP: creating mount and staging directories
I1008 19:45:31.636] I1008 19:45:31.632220    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.636] I1008 19:45:31.632262    8746 utils.go:112] GRPC request: 
I1008 19:45:31.637] I1008 19:45:31.632279    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.637] I1008 19:45:31.632293    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.637] 
I1008 19:45:31.637] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.637] Controller Service
I1008 19:45:31.637] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.637]   ControllerPublishVolume
I1008 19:45:31.637]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 19:45:31.637]     should fail when no node id is provided [BeforeEach]
I1008 19:45:31.638]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 19:45:31.638] 
I1008 19:45:31.638]     ControllerPublishVolume not supported
I1008 19:45:31.638] 
I1008 19:45:31.638]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.638] ------------------------------
I1008 19:45:31.638] Controller Service ControllerPublishVolume 
I1008 19:45:31.638]   should fail when no volume capability is provided
I1008 19:45:31.638]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 19:45:31.639] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.639] STEP: creating mount and staging directories
I1008 19:45:31.639] I1008 19:45:31.633482    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.639] I1008 19:45:31.633538    8746 utils.go:112] GRPC request: 
I1008 19:45:31.639] I1008 19:45:31.633617    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.639] I1008 19:45:31.633634    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.639] 
I1008 19:45:31.639] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.639] Controller Service
I1008 19:45:31.640] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.640]   ControllerPublishVolume
I1008 19:45:31.640]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 19:45:31.640]     should fail when no volume capability is provided [BeforeEach]
I1008 19:45:31.640]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 19:45:31.640] 
I1008 19:45:31.640]     ControllerPublishVolume not supported
I1008 19:45:31.640] 
I1008 19:45:31.640]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.641] ------------------------------
... skipping 17 lines ...
I1008 19:45:31.642] 
I1008 19:45:31.643]     ControllerPublishVolume not supported
I1008 19:45:31.643] 
I1008 19:45:31.643]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.643] ------------------------------
I1008 19:45:31.643] Controller Service ControllerPublishVolume 
I1008 19:45:31.643]   should fail when the volume does not exist
I1008 19:45:31.643]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 19:45:31.643] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.643] STEP: creating mount and staging directories
I1008 19:45:31.643] I1008 19:45:31.635258    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.644] I1008 19:45:31.635280    8746 utils.go:112] GRPC request: 
I1008 19:45:31.644] I1008 19:45:31.635284    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.644] I1008 19:45:31.635288    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.644] 
I1008 19:45:31.644] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.644] Controller Service
I1008 19:45:31.644] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.644]   ControllerPublishVolume
I1008 19:45:31.644]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 19:45:31.645]     should fail when the volume does not exist [BeforeEach]
I1008 19:45:31.645]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 19:45:31.645] 
I1008 19:45:31.645]     ControllerPublishVolume not supported
I1008 19:45:31.645] 
I1008 19:45:31.645]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.645] ------------------------------
I1008 19:45:31.645] Controller Service ControllerPublishVolume 
I1008 19:45:31.645]   should fail when the node does not exist
I1008 19:45:31.645]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 19:45:31.646] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.646] STEP: creating mount and staging directories
I1008 19:45:31.646] I1008 19:45:31.636143    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.646] I1008 19:45:31.636154    8746 utils.go:112] GRPC request: 
I1008 19:45:31.646] I1008 19:45:31.636158    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.646] I1008 19:45:31.636163    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.646] 
I1008 19:45:31.646] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.646] Controller Service
I1008 19:45:31.647] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.647]   ControllerPublishVolume
I1008 19:45:31.647]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 19:45:31.647]     should fail when the node does not exist [BeforeEach]
I1008 19:45:31.647]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 19:45:31.647] 
I1008 19:45:31.647]     ControllerPublishVolume not supported
I1008 19:45:31.647] 
I1008 19:45:31.647]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.647] ------------------------------
I1008 19:45:31.647] Controller Service ControllerPublishVolume 
I1008 19:45:31.648]   should fail when the volume is already published but is incompatible
I1008 19:45:31.648]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 19:45:31.648] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.648] STEP: creating mount and staging directories
I1008 19:45:31.648] I1008 19:45:31.636839    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.648] I1008 19:45:31.636850    8746 utils.go:112] GRPC request: 
I1008 19:45:31.648] I1008 19:45:31.636854    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.648] I1008 19:45:31.636858    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.649] 
I1008 19:45:31.649] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.649] Controller Service
I1008 19:45:31.649] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.649]   ControllerPublishVolume
I1008 19:45:31.649]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 19:45:31.649]     should fail when the volume is already published but is incompatible [BeforeEach]
I1008 19:45:31.649]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 19:45:31.649] 
I1008 19:45:31.649]     ControllerPublishVolume not supported
I1008 19:45:31.649] 
I1008 19:45:31.650]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 19:45:31.650] ------------------------------
I1008 19:45:31.650] Controller Service ControllerUnpublishVolume 
I1008 19:45:31.650]   should fail when no volume id is provided
I1008 19:45:31.650]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 19:45:31.650] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.650] STEP: creating mount and staging directories
I1008 19:45:31.650] I1008 19:45:31.637706    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.650] I1008 19:45:31.637717    8746 utils.go:112] GRPC request: 
I1008 19:45:31.651] I1008 19:45:31.637722    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.651] I1008 19:45:31.637726    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.651] 
I1008 19:45:31.651] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:31.651] Controller Service
I1008 19:45:31.651] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:31.651]   ControllerUnpublishVolume
I1008 19:45:31.651]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1059
I1008 19:45:31.651]     should fail when no volume id is provided [BeforeEach]
I1008 19:45:31.652]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 19:45:31.652] 
I1008 19:45:31.652]     ControllerUnpublishVolume not supported
I1008 19:45:31.652] 
I1008 19:45:31.652]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 19:45:31.652] ------------------------------
... skipping 17 lines ...
I1008 19:45:31.654] 
I1008 19:45:31.654]     ControllerUnpublishVolume not supported
I1008 19:45:31.654] 
I1008 19:45:31.654]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 19:45:31.654] ------------------------------
I1008 19:45:31.655] CreateSnapshot [Controller Server] 
I1008 19:45:31.655]   should fail when no name is provided
I1008 19:45:31.655]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1502
I1008 19:45:31.655] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.655] STEP: creating mount and staging directories
I1008 19:45:31.655] I1008 19:45:31.639169    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.655] I1008 19:45:31.639179    8746 utils.go:112] GRPC request: 
I1008 19:45:31.655] I1008 19:45:31.639183    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.655] I1008 19:45:31.639187    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.656] I1008 19:45:31.639502    8746 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 19:45:31.656] I1008 19:45:31.639512    8746 utils.go:112] GRPC request: source_volume_id:"testId" 
I1008 19:45:31.656] I1008 19:45:31.639518    8746 controllerserver.go:214] CreateSnapshot called with request {testId  map[] map[] {} [] 0}
I1008 19:45:31.656] E1008 19:45:31.639530    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
I1008 19:45:31.656] •
I1008 19:45:31.656] ------------------------------
I1008 19:45:31.656] CreateSnapshot [Controller Server] 
I1008 19:45:31.656]   should fail when no source volume id is provided
I1008 19:45:31.657]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1519
I1008 19:45:31.657] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.657] STEP: creating mount and staging directories
I1008 19:45:31.657] I1008 19:45:31.639859    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.657] I1008 19:45:31.639869    8746 utils.go:112] GRPC request: 
I1008 19:45:31.657] I1008 19:45:31.639873    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:31.657] I1008 19:45:31.639877    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:31.657] I1008 19:45:31.640189    8746 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 19:45:31.658] I1008 19:45:31.640199    8746 utils.go:112] GRPC request: name:"name" 
I1008 19:45:31.658] I1008 19:45:31.640204    8746 controllerserver.go:214] CreateSnapshot called with request { name map[] map[] {} [] 0}
I1008 19:45:31.658] E1008 19:45:31.640213    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
I1008 19:45:31.658] •
I1008 19:45:31.658] ------------------------------
I1008 19:45:31.658] CreateSnapshot [Controller Server] 
I1008 19:45:31.658]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1008 19:45:31.658]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1536
I1008 19:45:31.658] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:31.659] STEP: creating mount and staging directories
I1008 19:45:31.659] I1008 19:45:31.640543    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:31.659] I1008 19:45:31.640554    8746 utils.go:112] GRPC request: 
I1008 19:45:31.659] I1008 19:45:31.640558    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 28 lines ...
I1008 19:45:32.374] I1008 19:45:32.373699    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#createsnapshot-volume-1" 
I1008 19:45:32.552] I1008 19:45:32.551425    8746 controllerserver.go:149] azure file(createsnapshot-volume-1) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#createsnapshot-volume-1) is deleted successfully
I1008 19:45:32.552] I1008 19:45:32.551457    8746 utils.go:118] GRPC response: 
I1008 19:45:32.554] •
I1008 19:45:32.554] ------------------------------
I1008 19:45:32.554] CreateSnapshot [Controller Server] 
I1008 19:45:32.555]   should fail when requesting to create a snapshot with already existing name and different SourceVolumeId.
I1008 19:45:32.555]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1566
I1008 19:45:32.555] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:32.555] STEP: creating mount and staging directories
I1008 19:45:32.556] I1008 19:45:32.552290    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:32.556] I1008 19:45:32.552305    8746 utils.go:112] GRPC request: 
I1008 19:45:32.556] I1008 19:45:32.552313    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 20 lines ...
I1008 19:45:33.341] I1008 19:45:33.341058    8746 controllerserver.go:108] create file share createsnapshot-volume-3 on storage account f2125ae01ea0411e9ba4042 successfully
I1008 19:45:33.377] I1008 19:45:33.376645    8746 utils.go:118] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"#f2125ae01ea0411e9ba4042#createsnapshot-volume-3" > 
I1008 19:45:33.377] STEP: creating a snapshot with the same name but different volume source id
I1008 19:45:33.377] I1008 19:45:33.377621    8746 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 19:45:33.378] I1008 19:45:33.377707    8746 utils.go:112] GRPC request: source_volume_id:"#f2125ae01ea0411e9ba4042#createsnapshot-volume-3" name:"CreateSnapshot-snapshot-2" 
I1008 19:45:33.378] I1008 19:45:33.377730    8746 controllerserver.go:214] CreateSnapshot called with request {#f2125ae01ea0411e9ba4042#createsnapshot-volume-3 CreateSnapshot-snapshot-2 map[] map[] {} [] 0}
I1008 19:45:33.526] E1008 19:45:33.525612    8746 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(#f2125ae01ea0411e9ba4042#createsnapshot-volume-3)
I1008 19:45:33.527] STEP: cleaning up deleting the snapshot
I1008 19:45:33.527] I1008 19:45:33.526223    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 19:45:33.527] I1008 19:45:33.526259    8746 utils.go:112] GRPC request: snapshot_id:"#f2125ae01ea0411e9ba4042#createsnapshot-volume-2#2019-10-08T19:45:33.0000000Z" 
I1008 19:45:33.643] I1008 19:45:33.643339    8746 controllerserver.go:313] delete snapshot(2019-10-08T19:45:33.0000000Z) successfully
I1008 19:45:33.646] I1008 19:45:33.643453    8746 utils.go:118] GRPC response: 
I1008 19:45:33.646] STEP: cleaning up deleting the volume
I1008 19:45:33.646] I1008 19:45:33.644239    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 19:45:33.647] I1008 19:45:33.644278    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#createsnapshot-volume-2" 
I1008 19:45:33.814] I1008 19:45:33.812284    8746 controllerserver.go:149] azure file(createsnapshot-volume-2) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#createsnapshot-volume-2) is deleted successfully
I1008 19:45:33.814] I1008 19:45:33.812309    8746 utils.go:118] GRPC response: 
I1008 19:45:33.814] •
I1008 19:45:33.814] ------------------------------
I1008 19:45:33.814] CreateSnapshot [Controller Server] 
I1008 19:45:33.814]   should not fail when creating snapshot with maximum-length name
I1008 19:45:33.814]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1601
I1008 19:45:33.814] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:33.815] STEP: creating mount and staging directories
I1008 19:45:33.815] I1008 19:45:33.812896    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:33.815] I1008 19:45:33.812910    8746 utils.go:112] GRPC request: 
I1008 19:45:33.815] I1008 19:45:33.812916    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 72 lines ...
I1008 19:45:34.895] I1008 19:45:34.885451    8746 utils.go:112] GRPC request: 
I1008 19:45:34.895] I1008 19:45:34.885456    8746 nodeserver.go:228] Using default NodeGetInfo
I1008 19:45:34.895] I1008 19:45:34.885460    8746 utils.go:118] GRPC response: node_id:"CSINode" 
I1008 19:45:34.896] •
I1008 19:45:34.896] ------------------------------
I1008 19:45:34.896] Node Service NodePublishVolume 
I1008 19:45:34.896]   should fail when no volume id is provided
I1008 19:45:34.896]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:158
I1008 19:45:34.896] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.896] STEP: creating mount and staging directories
I1008 19:45:34.897] I1008 19:45:34.885989    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.897] I1008 19:45:34.886000    8746 utils.go:112] GRPC request: 
I1008 19:45:34.897] I1008 19:45:34.886005    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:34.898] I1008 19:45:34.886057    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:34.898] I1008 19:45:34.886372    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 19:45:34.898] I1008 19:45:34.886406    8746 utils.go:112] GRPC request: 
I1008 19:45:34.898] I1008 19:45:34.886448    8746 nodeserver.go:219] Using default NodeGetCapabilities
I1008 19:45:34.898] I1008 19:45:34.886469    8746 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 19:45:34.899] I1008 19:45:34.886888    8746 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 19:45:34.899] I1008 19:45:34.886933    8746 utils.go:112] GRPC request: 
I1008 19:45:34.899] E1008 19:45:34.886951    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 19:45:34.899] •
I1008 19:45:34.899] ------------------------------
I1008 19:45:34.899] Node Service NodePublishVolume 
I1008 19:45:34.900]   should fail when no target path is provided
I1008 19:45:34.900]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:172
I1008 19:45:34.900] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.900] STEP: creating mount and staging directories
I1008 19:45:34.900] I1008 19:45:34.887354    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.900] I1008 19:45:34.887400    8746 utils.go:112] GRPC request: 
I1008 19:45:34.901] I1008 19:45:34.887417    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:34.901] I1008 19:45:34.887431    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:34.901] I1008 19:45:34.887746    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 19:45:34.901] I1008 19:45:34.887780    8746 utils.go:112] GRPC request: 
I1008 19:45:34.901] I1008 19:45:34.887795    8746 nodeserver.go:219] Using default NodeGetCapabilities
I1008 19:45:34.901] I1008 19:45:34.887827    8746 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 19:45:34.902] I1008 19:45:34.888140    8746 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 19:45:34.902] I1008 19:45:34.888186    8746 utils.go:112] GRPC request: volume_id:"id" 
I1008 19:45:34.902] E1008 19:45:34.888203    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 19:45:34.902] •
I1008 19:45:34.902] ------------------------------
I1008 19:45:34.902] Node Service NodePublishVolume 
I1008 19:45:34.902]   should fail when no volume capability is provided
I1008 19:45:34.902]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:187
I1008 19:45:34.903] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.903] STEP: creating mount and staging directories
I1008 19:45:34.903] I1008 19:45:34.888527    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.903] I1008 19:45:34.888556    8746 utils.go:112] GRPC request: 
I1008 19:45:34.903] I1008 19:45:34.888591    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:34.904] I1008 19:45:34.888613    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:34.904] I1008 19:45:34.888870    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 19:45:34.904] I1008 19:45:34.888944    8746 utils.go:112] GRPC request: 
I1008 19:45:34.904] I1008 19:45:34.888967    8746 nodeserver.go:219] Using default NodeGetCapabilities
I1008 19:45:34.904] I1008 19:45:34.889033    8746 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 19:45:34.905] I1008 19:45:34.889445    8746 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 19:45:34.905] I1008 19:45:34.889455    8746 utils.go:112] GRPC request: volume_id:"id" target_path:"/tmp/csi" 
I1008 19:45:34.905] E1008 19:45:34.889460    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 19:45:34.905] •
I1008 19:45:34.905] ------------------------------
I1008 19:45:34.906] Node Service NodeUnpublishVolume 
I1008 19:45:34.906]   should fail when no volume id is provided
I1008 19:45:34.906]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:205
I1008 19:45:34.906] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.906] STEP: creating mount and staging directories
I1008 19:45:34.906] I1008 19:45:34.889906    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.907] I1008 19:45:34.889922    8746 utils.go:112] GRPC request: 
I1008 19:45:34.907] I1008 19:45:34.889929    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:34.907] I1008 19:45:34.889935    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:34.907] I1008 19:45:34.890371    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 19:45:34.908] I1008 19:45:34.890397    8746 utils.go:112] GRPC request: 
I1008 19:45:34.908] I1008 19:45:34.890403    8746 nodeserver.go:219] Using default NodeGetCapabilities
I1008 19:45:34.908] I1008 19:45:34.890408    8746 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 19:45:34.908] I1008 19:45:34.891011    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 19:45:34.908] I1008 19:45:34.891087    8746 utils.go:112] GRPC request: 
I1008 19:45:34.909] E1008 19:45:34.891115    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 19:45:34.909] •
I1008 19:45:34.909] ------------------------------
I1008 19:45:34.909] Node Service NodeUnpublishVolume 
I1008 19:45:34.909]   should fail when no target path is provided
I1008 19:45:34.909]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:217
I1008 19:45:34.910] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.910] STEP: creating mount and staging directories
I1008 19:45:34.910] I1008 19:45:34.891768    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.910] I1008 19:45:34.891828    8746 utils.go:112] GRPC request: 
I1008 19:45:34.910] I1008 19:45:34.891850    8746 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 19:45:34.911] I1008 19:45:34.891869    8746 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 19:45:34.911] I1008 19:45:34.892325    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 19:45:34.911] I1008 19:45:34.892343    8746 utils.go:112] GRPC request: 
I1008 19:45:34.911] I1008 19:45:34.892348    8746 nodeserver.go:219] Using default NodeGetCapabilities
I1008 19:45:34.911] I1008 19:45:34.892352    8746 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 19:45:34.912] I1008 19:45:34.892970    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 19:45:34.912] I1008 19:45:34.893056    8746 utils.go:112] GRPC request: volume_id:"id" 
I1008 19:45:34.912] E1008 19:45:34.893077    8746 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
I1008 19:45:34.912] •
I1008 19:45:34.912] ------------------------------
I1008 19:45:34.912] Node Service NodeStageVolume 
I1008 19:45:34.913]   should fail when no volume id is provided
I1008 19:45:34.913]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 19:45:34.913] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.913] STEP: creating mount and staging directories
I1008 19:45:34.913] I1008 19:45:34.893566    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.913] I1008 19:45:34.893618    8746 utils.go:112] GRPC request: 
I1008 19:45:34.914] I1008 19:45:34.893641    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 19:45:34.915] 
I1008 19:45:34.915] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 19:45:34.915] Node Service
I1008 19:45:34.915] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:34.915]   NodeStageVolume
I1008 19:45:34.916]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 19:45:34.916]     should fail when no volume id is provided [BeforeEach]
I1008 19:45:34.916]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 19:45:34.916] 
I1008 19:45:34.916]     NodeStageVolume not supported
I1008 19:45:34.916] 
I1008 19:45:34.917]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 19:45:34.917] ------------------------------
I1008 19:45:34.917] Node Service NodeStageVolume 
I1008 19:45:34.917]   should fail when no staging target path is provided
I1008 19:45:34.917]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 19:45:34.917] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.918] STEP: creating mount and staging directories
I1008 19:45:34.918] I1008 19:45:34.895162    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.918] I1008 19:45:34.895215    8746 utils.go:112] GRPC request: 
I1008 19:45:34.918] I1008 19:45:34.895241    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 19:45:34.919] 
I1008 19:45:34.919] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:34.919] Node Service
I1008 19:45:34.919] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:34.919]   NodeStageVolume
I1008 19:45:34.920]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 19:45:34.920]     should fail when no staging target path is provided [BeforeEach]
I1008 19:45:34.920]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 19:45:34.920] 
I1008 19:45:34.920]     NodeStageVolume not supported
I1008 19:45:34.920] 
I1008 19:45:34.920]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 19:45:34.920] ------------------------------
I1008 19:45:34.920] Node Service NodeStageVolume 
I1008 19:45:34.920]   should fail when no volume capability is provided
I1008 19:45:34.921]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 19:45:34.921] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.921] STEP: creating mount and staging directories
I1008 19:45:34.921] I1008 19:45:34.896405    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.921] I1008 19:45:34.896424    8746 utils.go:112] GRPC request: 
I1008 19:45:34.921] I1008 19:45:34.896429    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 19:45:34.922] 
I1008 19:45:34.922] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:34.922] Node Service
I1008 19:45:34.922] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:34.922]   NodeStageVolume
I1008 19:45:34.922]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 19:45:34.922]     should fail when no volume capability is provided [BeforeEach]
I1008 19:45:34.923]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 19:45:34.923] 
I1008 19:45:34.923]     NodeStageVolume not supported
I1008 19:45:34.923] 
I1008 19:45:34.923]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 19:45:34.923] ------------------------------
I1008 19:45:34.923] Node Service NodeUnstageVolume 
I1008 19:45:34.923]   should fail when no volume id is provided
I1008 19:45:34.923]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 19:45:34.923] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.924] STEP: creating mount and staging directories
I1008 19:45:34.924] I1008 19:45:34.897474    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.924] I1008 19:45:34.897483    8746 utils.go:112] GRPC request: 
I1008 19:45:34.924] I1008 19:45:34.897488    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 19:45:34.925] 
I1008 19:45:34.925] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:34.925] Node Service
I1008 19:45:34.925] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:34.925]   NodeUnstageVolume
I1008 19:45:34.925]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 19:45:34.925]     should fail when no volume id is provided [BeforeEach]
I1008 19:45:34.925]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 19:45:34.925] 
I1008 19:45:34.925]     NodeUnstageVolume not supported
I1008 19:45:34.926] 
I1008 19:45:34.926]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 19:45:34.926] ------------------------------
I1008 19:45:34.926] Node Service NodeUnstageVolume 
I1008 19:45:34.926]   should fail when no staging target path is provided
I1008 19:45:34.926]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 19:45:34.926] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 19:45:34.926] STEP: creating mount and staging directories
I1008 19:45:34.926] I1008 19:45:34.898841    8746 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 19:45:34.927] I1008 19:45:34.898861    8746 utils.go:112] GRPC request: 
I1008 19:45:34.927] I1008 19:45:34.898873    8746 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 19:45:34.927] 
I1008 19:45:34.927] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 19:45:34.928] Node Service
I1008 19:45:34.928] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:34.928]   NodeUnstageVolume
I1008 19:45:34.928]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 19:45:34.928]     should fail when no staging target path is provided [BeforeEach]
I1008 19:45:34.928]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 19:45:34.928] 
I1008 19:45:34.928]     NodeUnstageVolume not supported
I1008 19:45:34.928] 
I1008 19:45:34.928]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 19:45:34.929] ------------------------------
... skipping 32 lines ...
I1008 19:45:35.156] 
I1008 19:45:35.156] readonly false
I1008 19:45:35.156] volumeId #f2125ae01ea0411e9ba4042#sanity-node-full-b28cc495-3241ff6f
I1008 19:45:35.156] context map[]
I1008 19:45:35.156] mountflags []
I1008 19:45:35.249] I1008 19:45:35.249062    8746 mount_linux.go:138] Mounting cmd (mount) with arguments ([-t cifs -o username=f2125ae01ea0411e9ba4042,password=SXVLdgSKL5lGHGcqjSX78mGIvN4Y9qPSVj36A1cTAfI4ucA0DnwziwqrCRUawaOAMO6ZT+a4OXgq3Dn3yO4MZA==,file_mode=0777,dir_mode=0777,vers=3.0 //f2125ae01ea0411e9ba4042.file.core.windows.net/sanity-node-full-b28cc495-3241ff6f /tmp/csi])
I1008 19:45:36.335] E1008 19:45:36.335459    8746 mount_linux.go:143] Mount failed: exit status 32
I1008 19:45:36.336] Mounting command: mount
I1008 19:45:36.336] Mounting arguments: -t cifs -o username=f2125ae01ea0411e9ba4042,password=SXVLdgSKL5lGHGcqjSX78mGIvN4Y9qPSVj36A1cTAfI4ucA0DnwziwqrCRUawaOAMO6ZT+a4OXgq3Dn3yO4MZA==,file_mode=0777,dir_mode=0777,vers=3.0 //f2125ae01ea0411e9ba4042.file.core.windows.net/sanity-node-full-b28cc495-3241ff6f /tmp/csi
I1008 19:45:36.336] Output: mount error: cifs filesystem not supported by the system
I1008 19:45:36.337] mount error(19): No such device
I1008 19:45:36.337] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 19:45:36.337] 
I1008 19:45:36.337] E1008 19:45:36.335634    8746 utils.go:116] GRPC error: mount failed: exit status 32
I1008 19:45:36.337] Mounting command: mount
I1008 19:45:36.338] Mounting arguments: -t cifs -o username=f2125ae01ea0411e9ba4042,password=SXVLdgSKL5lGHGcqjSX78mGIvN4Y9qPSVj36A1cTAfI4ucA0DnwziwqrCRUawaOAMO6ZT+a4OXgq3Dn3yO4MZA==,file_mode=0777,dir_mode=0777,vers=3.0 //f2125ae01ea0411e9ba4042.file.core.windows.net/sanity-node-full-b28cc495-3241ff6f /tmp/csi
I1008 19:45:36.338] Output: mount error: cifs filesystem not supported by the system
I1008 19:45:36.338] mount error(19): No such device
I1008 19:45:36.338] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 19:45:36.338] 
I1008 19:45:36.339] cleanup: deleting sanity-node-full-B28CC495-3241FF6F = #f2125ae01ea0411e9ba4042#sanity-node-full-b28cc495-3241ff6f
I1008 19:45:36.339] I1008 19:45:36.337386    8746 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 19:45:36.339] I1008 19:45:36.337422    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-node-full-b28cc495-3241ff6f" target_path:"/tmp/csi" 
I1008 19:45:36.339] I1008 19:45:36.337432    8746 mount_linux.go:203] Unmounting /tmp/csi
I1008 19:45:36.348] E1008 19:45:36.346570    8746 utils.go:116] GRPC error: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 19:45:36.348] Unmounting arguments: /tmp/csi
I1008 19:45:36.348] Output: umount: /tmp/csi: mountpoint not found
I1008 19:45:36.349] 
I1008 19:45:36.349] cleanup: warning: NodeUnpublishVolume: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 19:45:36.349] Unmounting arguments: /tmp/csi
I1008 19:45:36.349] Output: umount: /tmp/csi: mountpoint not found
I1008 19:45:36.349] 
I1008 19:45:36.349] I1008 19:45:36.347260    8746 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 19:45:36.350] I1008 19:45:36.347299    8746 utils.go:112] GRPC request: volume_id:"#f2125ae01ea0411e9ba4042#sanity-node-full-b28cc495-3241ff6f" 
I1008 19:45:36.482] I1008 19:45:36.481188    8746 controllerserver.go:149] azure file(sanity-node-full-b28cc495-3241ff6f) under rg() account(f2125ae01ea0411e9ba4042) volumeID(#f2125ae01ea0411e9ba4042#sanity-node-full-b28cc495-3241ff6f) is deleted successfully
... skipping 2 lines ...
I1008 19:45:36.482] • Failure [1.581 seconds]
I1008 19:45:36.483] Node Service
I1008 19:45:36.483] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 19:45:36.483]   should work [It]
I1008 19:45:36.483]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:353
I1008 19:45:36.483] 
I1008 19:45:36.483]   Expected error:
I1008 19:45:36.483]       <*status.statusError | 0xc000320820>: {
I1008 19:45:36.484]           Code: 2,
I1008 19:45:36.484]           Message: "mount failed: exit status 32\nMounting command: mount\nMounting arguments: -t cifs -o username=f2125ae01ea0411e9ba4042,password=SXVLdgSKL5lGHGcqjSX78mGIvN4Y9qPSVj36A1cTAfI4ucA0DnwziwqrCRUawaOAMO6ZT+a4OXgq3Dn3yO4MZA==,file_mode=0777,dir_mode=0777,vers=3.0 //f2125ae01ea0411e9ba4042.file.core.windows.net/sanity-node-full-b28cc495-3241ff6f /tmp/csi\nOutput: mount error: cifs filesystem not supported by the system\nmount error(19): No such device\nRefer to the mount.cifs(8) manual page (e.g. man mount.cifs)\n\n",
I1008 19:45:36.484]           Details: nil,
I1008 19:45:36.485]           XXX_NoUnkeyedLiteral: {},
I1008 19:45:36.485]           XXX_unrecognized: nil,
I1008 19:45:36.485]           XXX_sizecache: 0,
I1008 19:45:36.485]       }
I1008 19:45:36.486]       rpc error: code = Unknown desc = mount failed: exit status 32
I1008 19:45:36.486]       Mounting command: mount
I1008 19:45:36.486]       Mounting arguments: -t cifs -o username=f2125ae01ea0411e9ba4042,password=SXVLdgSKL5lGHGcqjSX78mGIvN4Y9qPSVj36A1cTAfI4ucA0DnwziwqrCRUawaOAMO6ZT+a4OXgq3Dn3yO4MZA==,file_mode=0777,dir_mode=0777,vers=3.0 //f2125ae01ea0411e9ba4042.file.core.windows.net/sanity-node-full-b28cc495-3241ff6f /tmp/csi
I1008 19:45:36.487]       Output: mount error: cifs filesystem not supported by the system
I1008 19:45:36.487]       mount error(19): No such device
I1008 19:45:36.487]       Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 19:45:36.487]       
I1008 19:45:36.487]       
I1008 19:45:36.487]   not to have occurred
I1008 19:45:36.487] 
I1008 19:45:36.487]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
... skipping 139 lines ...
I1008 19:45:36.507]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1182
I1008 19:45:36.507] ------------------------------
I1008 19:45:36.507] 
I1008 19:45:36.507] 
I1008 19:45:36.508] Summarizing 1 Failure:
I1008 19:45:36.508] 
I1008 19:45:36.508] [Fail] Node Service [It] should work 
I1008 19:45:36.508] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
I1008 19:45:36.508] 
I1008 19:45:36.508] Ran 34 of 57 Specs in 31.292 seconds
I1008 19:45:36.508] FAIL! -- 33 Passed | 1 Failed | 0 Pending | 23 Skipped
I1008 19:45:36.508] --- FAIL: TestSanity (31.30s)
I1008 19:45:36.509] FAIL
I1008 19:45:36.509] pkill -f azurefileplugin
I1008 19:45:36.509] Deleting CSI sanity test binary
I1008 19:45:36.555] test/sanity/run-test.sh: line 19:  8746 Terminated              _output/azurefileplugin --endpoint "$endpoint" --nodeid "$nodeid" -v=5
I1008 19:45:36.556] 2019/10/08 19:45:36 Deleting resource group azurefile-csi-driver-test-13cb1f2d-ea04-11e9-97ff-42aa89d5a3f6
I1008 19:47:23.151] --- FAIL: TestSanity (158.71s)
I1008 19:47:23.151]     sanity_test.go:78: Sanity test failed exit status 1
I1008 19:47:23.151] FAIL
I1008 19:47:23.153] FAIL	github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity	158.728s
I1008 19:47:23.210] Makefile:54: recipe for target 'sanity-test' failed
W1008 19:47:23.214] make: *** [sanity-test] Error 1
W1008 19:47:23.215] Traceback (most recent call last):
W1008 19:47:23.215]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module>
W1008 19:47:23.215]     main(ARGS.env, ARGS.cmd + ARGS.args)
W1008 19:47:23.215]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main
W1008 19:47:23.216]     check(*cmd)
W1008 19:47:23.216]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check
W1008 19:47:23.216]     subprocess.check_call(cmd)
W1008 19:47:23.216]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W1008 19:47:23.216]     raise CalledProcessError(retcode, cmd)
W1008 19:47:23.216] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2
E1008 19:47:23.216] Command failed
I1008 19:47:23.216] process 456 exited with code 1 after 3.6m
E1008 19:47:23.217] FAIL: pull-azurefile-csi-driver-sanity
I1008 19:47:23.217] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1008 19:47:23.752] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1008 19:47:23.808] process 8775 exited with code 0 after 0.0m
I1008 19:47:23.808] Call:  gcloud config get-value account
I1008 19:47:24.146] process 8787 exited with code 0 after 0.0m
I1008 19:47:24.147] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
... skipping 28 lines ...