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 14:09
Elapsed4m23s
Revision
Buildergke-prow-ssd-pool-1a225945-g951
Refs master:daea6716
120:0469787c
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/971f0289-62b4-4fa3-a59e-21eb42303755/targets/test'}}
pod2c89fbf6-e9d5-11e9-94c6-b6a3998ca3c7
resultstorehttps://source.cloud.google.com/results/invocations/971f0289-62b4-4fa3-a59e-21eb42303755/targets/test
infra-commitb83b3fed1
pod2c89fbf6-e9d5-11e9-94c6-b6a3998ca3c7
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 14:11:19.486] Running Suite: CSI Driver Test Suite
I1008 14:11:19.486] ====================================
I1008 14:11:19.487] Random Seed: 1570543879
I1008 14:11:19.487] Will run 57 of 57 specs
I1008 14:11:19.487] 
I1008 14:11:19.487] CreateSnapshot [Controller Server] 
I1008 14:11:19.488]   should fail when no name is provided
I1008 14:11:19.488]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1502
I1008 14:11:19.488] STEP: connecting to CSI driver
I1008 14:11:19.494] I1008 14:11:19.493817    8854 main.go:82] set up prometheus server on [::]:10252
I1008 14:11:19.494] I1008 14:11:19.494674    8854 azurefile.go:84] 
I1008 14:11:19.495] DRIVER INFORMATION:
I1008 14:11:19.495] -------------------
... skipping 23 lines ...
I1008 14:11:20.494] I1008 14:11:20.494398    8854 utils.go:112] GRPC request: 
I1008 14:11:20.495] I1008 14:11:20.494406    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:20.496] I1008 14:11:20.494411    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:20.497] I1008 14:11:20.497382    8854 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 14:11:20.498] I1008 14:11:20.497956    8854 utils.go:112] GRPC request: source_volume_id:"testId" 
I1008 14:11:20.498] I1008 14:11:20.498460    8854 controllerserver.go:214] CreateSnapshot called with request {testId  map[] map[] {} [] 0}
I1008 14:11:20.499] E1008 14:11:20.498925    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
I1008 14:11:20.499] •
I1008 14:11:20.499] ------------------------------
I1008 14:11:20.500] CreateSnapshot [Controller Server] 
I1008 14:11:20.500]   should fail when no source volume id is provided
I1008 14:11:20.500]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1519
I1008 14:11:20.501] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:20.501] STEP: creating mount and staging directories
I1008 14:11:20.501] I1008 14:11:20.500019    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:20.503] I1008 14:11:20.500030    8854 utils.go:112] GRPC request: 
I1008 14:11:20.503] I1008 14:11:20.500045    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:20.504] I1008 14:11:20.500070    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:20.504] I1008 14:11:20.502037    8854 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 14:11:20.504] I1008 14:11:20.502066    8854 utils.go:112] GRPC request: name:"name" 
I1008 14:11:20.504] I1008 14:11:20.502073    8854 controllerserver.go:214] CreateSnapshot called with request { name map[] map[] {} [] 0}
I1008 14:11:20.505] E1008 14:11:20.502104    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
I1008 14:11:20.505] •
I1008 14:11:20.505] ------------------------------
I1008 14:11:20.506] CreateSnapshot [Controller Server] 
I1008 14:11:20.506]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1008 14:11:20.506]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1536
I1008 14:11:20.506] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:20.507] STEP: creating mount and staging directories
I1008 14:11:20.507] I1008 14:11:20.503352    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:20.507] I1008 14:11:20.503364    8854 utils.go:112] GRPC request: 
I1008 14:11:20.507] I1008 14:11:20.503368    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 29 lines ...
I1008 14:11:40.697] I1008 14:11:40.697032    8854 controllerserver.go:149] azure file(createsnapshot-volume-1) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#createsnapshot-volume-1) is deleted successfully
I1008 14:11:40.698] I1008 14:11:40.697182    8854 utils.go:118] GRPC response: 
I1008 14:11:40.698] 
I1008 14:11:40.698] • [SLOW TEST:20.195 seconds]
I1008 14:11:40.698] CreateSnapshot [Controller Server]
I1008 14:11:40.699] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:40.699]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1008 14:11:40.699]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1536
I1008 14:11:40.699] ------------------------------
I1008 14:11:40.699] CreateSnapshot [Controller Server] 
I1008 14:11:40.699]   should fail when requesting to create a snapshot with already existing name and different SourceVolumeId.
I1008 14:11:40.699]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1566
I1008 14:11:40.700] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:40.700] STEP: creating mount and staging directories
I1008 14:11:40.700] I1008 14:11:40.698448    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:40.700] I1008 14:11:40.698482    8854 utils.go:112] GRPC request: 
I1008 14:11:40.700] I1008 14:11:40.698488    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 20 lines ...
I1008 14:11:41.473] I1008 14:11:41.472859    8854 controllerserver.go:108] create file share createsnapshot-volume-3 on storage account f80da8236e9d511e9aef042 successfully
I1008 14:11:41.509] I1008 14:11:41.508430    8854 utils.go:118] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"#f80da8236e9d511e9aef042#createsnapshot-volume-3" > 
I1008 14:11:41.510] STEP: creating a snapshot with the same name but different volume source id
I1008 14:11:41.510] I1008 14:11:41.509172    8854 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 14:11:41.510] I1008 14:11:41.509269    8854 utils.go:112] GRPC request: source_volume_id:"#f80da8236e9d511e9aef042#createsnapshot-volume-3" name:"CreateSnapshot-snapshot-2" 
I1008 14:11:41.511] I1008 14:11:41.509299    8854 controllerserver.go:214] CreateSnapshot called with request {#f80da8236e9d511e9aef042#createsnapshot-volume-3 CreateSnapshot-snapshot-2 map[] map[] {} [] 0}
I1008 14:11:41.651] E1008 14:11:41.649882    8854 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(#f80da8236e9d511e9aef042#createsnapshot-volume-3)
I1008 14:11:41.652] STEP: cleaning up deleting the snapshot
I1008 14:11:41.652] I1008 14:11:41.650701    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 14:11:41.652] I1008 14:11:41.650776    8854 utils.go:112] GRPC request: snapshot_id:"#f80da8236e9d511e9aef042#createsnapshot-volume-2#2019-10-08T14:11:41.0000000Z" 
I1008 14:11:41.764] I1008 14:11:41.763728    8854 controllerserver.go:313] delete snapshot(2019-10-08T14:11:41.0000000Z) successfully
I1008 14:11:41.765] I1008 14:11:41.763847    8854 utils.go:118] GRPC response: 
I1008 14:11:41.765] STEP: cleaning up deleting the volume
I1008 14:11:41.765] I1008 14:11:41.764653    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 14:11:41.766] I1008 14:11:41.764744    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#createsnapshot-volume-2" 
I1008 14:11:41.904] I1008 14:11:41.902683    8854 controllerserver.go:149] azure file(createsnapshot-volume-2) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#createsnapshot-volume-2) is deleted successfully
I1008 14:11:41.904] I1008 14:11:41.902724    8854 utils.go:118] GRPC response: 
I1008 14:11:41.905] •
I1008 14:11:41.905] ------------------------------
I1008 14:11:41.905] CreateSnapshot [Controller Server] 
I1008 14:11:41.905]   should not fail when creating snapshot with maximum-length name
I1008 14:11:41.905]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1601
I1008 14:11:41.905] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:41.906] STEP: creating mount and staging directories
I1008 14:11:41.906] I1008 14:11:41.903955    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:41.906] I1008 14:11:41.904015    8854 utils.go:112] GRPC request: 
I1008 14:11:41.906] I1008 14:11:41.904032    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 29 lines ...
I1008 14:11:42.650] I1008 14:11:42.648721    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#createsnapshot-volume-3" 
I1008 14:11:42.797] I1008 14:11:42.796325    8854 controllerserver.go:149] azure file(createsnapshot-volume-3) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#createsnapshot-volume-3) is deleted successfully
I1008 14:11:42.800] I1008 14:11:42.796426    8854 utils.go:118] GRPC response: 
I1008 14:11:42.800] •
I1008 14:11:42.800] ------------------------------
I1008 14:11:42.800] DeleteSnapshot [Controller Server] 
I1008 14:11:42.800]   should fail when no snapshot id is provided
I1008 14:11:42.801]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1439
I1008 14:11:42.801] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:42.801] STEP: creating mount and staging directories
I1008 14:11:42.801] I1008 14:11:42.797339    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:42.801] I1008 14:11:42.797405    8854 utils.go:112] GRPC request: 
I1008 14:11:42.801] I1008 14:11:42.797428    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:42.801] I1008 14:11:42.797442    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:42.801] I1008 14:11:42.797983    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 14:11:42.802] I1008 14:11:42.798036    8854 utils.go:112] GRPC request: 
I1008 14:11:42.802] E1008 14:11:42.798052    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
I1008 14:11:42.802] •
I1008 14:11:42.802] ------------------------------
I1008 14:11:42.802] DeleteSnapshot [Controller Server] 
I1008 14:11:42.802]   should succeed when an invalid snapshot id is used
I1008 14:11:42.802]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1455
I1008 14:11:42.802] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:42.802] STEP: creating mount and staging directories
I1008 14:11:42.803] I1008 14:11:42.798468    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:42.803] I1008 14:11:42.798501    8854 utils.go:112] GRPC request: 
I1008 14:11:42.803] I1008 14:11:42.798518    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:42.803] I1008 14:11:42.798532    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:42.803] I1008 14:11:42.798817    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 14:11:42.803] I1008 14:11:42.798856    8854 utils.go:112] GRPC request: snapshot_id:"reallyfakesnapshotid" 
I1008 14:11:42.803] E1008 14:11:42.798879    8854 controllerserver.go:342] getFileShareInfo(reallyfakesnapshotid) failed with error: error parsing volume id: "reallyfakesnapshotid", should at least contain two #
I1008 14:11:42.804] I1008 14:11:42.798895    8854 controllerserver.go:295] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I1008 14:11:42.804] I1008 14:11:42.798931    8854 utils.go:118] GRPC response: 
I1008 14:11:42.804] •
I1008 14:11:42.804] ------------------------------
I1008 14:11:42.804] DeleteSnapshot [Controller Server] 
I1008 14:11:42.804]   should return appropriate values (no optional values added)
I1008 14:11:42.804]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1462
... skipping 105 lines ...
I1008 14:11:43.549] I1008 14:11:43.547394    8854 utils.go:112] GRPC request: 
I1008 14:11:43.549] I1008 14:11:43.547398    8854 nodeserver.go:228] Using default NodeGetInfo
I1008 14:11:43.549] I1008 14:11:43.547402    8854 utils.go:118] GRPC response: node_id:"CSINode" 
I1008 14:11:43.549] •
I1008 14:11:43.549] ------------------------------
I1008 14:11:43.550] Node Service NodePublishVolume 
I1008 14:11:43.550]   should fail when no volume id is provided
I1008 14:11:43.550]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:158
I1008 14:11:43.550] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.550] STEP: creating mount and staging directories
I1008 14:11:43.551] I1008 14:11:43.547742    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.551] I1008 14:11:43.547752    8854 utils.go:112] GRPC request: 
I1008 14:11:43.551] I1008 14:11:43.547757    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:43.551] I1008 14:11:43.547760    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:43.551] I1008 14:11:43.548026    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 14:11:43.552] I1008 14:11:43.548044    8854 utils.go:112] GRPC request: 
I1008 14:11:43.552] I1008 14:11:43.548048    8854 nodeserver.go:219] Using default NodeGetCapabilities
I1008 14:11:43.552] I1008 14:11:43.548052    8854 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 14:11:43.552] I1008 14:11:43.548529    8854 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 14:11:43.553] I1008 14:11:43.548543    8854 utils.go:112] GRPC request: 
I1008 14:11:43.553] E1008 14:11:43.548549    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 14:11:43.553] •
I1008 14:11:43.553] ------------------------------
I1008 14:11:43.553] Node Service NodePublishVolume 
I1008 14:11:43.553]   should fail when no target path is provided
I1008 14:11:43.554]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:172
I1008 14:11:43.554] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.554] STEP: creating mount and staging directories
I1008 14:11:43.554] I1008 14:11:43.548968    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.554] I1008 14:11:43.548979    8854 utils.go:112] GRPC request: 
I1008 14:11:43.555] I1008 14:11:43.548983    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:43.555] I1008 14:11:43.548987    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:43.555] I1008 14:11:43.549471    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 14:11:43.555] I1008 14:11:43.549482    8854 utils.go:112] GRPC request: 
I1008 14:11:43.556] I1008 14:11:43.549486    8854 nodeserver.go:219] Using default NodeGetCapabilities
I1008 14:11:43.556] I1008 14:11:43.549490    8854 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 14:11:43.556] I1008 14:11:43.549926    8854 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 14:11:43.556] I1008 14:11:43.549936    8854 utils.go:112] GRPC request: volume_id:"id" 
I1008 14:11:43.556] E1008 14:11:43.549941    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 14:11:43.556] •
I1008 14:11:43.556] ------------------------------
I1008 14:11:43.556] Node Service NodePublishVolume 
I1008 14:11:43.556]   should fail when no volume capability is provided
I1008 14:11:43.557]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:187
I1008 14:11:43.557] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.557] STEP: creating mount and staging directories
I1008 14:11:43.557] I1008 14:11:43.550256    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.557] I1008 14:11:43.550265    8854 utils.go:112] GRPC request: 
I1008 14:11:43.557] I1008 14:11:43.550270    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:43.558] I1008 14:11:43.550273    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:43.558] I1008 14:11:43.550539    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 14:11:43.558] I1008 14:11:43.550558    8854 utils.go:112] GRPC request: 
I1008 14:11:43.558] I1008 14:11:43.550562    8854 nodeserver.go:219] Using default NodeGetCapabilities
I1008 14:11:43.558] I1008 14:11:43.550569    8854 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 14:11:43.558] I1008 14:11:43.550832    8854 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 14:11:43.559] I1008 14:11:43.550841    8854 utils.go:112] GRPC request: volume_id:"id" target_path:"/tmp/csi" 
I1008 14:11:43.559] E1008 14:11:43.550845    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 14:11:43.559] •
I1008 14:11:43.559] ------------------------------
I1008 14:11:43.559] Node Service NodeUnpublishVolume 
I1008 14:11:43.559]   should fail when no volume id is provided
I1008 14:11:43.560]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:205
I1008 14:11:43.560] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.560] STEP: creating mount and staging directories
I1008 14:11:43.560] I1008 14:11:43.551270    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.560] I1008 14:11:43.551494    8854 utils.go:112] GRPC request: 
I1008 14:11:43.560] I1008 14:11:43.551568    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:43.561] I1008 14:11:43.551600    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:43.561] I1008 14:11:43.552053    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 14:11:43.561] I1008 14:11:43.552070    8854 utils.go:112] GRPC request: 
I1008 14:11:43.561] I1008 14:11:43.552092    8854 nodeserver.go:219] Using default NodeGetCapabilities
I1008 14:11:43.561] I1008 14:11:43.552099    8854 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 14:11:43.561] I1008 14:11:43.552575    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 14:11:43.562] I1008 14:11:43.552592    8854 utils.go:112] GRPC request: 
I1008 14:11:43.562] E1008 14:11:43.552599    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 14:11:43.562] •
I1008 14:11:43.562] ------------------------------
I1008 14:11:43.562] Node Service NodeUnpublishVolume 
I1008 14:11:43.562]   should fail when no target path is provided
I1008 14:11:43.562]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:217
I1008 14:11:43.563] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.563] STEP: creating mount and staging directories
I1008 14:11:43.563] I1008 14:11:43.553097    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.563] I1008 14:11:43.553113    8854 utils.go:112] GRPC request: 
I1008 14:11:43.563] I1008 14:11:43.553119    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:43.564] I1008 14:11:43.553125    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:43.564] I1008 14:11:43.553537    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 14:11:43.564] I1008 14:11:43.553551    8854 utils.go:112] GRPC request: 
I1008 14:11:43.564] I1008 14:11:43.553558    8854 nodeserver.go:219] Using default NodeGetCapabilities
I1008 14:11:43.564] I1008 14:11:43.553565    8854 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 14:11:43.564] I1008 14:11:43.553966    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 14:11:43.565] I1008 14:11:43.553989    8854 utils.go:112] GRPC request: volume_id:"id" 
I1008 14:11:43.565] E1008 14:11:43.554000    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
I1008 14:11:43.565] •
I1008 14:11:43.565] ------------------------------
I1008 14:11:43.565] Node Service NodeStageVolume 
I1008 14:11:43.565]   should fail when no volume id is provided
I1008 14:11:43.565]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 14:11:43.566] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.566] STEP: creating mount and staging directories
I1008 14:11:43.566] I1008 14:11:43.554505    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.566] I1008 14:11:43.554519    8854 utils.go:112] GRPC request: 
I1008 14:11:43.566] I1008 14:11:43.554525    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 14:11:43.567] 
I1008 14:11:43.567] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:43.567] Node Service
I1008 14:11:43.567] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:43.568]   NodeStageVolume
I1008 14:11:43.568]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 14:11:43.568]     should fail when no volume id is provided [BeforeEach]
I1008 14:11:43.568]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 14:11:43.568] 
I1008 14:11:43.568]     NodeStageVolume not supported
I1008 14:11:43.568] 
I1008 14:11:43.568]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 14:11:43.569] ------------------------------
I1008 14:11:43.569] Node Service NodeStageVolume 
I1008 14:11:43.569]   should fail when no staging target path is provided
I1008 14:11:43.569]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 14:11:43.569] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.569] STEP: creating mount and staging directories
I1008 14:11:43.569] I1008 14:11:43.556110    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.570] I1008 14:11:43.556127    8854 utils.go:112] GRPC request: 
I1008 14:11:43.570] I1008 14:11:43.556134    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 14:11:43.571] 
I1008 14:11:43.571] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:43.571] Node Service
I1008 14:11:43.571] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:43.571]   NodeStageVolume
I1008 14:11:43.571]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 14:11:43.571]     should fail when no staging target path is provided [BeforeEach]
I1008 14:11:43.572]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 14:11:43.572] 
I1008 14:11:43.572]     NodeStageVolume not supported
I1008 14:11:43.572] 
I1008 14:11:43.572]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 14:11:43.572] ------------------------------
I1008 14:11:43.572] Node Service NodeStageVolume 
I1008 14:11:43.572]   should fail when no volume capability is provided
I1008 14:11:43.572]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 14:11:43.573] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.573] STEP: creating mount and staging directories
I1008 14:11:43.573] I1008 14:11:43.557798    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.573] I1008 14:11:43.557810    8854 utils.go:112] GRPC request: 
I1008 14:11:43.573] I1008 14:11:43.557815    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 14:11:43.574] 
I1008 14:11:43.574] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 14:11:43.574] Node Service
I1008 14:11:43.574] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:43.575]   NodeStageVolume
I1008 14:11:43.575]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 14:11:43.575]     should fail when no volume capability is provided [BeforeEach]
I1008 14:11:43.575]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 14:11:43.575] 
I1008 14:11:43.575]     NodeStageVolume not supported
I1008 14:11:43.575] 
I1008 14:11:43.575]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 14:11:43.575] ------------------------------
I1008 14:11:43.576] Node Service NodeUnstageVolume 
I1008 14:11:43.576]   should fail when no volume id is provided
I1008 14:11:43.576]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 14:11:43.576] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.576] STEP: creating mount and staging directories
I1008 14:11:43.576] I1008 14:11:43.559669    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.577] I1008 14:11:43.559680    8854 utils.go:112] GRPC request: 
I1008 14:11:43.577] I1008 14:11:43.559684    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 14:11:43.578] 
I1008 14:11:43.578] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 14:11:43.578] Node Service
I1008 14:11:43.578] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:43.578]   NodeUnstageVolume
I1008 14:11:43.578]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 14:11:43.579]     should fail when no volume id is provided [BeforeEach]
I1008 14:11:43.579]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 14:11:43.579] 
I1008 14:11:43.579]     NodeUnstageVolume not supported
I1008 14:11:43.579] 
I1008 14:11:43.579]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 14:11:43.579] ------------------------------
I1008 14:11:43.579] Node Service NodeUnstageVolume 
I1008 14:11:43.580]   should fail when no staging target path is provided
I1008 14:11:43.580]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 14:11:43.580] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:43.580] STEP: creating mount and staging directories
I1008 14:11:43.580] I1008 14:11:43.561328    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:43.580] I1008 14:11:43.561349    8854 utils.go:112] GRPC request: 
I1008 14:11:43.581] I1008 14:11:43.561355    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 14:11:43.582] 
I1008 14:11:43.582] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 14:11:43.582] Node Service
I1008 14:11:43.582] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:43.582]   NodeUnstageVolume
I1008 14:11:43.582]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 14:11:43.583]     should fail when no staging target path is provided [BeforeEach]
I1008 14:11:43.583]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 14:11:43.583] 
I1008 14:11:43.583]     NodeUnstageVolume not supported
I1008 14:11:43.583] 
I1008 14:11:43.583]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 14:11:43.583] ------------------------------
... skipping 32 lines ...
I1008 14:11:43.838] 
I1008 14:11:43.838] readonly false
I1008 14:11:43.839] volumeId #f80da8236e9d511e9aef042#sanity-node-full-d5fcb7e1-f016a75b
I1008 14:11:43.839] context map[]
I1008 14:11:43.839] mountflags []
I1008 14:11:43.935] I1008 14:11:43.934653    8854 mount_linux.go:138] Mounting cmd (mount) with arguments ([-t cifs -o username=f80da8236e9d511e9aef042,password=RNs6teQia/DruDox7aUGnk7B6QN1xnuo9iBCmjb5S8yzxVDcvW69aGkc4abVJtPusJKbgZbWA/XfC+1m47+b1g==,file_mode=0777,dir_mode=0777,vers=3.0 //f80da8236e9d511e9aef042.file.core.windows.net/sanity-node-full-d5fcb7e1-f016a75b /tmp/csi])
I1008 14:11:44.065] E1008 14:11:44.064689    8854 mount_linux.go:143] Mount failed: exit status 32
I1008 14:11:44.065] Mounting command: mount
I1008 14:11:44.066] Mounting arguments: -t cifs -o username=f80da8236e9d511e9aef042,password=RNs6teQia/DruDox7aUGnk7B6QN1xnuo9iBCmjb5S8yzxVDcvW69aGkc4abVJtPusJKbgZbWA/XfC+1m47+b1g==,file_mode=0777,dir_mode=0777,vers=3.0 //f80da8236e9d511e9aef042.file.core.windows.net/sanity-node-full-d5fcb7e1-f016a75b /tmp/csi
I1008 14:11:44.066] Output: mount error: cifs filesystem not supported by the system
I1008 14:11:44.066] mount error(19): No such device
I1008 14:11:44.066] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 14:11:44.066] 
I1008 14:11:44.066] E1008 14:11:44.064890    8854 utils.go:116] GRPC error: mount failed: exit status 32
I1008 14:11:44.066] Mounting command: mount
I1008 14:11:44.067] Mounting arguments: -t cifs -o username=f80da8236e9d511e9aef042,password=RNs6teQia/DruDox7aUGnk7B6QN1xnuo9iBCmjb5S8yzxVDcvW69aGkc4abVJtPusJKbgZbWA/XfC+1m47+b1g==,file_mode=0777,dir_mode=0777,vers=3.0 //f80da8236e9d511e9aef042.file.core.windows.net/sanity-node-full-d5fcb7e1-f016a75b /tmp/csi
I1008 14:11:44.067] Output: mount error: cifs filesystem not supported by the system
I1008 14:11:44.067] mount error(19): No such device
I1008 14:11:44.067] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 14:11:44.067] 
I1008 14:11:44.068] cleanup: deleting sanity-node-full-D5FCB7E1-F016A75B = #f80da8236e9d511e9aef042#sanity-node-full-d5fcb7e1-f016a75b
I1008 14:11:44.068] I1008 14:11:44.067327    8854 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 14:11:44.068] I1008 14:11:44.067343    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-node-full-d5fcb7e1-f016a75b" target_path:"/tmp/csi" 
I1008 14:11:44.068] I1008 14:11:44.067351    8854 mount_linux.go:203] Unmounting /tmp/csi
I1008 14:11:44.074] E1008 14:11:44.073657    8854 utils.go:116] GRPC error: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 14:11:44.075] Unmounting arguments: /tmp/csi
I1008 14:11:44.075] Output: umount: /tmp/csi: mountpoint not found
I1008 14:11:44.075] 
I1008 14:11:44.075] cleanup: warning: NodeUnpublishVolume: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 14:11:44.075] Unmounting arguments: /tmp/csi
I1008 14:11:44.075] Output: umount: /tmp/csi: mountpoint not found
I1008 14:11:44.076] 
I1008 14:11:44.076] I1008 14:11:44.074274    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 14:11:44.076] I1008 14:11:44.074293    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-node-full-d5fcb7e1-f016a75b" 
I1008 14:11:44.216] I1008 14:11:44.213887    8854 controllerserver.go:149] azure file(sanity-node-full-d5fcb7e1-f016a75b) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#sanity-node-full-d5fcb7e1-f016a75b) is deleted successfully
... skipping 2 lines ...
I1008 14:11:44.216] • Failure [0.651 seconds]
I1008 14:11:44.217] Node Service
I1008 14:11:44.217] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:44.217]   should work [It]
I1008 14:11:44.217]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:353
I1008 14:11:44.217] 
I1008 14:11:44.217]   Expected error:
I1008 14:11:44.217]       <*status.statusError | 0xc000408af0>: {
I1008 14:11:44.218]           Code: 2,
I1008 14:11:44.218]           Message: "mount failed: exit status 32\nMounting command: mount\nMounting arguments: -t cifs -o username=f80da8236e9d511e9aef042,password=RNs6teQia/DruDox7aUGnk7B6QN1xnuo9iBCmjb5S8yzxVDcvW69aGkc4abVJtPusJKbgZbWA/XfC+1m47+b1g==,file_mode=0777,dir_mode=0777,vers=3.0 //f80da8236e9d511e9aef042.file.core.windows.net/sanity-node-full-d5fcb7e1-f016a75b /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 14:11:44.218]           Details: nil,
I1008 14:11:44.219]           XXX_NoUnkeyedLiteral: {},
I1008 14:11:44.219]           XXX_unrecognized: nil,
I1008 14:11:44.219]           XXX_sizecache: 0,
I1008 14:11:44.219]       }
I1008 14:11:44.219]       rpc error: code = Unknown desc = mount failed: exit status 32
I1008 14:11:44.219]       Mounting command: mount
I1008 14:11:44.220]       Mounting arguments: -t cifs -o username=f80da8236e9d511e9aef042,password=RNs6teQia/DruDox7aUGnk7B6QN1xnuo9iBCmjb5S8yzxVDcvW69aGkc4abVJtPusJKbgZbWA/XfC+1m47+b1g==,file_mode=0777,dir_mode=0777,vers=3.0 //f80da8236e9d511e9aef042.file.core.windows.net/sanity-node-full-d5fcb7e1-f016a75b /tmp/csi
I1008 14:11:44.220]       Output: mount error: cifs filesystem not supported by the system
I1008 14:11:44.220]       mount error(19): No such device
I1008 14:11:44.220]       Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 14:11:44.220]       
I1008 14:11:44.220]       
I1008 14:11:44.221]   not to have occurred
I1008 14:11:44.221] 
I1008 14:11:44.221]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
... skipping 52 lines ...
I1008 14:11:44.229] 
I1008 14:11:44.229]     ListVolumes not supported
I1008 14:11:44.229] 
I1008 14:11:44.229]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:157
I1008 14:11:44.229] ------------------------------
I1008 14:11:44.229] Controller Service CreateVolume 
I1008 14:11:44.229]   should fail when no name is provided
I1008 14:11:44.229]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:186
I1008 14:11:44.230] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:44.230] STEP: creating mount and staging directories
I1008 14:11:44.230] I1008 14:11:44.217530    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:44.230] I1008 14:11:44.217545    8854 utils.go:112] GRPC request: 
I1008 14:11:44.230] I1008 14:11:44.217559    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:44.230] I1008 14:11:44.217565    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:44.230] I1008 14:11:44.218023    8854 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 14:11:44.230] I1008 14:11:44.218041    8854 utils.go:112] GRPC request: 
I1008 14:11:44.231] E1008 14:11:44.218050    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
I1008 14:11:44.231] •
I1008 14:11:44.231] ------------------------------
I1008 14:11:44.231] Controller Service CreateVolume 
I1008 14:11:44.231]   should fail when no volume capabilities are provided
I1008 14:11:44.231]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:202
I1008 14:11:44.231] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:44.231] STEP: creating mount and staging directories
I1008 14:11:44.231] I1008 14:11:44.218572    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:44.232] I1008 14:11:44.218587    8854 utils.go:112] GRPC request: 
I1008 14:11:44.232] I1008 14:11:44.218594    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:44.232] I1008 14:11:44.218601    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:44.232] I1008 14:11:44.219053    8854 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 14:11:44.232] I1008 14:11:44.219069    8854 utils.go:112] GRPC request: name:"sanity-controller-create-no-volume-capabilities-D5FCB7E1-F016A75B" 
I1008 14:11:44.232] E1008 14:11:44.219077    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities must be provided
I1008 14:11:44.232] •
I1008 14:11:44.232] ------------------------------
I1008 14:11:44.233] Controller Service CreateVolume 
I1008 14:11:44.233]   should return appropriate values SingleNodeWriter NoCapacity Type:Mount
I1008 14:11:44.233]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:220
I1008 14:11:44.233] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 40 lines ...
I1008 14:11:44.816] I1008 14:11:44.814661    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-controller-create-single-with-capacity-d5fcb7e1-f016a75b" 
I1008 14:11:45.032] I1008 14:11:45.031552    8854 controllerserver.go:149] azure file(sanity-controller-create-single-with-capacity-d5fcb7e1-f016a75b) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#sanity-controller-create-single-with-capacity-d5fcb7e1-f016a75b) is deleted successfully
I1008 14:11:45.033] I1008 14:11:45.031576    8854 utils.go:118] GRPC response: 
I1008 14:11:45.033] •
I1008 14:11:45.034] ------------------------------
I1008 14:11:45.034] Controller Service CreateVolume 
I1008 14:11:45.034]   should not fail when requesting to create a volume with already existing name and same capacity.
I1008 14:11:45.034]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:311
I1008 14:11:45.034] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:45.034] STEP: creating mount and staging directories
I1008 14:11:45.034] I1008 14:11:45.032252    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:45.034] I1008 14:11:45.032267    8854 utils.go:112] GRPC request: 
I1008 14:11:45.034] I1008 14:11:45.032274    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 19 lines ...
I1008 14:11:45.510] I1008 14:11:45.509858    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-controller-create-twice-d5fcb7e1-f016a75b" 
I1008 14:11:45.623] I1008 14:11:45.621958    8854 controllerserver.go:149] azure file(sanity-controller-create-twice-d5fcb7e1-f016a75b) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#sanity-controller-create-twice-d5fcb7e1-f016a75b) is deleted successfully
I1008 14:11:45.624] I1008 14:11:45.621984    8854 utils.go:118] GRPC response: 
I1008 14:11:45.624] •
I1008 14:11:45.624] ------------------------------
I1008 14:11:45.624] Controller Service CreateVolume 
I1008 14:11:45.624]   should fail when requesting to create a volume with already existing name and different capacity.
I1008 14:11:45.625]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:385
I1008 14:11:45.625] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:45.625] STEP: creating mount and staging directories
I1008 14:11:45.625] I1008 14:11:45.622704    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:45.625] I1008 14:11:45.622722    8854 utils.go:112] GRPC request: 
I1008 14:11:45.626] I1008 14:11:45.622728    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 10 lines ...
I1008 14:11:45.880] I1008 14:11:45.877291    8854 utils.go:112] GRPC request: name:"sanity-controller-create-twice-different-D5FCB7E1-F016A75B" capacity_range:<required_bytes:21474836480 limit_bytes:21474836480 > volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 14:11:45.880] I1008 14:11:45.877304    8854 controllerserver.go:95] begin to create file share(sanity-controller-create-twice-different-d5fcb7e1-f016a75b) on account() type() rg() location() size(20)
I1008 14:11:45.947] I1008 14:11:45.947154    8854 azure_storageaccount.go:103] found a matching account f80da8236e9d511e9aef042 type Standard_LRS location eastus2
I1008 14:11:46.109] I1008 14:11:46.108897    8854 azure_file.go:68] file share(sanity-controller-create-twice-different-d5fcb7e1-f016a75b) under account(f80da8236e9d511e9aef042) already exists
I1008 14:11:46.109] I1008 14:11:46.108925    8854 azure_storage.go:49] created share sanity-controller-create-twice-different-d5fcb7e1-f016a75b in account f80da8236e9d511e9aef042
I1008 14:11:46.109] I1008 14:11:46.108937    8854 controllerserver.go:108] create file share sanity-controller-create-twice-different-d5fcb7e1-f016a75b on storage account f80da8236e9d511e9aef042 successfully
I1008 14:11:46.145] E1008 14:11:46.144541    8854 utils.go:116] GRPC error: rpc error: code = AlreadyExists desc = the request volume already exists, but its capacity(10) is different from (20)
I1008 14:11:46.146] STEP: cleaning up deleting the volume
I1008 14:11:46.146] I1008 14:11:46.145204    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 14:11:46.146] I1008 14:11:46.145221    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-controller-create-twice-different-d5fcb7e1-f016a75b" 
I1008 14:11:46.280] I1008 14:11:46.279534    8854 controllerserver.go:149] azure file(sanity-controller-create-twice-different-d5fcb7e1-f016a75b) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#sanity-controller-create-twice-different-d5fcb7e1-f016a75b) is deleted successfully
I1008 14:11:46.280] I1008 14:11:46.279560    8854 utils.go:118] GRPC response: 
I1008 14:11:46.281] •
I1008 14:11:46.281] ------------------------------
I1008 14:11:46.281] Controller Service CreateVolume 
I1008 14:11:46.281]   should not fail when creating volume with maximum-length name
I1008 14:11:46.281]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:460
I1008 14:11:46.281] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:46.281] STEP: creating mount and staging directories
I1008 14:11:46.281] I1008 14:11:46.280232    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:46.281] I1008 14:11:46.280250    8854 utils.go:112] GRPC request: 
I1008 14:11:46.282] I1008 14:11:46.280256    8854 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 11 lines ...
I1008 14:11:46.521] I1008 14:11:46.520774    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa" 
I1008 14:11:46.659] I1008 14:11:46.656629    8854 controllerserver.go:149] azure file(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) is deleted successfully
I1008 14:11:46.660] I1008 14:11:46.656659    8854 utils.go:118] GRPC response: 
I1008 14:11:46.660] •
I1008 14:11:46.660] ------------------------------
I1008 14:11:46.660] Controller Service DeleteVolume 
I1008 14:11:46.660]   should fail when no volume id is provided
I1008 14:11:46.660]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:519
I1008 14:11:46.660] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:46.660] STEP: creating mount and staging directories
I1008 14:11:46.661] I1008 14:11:46.657397    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:46.661] I1008 14:11:46.657414    8854 utils.go:112] GRPC request: 
I1008 14:11:46.661] I1008 14:11:46.657420    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:46.661] I1008 14:11:46.657424    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:46.662] I1008 14:11:46.657832    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 14:11:46.662] I1008 14:11:46.657843    8854 utils.go:112] GRPC request: 
I1008 14:11:46.662] E1008 14:11:46.657848    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 14:11:46.662] •
I1008 14:11:46.662] ------------------------------
I1008 14:11:46.663] Controller Service DeleteVolume 
I1008 14:11:46.663]   should succeed when an invalid volume id is used
I1008 14:11:46.663]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:534
I1008 14:11:46.663] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:46.663] STEP: creating mount and staging directories
I1008 14:11:46.663] I1008 14:11:46.658158    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:46.664] I1008 14:11:46.658167    8854 utils.go:112] GRPC request: 
I1008 14:11:46.664] I1008 14:11:46.658171    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:46.664] I1008 14:11:46.658175    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:46.664] I1008 14:11:46.658485    8854 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 14:11:46.664] I1008 14:11:46.658496    8854 utils.go:112] GRPC request: volume_id:"reallyfakevolumeid" 
I1008 14:11:46.665] E1008 14:11:46.658509    8854 controllerserver.go:342] getFileShareInfo(reallyfakevolumeid) failed with error: error parsing volume id: "reallyfakevolumeid", should at least contain two #
I1008 14:11:46.665] I1008 14:11:46.658518    8854 controllerserver.go:137] failed to get share url with (reallyfakevolumeid): error parsing volume id: "reallyfakevolumeid", should at least contain two #, returning with success
I1008 14:11:46.665] I1008 14:11:46.658525    8854 utils.go:118] GRPC response: 
I1008 14:11:46.665] •
I1008 14:11:46.665] ------------------------------
I1008 14:11:46.665] Controller Service DeleteVolume 
I1008 14:11:46.666]   should return appropriate values (no optional values added)
I1008 14:11:46.666]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:546
... skipping 17 lines ...
I1008 14:11:46.881] I1008 14:11:46.880953    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-controller-create-appropriate-d5fcb7e1-f016a75b" 
I1008 14:11:47.012] I1008 14:11:47.009995    8854 controllerserver.go:149] azure file(sanity-controller-create-appropriate-d5fcb7e1-f016a75b) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#sanity-controller-create-appropriate-d5fcb7e1-f016a75b) is deleted successfully
I1008 14:11:47.012] I1008 14:11:47.010027    8854 utils.go:118] GRPC response: 
I1008 14:11:47.012] •
I1008 14:11:47.012] ------------------------------
I1008 14:11:47.013] Controller Service ValidateVolumeCapabilities 
I1008 14:11:47.013]   should fail when no volume id is provided
I1008 14:11:47.013]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:592
I1008 14:11:47.013] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.013] STEP: creating mount and staging directories
I1008 14:11:47.013] I1008 14:11:47.010865    8854 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 14:11:47.013] I1008 14:11:47.010879    8854 utils.go:112] GRPC request: 
I1008 14:11:47.013] E1008 14:11:47.010885    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 14:11:47.013] •
I1008 14:11:47.013] ------------------------------
I1008 14:11:47.014] Controller Service ValidateVolumeCapabilities 
I1008 14:11:47.014]   should fail when no volume capabilities are provided
I1008 14:11:47.014]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:604
I1008 14:11:47.014] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.014] STEP: creating mount and staging directories
I1008 14:11:47.014] I1008 14:11:47.011295    8854 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 14:11:47.014] I1008 14:11:47.011306    8854 utils.go:112] GRPC request: volume_id:"id" 
I1008 14:11:47.014] E1008 14:11:47.011311    8854 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities missing in request
I1008 14:11:47.015] •
I1008 14:11:47.015] ------------------------------
I1008 14:11:47.015] Controller Service ValidateVolumeCapabilities 
I1008 14:11:47.015]   should return appropriate values (no optional values added)
I1008 14:11:47.015]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:618
I1008 14:11:47.015] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 16 lines ...
I1008 14:11:47.386] I1008 14:11:47.384663    8854 utils.go:112] GRPC request: volume_id:"#f80da8236e9d511e9aef042#sanity-controller-validate-d5fcb7e1-f016a75b" 
I1008 14:11:47.524] I1008 14:11:47.520298    8854 controllerserver.go:149] azure file(sanity-controller-validate-d5fcb7e1-f016a75b) under rg() account(f80da8236e9d511e9aef042) volumeID(#f80da8236e9d511e9aef042#sanity-controller-validate-d5fcb7e1-f016a75b) is deleted successfully
I1008 14:11:47.524] I1008 14:11:47.520324    8854 utils.go:118] GRPC response: 
I1008 14:11:47.525] •
I1008 14:11:47.525] ------------------------------
I1008 14:11:47.525] Controller Service ValidateVolumeCapabilities 
I1008 14:11:47.525]   should fail when the requested volume does not exist
I1008 14:11:47.525]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:687
I1008 14:11:47.525] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.525] STEP: creating mount and staging directories
I1008 14:11:47.525] I1008 14:11:47.521179    8854 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 14:11:47.526] I1008 14:11:47.521197    8854 utils.go:112] GRPC request: volume_id:"some-vol-id" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 14:11:47.526] E1008 14:11:47.521208    8854 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 14:11:47.526] •
I1008 14:11:47.526] ------------------------------
I1008 14:11:47.526] Controller Service ControllerPublishVolume 
I1008 14:11:47.527]   should fail when no volume id is provided
I1008 14:11:47.527]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 14:11:47.527] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.527] STEP: creating mount and staging directories
I1008 14:11:47.527] I1008 14:11:47.521681    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.527] I1008 14:11:47.521691    8854 utils.go:112] GRPC request: 
I1008 14:11:47.528] I1008 14:11:47.521697    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.528] I1008 14:11:47.521701    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.528] 
I1008 14:11:47.528] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:47.528] Controller Service
I1008 14:11:47.528] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.528]   ControllerPublishVolume
I1008 14:11:47.528]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 14:11:47.529]     should fail when no volume id is provided [BeforeEach]
I1008 14:11:47.529]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 14:11:47.529] 
I1008 14:11:47.529]     ControllerPublishVolume not supported
I1008 14:11:47.529] 
I1008 14:11:47.529]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.529] ------------------------------
I1008 14:11:47.529] Controller Service ControllerPublishVolume 
I1008 14:11:47.530]   should fail when no node id is provided
I1008 14:11:47.530]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 14:11:47.530] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.530] STEP: creating mount and staging directories
I1008 14:11:47.530] I1008 14:11:47.522540    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.531] I1008 14:11:47.522552    8854 utils.go:112] GRPC request: 
I1008 14:11:47.531] I1008 14:11:47.522556    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.531] I1008 14:11:47.522560    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.531] 
I1008 14:11:47.531] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:47.532] Controller Service
I1008 14:11:47.532] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.532]   ControllerPublishVolume
I1008 14:11:47.532]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 14:11:47.532]     should fail when no node id is provided [BeforeEach]
I1008 14:11:47.532]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 14:11:47.533] 
I1008 14:11:47.533]     ControllerPublishVolume not supported
I1008 14:11:47.533] 
I1008 14:11:47.533]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.533] ------------------------------
I1008 14:11:47.533] Controller Service ControllerPublishVolume 
I1008 14:11:47.534]   should fail when no volume capability is provided
I1008 14:11:47.534]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 14:11:47.534] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.534] STEP: creating mount and staging directories
I1008 14:11:47.534] I1008 14:11:47.523260    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.535] I1008 14:11:47.523270    8854 utils.go:112] GRPC request: 
I1008 14:11:47.535] I1008 14:11:47.523275    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.535] I1008 14:11:47.523279    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.535] 
I1008 14:11:47.535] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:47.535] Controller Service
I1008 14:11:47.536] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.536]   ControllerPublishVolume
I1008 14:11:47.536]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 14:11:47.536]     should fail when no volume capability is provided [BeforeEach]
I1008 14:11:47.536]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 14:11:47.536] 
I1008 14:11:47.536]     ControllerPublishVolume not supported
I1008 14:11:47.537] 
I1008 14:11:47.537]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.537] ------------------------------
... skipping 17 lines ...
I1008 14:11:47.539] 
I1008 14:11:47.540]     ControllerPublishVolume not supported
I1008 14:11:47.540] 
I1008 14:11:47.540]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.540] ------------------------------
I1008 14:11:47.540] Controller Service ControllerPublishVolume 
I1008 14:11:47.540]   should fail when the volume does not exist
I1008 14:11:47.540]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 14:11:47.540] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.540] STEP: creating mount and staging directories
I1008 14:11:47.541] I1008 14:11:47.524642    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.541] I1008 14:11:47.524653    8854 utils.go:112] GRPC request: 
I1008 14:11:47.541] I1008 14:11:47.524657    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.541] I1008 14:11:47.524661    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.541] 
I1008 14:11:47.541] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:47.541] Controller Service
I1008 14:11:47.541] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.541]   ControllerPublishVolume
I1008 14:11:47.542]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 14:11:47.542]     should fail when the volume does not exist [BeforeEach]
I1008 14:11:47.542]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 14:11:47.542] 
I1008 14:11:47.542]     ControllerPublishVolume not supported
I1008 14:11:47.542] 
I1008 14:11:47.542]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.542] ------------------------------
I1008 14:11:47.542] Controller Service ControllerPublishVolume 
I1008 14:11:47.542]   should fail when the node does not exist
I1008 14:11:47.543]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 14:11:47.543] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.543] STEP: creating mount and staging directories
I1008 14:11:47.543] I1008 14:11:47.525434    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.543] I1008 14:11:47.525445    8854 utils.go:112] GRPC request: 
I1008 14:11:47.543] I1008 14:11:47.525449    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.544] I1008 14:11:47.525453    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.544] 
I1008 14:11:47.544] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:47.544] Controller Service
I1008 14:11:47.544] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.544]   ControllerPublishVolume
I1008 14:11:47.544]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 14:11:47.544]     should fail when the node does not exist [BeforeEach]
I1008 14:11:47.545]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 14:11:47.545] 
I1008 14:11:47.545]     ControllerPublishVolume not supported
I1008 14:11:47.545] 
I1008 14:11:47.545]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.545] ------------------------------
I1008 14:11:47.545] Controller Service ControllerPublishVolume 
I1008 14:11:47.545]   should fail when the volume is already published but is incompatible
I1008 14:11:47.545]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 14:11:47.545] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.546] STEP: creating mount and staging directories
I1008 14:11:47.546] I1008 14:11:47.526600    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.546] I1008 14:11:47.526661    8854 utils.go:112] GRPC request: 
I1008 14:11:47.546] I1008 14:11:47.526674    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.546] I1008 14:11:47.526678    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.546] 
I1008 14:11:47.546] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 14:11:47.546] Controller Service
I1008 14:11:47.546] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.547]   ControllerPublishVolume
I1008 14:11:47.547]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 14:11:47.547]     should fail when the volume is already published but is incompatible [BeforeEach]
I1008 14:11:47.547]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 14:11:47.547] 
I1008 14:11:47.547]     ControllerPublishVolume not supported
I1008 14:11:47.547] 
I1008 14:11:47.547]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 14:11:47.547] ------------------------------
I1008 14:11:47.547] Controller Service ControllerUnpublishVolume 
I1008 14:11:47.548]   should fail when no volume id is provided
I1008 14:11:47.548]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 14:11:47.548] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 14:11:47.548] STEP: creating mount and staging directories
I1008 14:11:47.548] I1008 14:11:47.528333    8854 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 14:11:47.548] I1008 14:11:47.528518    8854 utils.go:112] GRPC request: 
I1008 14:11:47.548] I1008 14:11:47.528529    8854 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 14:11:47.548] I1008 14:11:47.528626    8854 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 14:11:47.549] 
I1008 14:11:47.549] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 14:11:47.549] Controller Service
I1008 14:11:47.549] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 14:11:47.549]   ControllerUnpublishVolume
I1008 14:11:47.549]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1059
I1008 14:11:47.549]     should fail when no volume id is provided [BeforeEach]
I1008 14:11:47.549]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 14:11:47.549] 
I1008 14:11:47.549]     ControllerUnpublishVolume not supported
I1008 14:11:47.550] 
I1008 14:11:47.550]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 14:11:47.550] ------------------------------
... skipping 160 lines ...
I1008 14:11:47.577]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1182
I1008 14:11:47.577] ------------------------------
I1008 14:11:47.577] 
I1008 14:11:47.577] 
I1008 14:11:47.577] Summarizing 1 Failure:
I1008 14:11:47.577] 
I1008 14:11:47.577] [Fail] Node Service [It] should work 
I1008 14:11:47.578] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
I1008 14:11:47.578] 
I1008 14:11:47.578] Ran 34 of 57 Specs in 28.054 seconds
I1008 14:11:47.578] FAIL! -- 33 Passed | 1 Failed | 0 Pending | 23 Skipped
I1008 14:11:47.578] --- FAIL: TestSanity (28.07s)
I1008 14:11:47.578] FAIL
I1008 14:11:47.578] pkill -f azurefileplugin
I1008 14:11:47.578] Deleting CSI sanity test binary
I1008 14:11:47.635] test/sanity/run-test.sh: line 19:  8854 Terminated              _output/azurefileplugin --endpoint "$endpoint" --nodeid "$nodeid" -v=5
I1008 14:11:47.635] 2019/10/08 14:11:47 Deleting resource group azurefile-csi-driver-test-72b91008-e9d5-11e9-9301-4237e9219e29
I1008 14:14:04.215] --- FAIL: TestSanity (186.86s)
I1008 14:14:04.215]     sanity_test.go:78: Sanity test failed exit status 1
I1008 14:14:04.215] FAIL
I1008 14:14:04.221] FAIL	github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity	186.878s
I1008 14:14:04.313] Makefile:54: recipe for target 'sanity-test' failed
W1008 14:14:04.325] make: *** [sanity-test] Error 1
W1008 14:14:04.326] Traceback (most recent call last):
W1008 14:14:04.326]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module>
W1008 14:14:04.326]     main(ARGS.env, ARGS.cmd + ARGS.args)
W1008 14:14:04.326]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main
W1008 14:14:04.326]     check(*cmd)
W1008 14:14:04.327]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check
W1008 14:14:04.327]     subprocess.check_call(cmd)
W1008 14:14:04.327]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W1008 14:14:04.327]     raise CalledProcessError(retcode, cmd)
W1008 14:14:04.327] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2
E1008 14:14:04.328] Command failed
I1008 14:14:04.329] process 456 exited with code 1 after 4.1m
E1008 14:14:04.329] FAIL: pull-azurefile-csi-driver-sanity
I1008 14:14:04.330] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1008 14:14:04.935] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1008 14:14:05.004] process 8882 exited with code 0 after 0.0m
I1008 14:14:05.004] Call:  gcloud config get-value account
I1008 14:14:05.389] process 8894 exited with code 0 after 0.0m
I1008 14:14:05.390] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
... skipping 27 lines ...