This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2019-10-09 16:18
Elapsed4m15s
Revision
Buildergke-prow-ssd-pool-1a225945-08s9
Refs master:bd996ff3
120:88d0fbfd
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/fb51cd3c-ed33-4c32-bd2a-19bfc11258e0/targets/test'}}
pod48967e0e-eab0-11e9-8806-b65fa96d8677
resultstorehttps://source.cloud.google.com/results/invocations/fb51cd3c-ed33-4c32-bd2a-19bfc11258e0/targets/test
infra-commitefb747517
pod48967e0e-eab0-11e9-8806-b65fa96d8677
reposigs.k8s.io/azurefile-csi-driver
repo-commit805050614da9e862384ec27de19fbc34a1a27499
repos{u'sigs.k8s.io/azurefile-csi-driver': u'master:bd996ff37f2d725eac026d19a820ba7afeb19293,120:88d0fbfd4574352a60b649b1271fe4b05e9d6079'}

No Test Failures!


Error lines from build-log.txt

... skipping 534 lines ...
I1009 16:20:07.157] Running Suite: CSI Driver Test Suite
I1009 16:20:07.157] ====================================
I1009 16:20:07.158] Random Seed: 1570638007
I1009 16:20:07.158] Will run 71 of 72 specs
I1009 16:20:07.159] 
I1009 16:20:07.161] CreateSnapshot [Controller Server] 
I1009 16:20:07.161]   should fail when no name is provided
I1009 16:20:07.161]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1939
I1009 16:20:07.162] STEP: connecting to CSI driver
I1009 16:20:07.184] I1009 16:20:07.184268    8806 main.go:82] set up prometheus server on [::]:10252
I1009 16:20:07.185] I1009 16:20:07.185779    8806 azurefile.go:84] 
I1009 16:20:07.186] DRIVER INFORMATION:
I1009 16:20:07.186] -------------------
... skipping 23 lines ...
I1009 16:20:08.168] I1009 16:20:08.167767    8806 utils.go:112] GRPC request: 
I1009 16:20:08.168] I1009 16:20:08.167787    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:08.168] I1009 16:20:08.167800    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:08.170] I1009 16:20:08.169876    8806 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1009 16:20:08.170] I1009 16:20:08.170502    8806 utils.go:112] GRPC request: source_volume_id:"testId" 
I1009 16:20:08.171] I1009 16:20:08.170927    8806 controllerserver.go:214] CreateSnapshot called with request {testId  map[] map[] {} [] 0}
I1009 16:20:08.171] E1009 16:20:08.171364    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
I1009 16:20:08.172] •
I1009 16:20:08.172] ------------------------------
I1009 16:20:08.173] CreateSnapshot [Controller Server] 
I1009 16:20:08.173]   should fail when no source volume id is provided
I1009 16:20:08.173]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1956
I1009 16:20:08.174] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:08.174] STEP: creating mount and staging directories
I1009 16:20:08.180] I1009 16:20:08.180288    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:08.181] I1009 16:20:08.180312    8806 utils.go:112] GRPC request: 
I1009 16:20:08.181] I1009 16:20:08.180319    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:08.182] I1009 16:20:08.180324    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:08.182] I1009 16:20:08.181025    8806 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1009 16:20:08.182] I1009 16:20:08.181060    8806 utils.go:112] GRPC request: name:"name" 
I1009 16:20:08.183] I1009 16:20:08.181070    8806 controllerserver.go:214] CreateSnapshot called with request { name map[] map[] {} [] 0}
I1009 16:20:08.183] E1009 16:20:08.181102    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
I1009 16:20:08.183] •
I1009 16:20:08.183] ------------------------------
I1009 16:20:08.183] CreateSnapshot [Controller Server] 
I1009 16:20:08.184]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1009 16:20:08.184]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1973
I1009 16:20:08.184] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:08.184] STEP: creating mount and staging directories
I1009 16:20:08.184] I1009 16:20:08.181927    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:08.185] I1009 16:20:08.182002    8806 utils.go:112] GRPC request: 
I1009 16:20:08.185] I1009 16:20:08.182026    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 29 lines ...
I1009 16:20:28.863] I1009 16:20:28.860879    8806 controllerserver.go:149] azure file(createsnapshot-volume-1) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#createsnapshot-volume-1) is deleted successfully
I1009 16:20:28.863] I1009 16:20:28.861073    8806 utils.go:118] GRPC response: 
I1009 16:20:28.863] 
I1009 16:20:28.863] • [SLOW TEST:20.680 seconds]
I1009 16:20:28.863] CreateSnapshot [Controller Server]
I1009 16:20:28.864] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:28.864]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1009 16:20:28.864]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1973
I1009 16:20:28.864] ------------------------------
I1009 16:20:28.864] CreateSnapshot [Controller Server] 
I1009 16:20:28.864]   should fail when requesting to create a snapshot with already existing name and different SourceVolumeId.
I1009 16:20:28.865]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2003
I1009 16:20:28.865] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:28.865] STEP: creating mount and staging directories
I1009 16:20:28.865] I1009 16:20:28.862942    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:28.865] I1009 16:20:28.863286    8806 utils.go:112] GRPC request: 
I1009 16:20:28.865] I1009 16:20:28.863426    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 20 lines ...
I1009 16:20:29.664] I1009 16:20:29.663098    8806 controllerserver.go:108] create file share createsnapshot-volume-3 on storage account fa9480518eab011e98d7e9a successfully
I1009 16:20:29.701] I1009 16:20:29.700007    8806 utils.go:118] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"#fa9480518eab011e98d7e9a#createsnapshot-volume-3" > 
I1009 16:20:29.701] STEP: creating a snapshot with the same name but different volume source id
I1009 16:20:29.701] I1009 16:20:29.701052    8806 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1009 16:20:29.702] I1009 16:20:29.701100    8806 utils.go:112] GRPC request: source_volume_id:"#fa9480518eab011e98d7e9a#createsnapshot-volume-3" name:"CreateSnapshot-snapshot-2" 
I1009 16:20:29.702] I1009 16:20:29.701118    8806 controllerserver.go:214] CreateSnapshot called with request {#fa9480518eab011e98d7e9a#createsnapshot-volume-3 CreateSnapshot-snapshot-2 map[] map[] {} [] 0}
I1009 16:20:29.837] E1009 16:20:29.835541    8806 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(#fa9480518eab011e98d7e9a#createsnapshot-volume-3)
I1009 16:20:29.837] STEP: cleaning up deleting the snapshot
I1009 16:20:29.838] I1009 16:20:29.836256    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1009 16:20:29.838] I1009 16:20:29.836324    8806 utils.go:112] GRPC request: snapshot_id:"#fa9480518eab011e98d7e9a#createsnapshot-volume-2#2019-10-09T16:20:29.0000000Z" 
I1009 16:20:29.956] I1009 16:20:29.954802    8806 controllerserver.go:313] delete snapshot(2019-10-09T16:20:29.0000000Z) successfully
I1009 16:20:29.957] I1009 16:20:29.954912    8806 utils.go:118] GRPC response: 
I1009 16:20:29.957] STEP: cleaning up deleting the volume
I1009 16:20:29.958] I1009 16:20:29.955578    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1009 16:20:29.958] I1009 16:20:29.955614    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#createsnapshot-volume-2" 
I1009 16:20:30.108] I1009 16:20:30.107183    8806 controllerserver.go:149] azure file(createsnapshot-volume-2) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#createsnapshot-volume-2) is deleted successfully
I1009 16:20:30.109] I1009 16:20:30.107220    8806 utils.go:118] GRPC response: 
I1009 16:20:30.109] •
I1009 16:20:30.109] ------------------------------
I1009 16:20:30.109] CreateSnapshot [Controller Server] 
I1009 16:20:30.109]   should not fail when creating snapshot with maximum-length name
I1009 16:20:30.110]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2038
I1009 16:20:30.110] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:30.110] STEP: creating mount and staging directories
I1009 16:20:30.110] I1009 16:20:30.108495    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:30.111] I1009 16:20:30.108597    8806 utils.go:112] GRPC request: 
I1009 16:20:30.111] I1009 16:20:30.108632    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 29 lines ...
I1009 16:20:30.974] I1009 16:20:30.974113    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#createsnapshot-volume-3" 
I1009 16:20:31.093] I1009 16:20:31.093406    8806 controllerserver.go:149] azure file(createsnapshot-volume-3) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#createsnapshot-volume-3) is deleted successfully
I1009 16:20:31.094] I1009 16:20:31.093537    8806 utils.go:118] GRPC response: 
I1009 16:20:31.095] •
I1009 16:20:31.100] ------------------------------
I1009 16:20:31.100] ExpandVolume [Controller Server] 
I1009 16:20:31.101]   should fail if no volume id is given
I1009 16:20:31.101]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2094
I1009 16:20:31.101] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:31.101] STEP: creating mount and staging directories
I1009 16:20:31.101] I1009 16:20:31.094767    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:31.102] I1009 16:20:31.094802    8806 utils.go:112] GRPC request: 
I1009 16:20:31.102] I1009 16:20:31.094836    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:31.102] I1009 16:20:31.094858    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:31.102] 
I1009 16:20:31.102] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1009 16:20:31.102] ExpandVolume [Controller Server]
I1009 16:20:31.103] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:31.103]   should fail if no volume id is given [BeforeEach]
I1009 16:20:31.103]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2094
I1009 16:20:31.103] 
I1009 16:20:31.103]   ControllerExpandVolume not supported
I1009 16:20:31.103] 
I1009 16:20:31.104]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2084
I1009 16:20:31.104] ------------------------------
I1009 16:20:31.104] ExpandVolume [Controller Server] 
I1009 16:20:31.104]   should fail if no capacity range is given
I1009 16:20:31.104]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2110
I1009 16:20:31.104] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:31.105] STEP: creating mount and staging directories
I1009 16:20:31.105] I1009 16:20:31.096465    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:31.105] I1009 16:20:31.096550    8806 utils.go:112] GRPC request: 
I1009 16:20:31.105] I1009 16:20:31.096578    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:31.105] I1009 16:20:31.096627    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:31.106] 
I1009 16:20:31.106] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1009 16:20:31.107] ExpandVolume [Controller Server]
I1009 16:20:31.107] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:31.107]   should fail if no capacity range is given [BeforeEach]
I1009 16:20:31.108]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2110
I1009 16:20:31.108] 
I1009 16:20:31.108]   ControllerExpandVolume not supported
I1009 16:20:31.108] 
I1009 16:20:31.108]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:2084
I1009 16:20:31.108] ------------------------------
... skipping 211 lines ...
I1009 16:20:31.146] 
I1009 16:20:31.146]     ListVolumes not supported
I1009 16:20:31.147] 
I1009 16:20:31.147]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:169
I1009 16:20:31.147] ------------------------------
I1009 16:20:31.147] Controller Service [Controller Server] ListVolumes 
I1009 16:20:31.147]   should fail when an invalid starting_token is passed
I1009 16:20:31.147]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:185
I1009 16:20:31.148] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:31.148] STEP: creating mount and staging directories
I1009 16:20:31.148] I1009 16:20:31.114718    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:31.149] I1009 16:20:31.114775    8806 utils.go:112] GRPC request: 
I1009 16:20:31.149] I1009 16:20:31.114799    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:31.149] I1009 16:20:31.114817    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:31.149] 
I1009 16:20:31.150] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:31.150] Controller Service [Controller Server]
I1009 16:20:31.150] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:31.150]   ListVolumes
I1009 16:20:31.150]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:166
I1009 16:20:31.151]     should fail when an invalid starting_token is passed [BeforeEach]
I1009 16:20:31.151]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:185
I1009 16:20:31.151] 
I1009 16:20:31.151]     ListVolumes not supported
I1009 16:20:31.151] 
I1009 16:20:31.151]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:169
I1009 16:20:31.152] ------------------------------
... skipping 39 lines ...
I1009 16:20:31.159] 
I1009 16:20:31.159]     ListVolumes not supported
I1009 16:20:31.160] 
I1009 16:20:31.160]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:169
I1009 16:20:31.160] ------------------------------
I1009 16:20:31.160] Controller Service [Controller Server] CreateVolume 
I1009 16:20:31.160]   should fail when no name is provided
I1009 16:20:31.161]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:387
I1009 16:20:31.161] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:31.161] STEP: creating mount and staging directories
I1009 16:20:31.161] I1009 16:20:31.118800    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:31.161] I1009 16:20:31.118843    8806 utils.go:112] GRPC request: 
I1009 16:20:31.161] I1009 16:20:31.118886    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:31.162] I1009 16:20:31.118914    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:31.162] I1009 16:20:31.119385    8806 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1009 16:20:31.162] I1009 16:20:31.119470    8806 utils.go:112] GRPC request: 
I1009 16:20:31.162] E1009 16:20:31.119495    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
I1009 16:20:31.162] •
I1009 16:20:31.163] ------------------------------
I1009 16:20:31.163] Controller Service [Controller Server] CreateVolume 
I1009 16:20:31.163]   should fail when no volume capabilities are provided
I1009 16:20:31.163]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:403
I1009 16:20:31.164] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:31.164] STEP: creating mount and staging directories
I1009 16:20:31.164] I1009 16:20:31.120195    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:31.164] I1009 16:20:31.120236    8806 utils.go:112] GRPC request: 
I1009 16:20:31.164] I1009 16:20:31.120288    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:31.165] I1009 16:20:31.120316    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:31.165] I1009 16:20:31.120899    8806 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1009 16:20:31.165] I1009 16:20:31.120945    8806 utils.go:112] GRPC request: name:"sanity-controller-create-no-volume-capabilities-B53FCB65-704D18BC" 
I1009 16:20:31.165] E1009 16:20:31.120992    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities must be provided
I1009 16:20:31.165] •
I1009 16:20:31.166] ------------------------------
I1009 16:20:31.166] Controller Service [Controller Server] CreateVolume 
I1009 16:20:31.166]   should return appropriate values SingleNodeWriter NoCapacity Type:Mount
I1009 16:20:31.166]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:421
I1009 16:20:31.166] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 40 lines ...
I1009 16:20:31.741] I1009 16:20:31.739947    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-create-single-with-capacity-b53fcb65-704d18bc" 
I1009 16:20:31.878] I1009 16:20:31.876966    8806 controllerserver.go:149] azure file(sanity-controller-create-single-with-capacity-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-controller-create-single-with-capacity-b53fcb65-704d18bc) is deleted successfully
I1009 16:20:31.878] I1009 16:20:31.877008    8806 utils.go:118] GRPC response: 
I1009 16:20:31.879] •
I1009 16:20:31.879] ------------------------------
I1009 16:20:31.879] Controller Service [Controller Server] CreateVolume 
I1009 16:20:31.879]   should not fail when requesting to create a volume with already existing name and same capacity.
I1009 16:20:31.879]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:512
I1009 16:20:31.879] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:31.879] STEP: creating mount and staging directories
I1009 16:20:31.880] I1009 16:20:31.878132    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:31.880] I1009 16:20:31.878151    8806 utils.go:112] GRPC request: 
I1009 16:20:31.880] I1009 16:20:31.878158    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 19 lines ...
I1009 16:20:32.427] I1009 16:20:32.426808    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-create-twice-b53fcb65-704d18bc" 
I1009 16:20:32.548] I1009 16:20:32.547281    8806 controllerserver.go:149] azure file(sanity-controller-create-twice-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-controller-create-twice-b53fcb65-704d18bc) is deleted successfully
I1009 16:20:32.550] I1009 16:20:32.547666    8806 utils.go:118] GRPC response: 
I1009 16:20:32.550] •
I1009 16:20:32.550] ------------------------------
I1009 16:20:32.551] Controller Service [Controller Server] CreateVolume 
I1009 16:20:32.551]   should fail when requesting to create a volume with already existing name and different capacity.
I1009 16:20:32.551]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:586
I1009 16:20:32.551] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:32.552] STEP: creating mount and staging directories
I1009 16:20:32.553] I1009 16:20:32.552213    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:32.554] I1009 16:20:32.552396    8806 utils.go:112] GRPC request: 
I1009 16:20:32.554] I1009 16:20:32.552474    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 10 lines ...
I1009 16:20:32.795] I1009 16:20:32.793266    8806 utils.go:112] GRPC request: name:"sanity-controller-create-twice-different-B53FCB65-704D18BC" capacity_range:<required_bytes:21474836480 limit_bytes:21474836480 > volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1009 16:20:32.796] I1009 16:20:32.793284    8806 controllerserver.go:95] begin to create file share(sanity-controller-create-twice-different-b53fcb65-704d18bc) on account() type() rg() location() size(20)
I1009 16:20:32.869] I1009 16:20:32.869091    8806 azure_storageaccount.go:103] found a matching account fa9480518eab011e98d7e9a type Standard_LRS location eastus2
I1009 16:20:33.018] I1009 16:20:33.018340    8806 azure_file.go:68] file share(sanity-controller-create-twice-different-b53fcb65-704d18bc) under account(fa9480518eab011e98d7e9a) already exists
I1009 16:20:33.019] I1009 16:20:33.018386    8806 azure_storage.go:49] created share sanity-controller-create-twice-different-b53fcb65-704d18bc in account fa9480518eab011e98d7e9a
I1009 16:20:33.019] I1009 16:20:33.018398    8806 controllerserver.go:108] create file share sanity-controller-create-twice-different-b53fcb65-704d18bc on storage account fa9480518eab011e98d7e9a successfully
I1009 16:20:33.055] E1009 16:20:33.054336    8806 utils.go:116] GRPC error: rpc error: code = AlreadyExists desc = the request volume already exists, but its capacity(10) is different from (20)
I1009 16:20:33.056] STEP: cleaning up deleting the volume
I1009 16:20:33.056] I1009 16:20:33.055209    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1009 16:20:33.056] I1009 16:20:33.055237    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-create-twice-different-b53fcb65-704d18bc" 
I1009 16:20:33.195] I1009 16:20:33.192328    8806 controllerserver.go:149] azure file(sanity-controller-create-twice-different-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-controller-create-twice-different-b53fcb65-704d18bc) is deleted successfully
I1009 16:20:33.195] I1009 16:20:33.192364    8806 utils.go:118] GRPC response: 
I1009 16:20:33.195] •
I1009 16:20:33.195] ------------------------------
I1009 16:20:33.196] Controller Service [Controller Server] CreateVolume 
I1009 16:20:33.196]   should not fail when creating volume with maximum-length name
I1009 16:20:33.196]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:661
I1009 16:20:33.196] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:33.196] STEP: creating mount and staging directories
I1009 16:20:33.196] I1009 16:20:33.193507    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:33.196] I1009 16:20:33.193653    8806 utils.go:112] GRPC request: 
I1009 16:20:33.196] I1009 16:20:33.193688    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 89 lines ...
I1009 16:20:34.821] 
I1009 16:20:34.821]     Volume Cloning not supported
I1009 16:20:34.821] 
I1009 16:20:34.821]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:784
I1009 16:20:34.821] ------------------------------
I1009 16:20:34.822] Controller Service [Controller Server] CreateVolume 
I1009 16:20:34.822]   should fail when the volume source volume is not found
I1009 16:20:34.822]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:817
I1009 16:20:34.822] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:34.823] STEP: creating mount and staging directories
I1009 16:20:34.823] I1009 16:20:34.811659    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:34.823] I1009 16:20:34.811697    8806 utils.go:112] GRPC request: 
I1009 16:20:34.824] I1009 16:20:34.811750    8806 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1009 16:20:34.825] 
I1009 16:20:34.826] S [SKIPPING] [0.002 seconds]
I1009 16:20:34.826] Controller Service [Controller Server]
I1009 16:20:34.826] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:34.826]   CreateVolume
I1009 16:20:34.827]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:380
I1009 16:20:34.827]     should fail when the volume source volume is not found [It]
I1009 16:20:34.827]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:817
I1009 16:20:34.827] 
I1009 16:20:34.827]     Volume Cloning not supported
I1009 16:20:34.828] 
I1009 16:20:34.828]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:819
I1009 16:20:34.828] ------------------------------
I1009 16:20:34.828] Controller Service [Controller Server] DeleteVolume 
I1009 16:20:34.829]   should fail when no volume id is provided
I1009 16:20:34.829]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:847
I1009 16:20:34.829] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:34.829] STEP: creating mount and staging directories
I1009 16:20:34.830] I1009 16:20:34.813197    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:34.830] I1009 16:20:34.813207    8806 utils.go:112] GRPC request: 
I1009 16:20:34.830] I1009 16:20:34.813212    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:34.831] I1009 16:20:34.813218    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:34.831] I1009 16:20:34.813691    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1009 16:20:34.831] I1009 16:20:34.813708    8806 utils.go:112] GRPC request: 
I1009 16:20:34.832] E1009 16:20:34.813714    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1009 16:20:34.832] •
I1009 16:20:34.832] ------------------------------
I1009 16:20:34.832] Controller Service [Controller Server] DeleteVolume 
I1009 16:20:34.832]   should succeed when an invalid volume id is used
I1009 16:20:34.833]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:862
I1009 16:20:34.833] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:34.833] STEP: creating mount and staging directories
I1009 16:20:34.834] I1009 16:20:34.814219    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:34.834] I1009 16:20:34.814234    8806 utils.go:112] GRPC request: 
I1009 16:20:34.834] I1009 16:20:34.814239    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:34.834] I1009 16:20:34.814246    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:34.835] I1009 16:20:34.814593    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1009 16:20:34.835] I1009 16:20:34.814606    8806 utils.go:112] GRPC request: volume_id:"fake-vol-id" 
I1009 16:20:34.835] E1009 16:20:34.814619    8806 controllerserver.go:342] getFileShareInfo(fake-vol-id) failed with error: error parsing volume id: "fake-vol-id", should at least contain two #
I1009 16:20:34.835] I1009 16:20:34.814626    8806 controllerserver.go:137] failed to get share url with (fake-vol-id): error parsing volume id: "fake-vol-id", should at least contain two #, returning with success
I1009 16:20:34.836] I1009 16:20:34.814632    8806 utils.go:118] GRPC response: 
I1009 16:20:34.836] •
I1009 16:20:34.836] ------------------------------
I1009 16:20:34.836] Controller Service [Controller Server] DeleteVolume 
I1009 16:20:34.837]   should return appropriate values (no optional values added)
I1009 16:20:34.837]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:874
... skipping 17 lines ...
I1009 16:20:35.047] I1009 16:20:35.045185    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-create-appropriate-b53fcb65-704d18bc" 
I1009 16:20:35.171] I1009 16:20:35.169683    8806 controllerserver.go:149] azure file(sanity-controller-create-appropriate-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-controller-create-appropriate-b53fcb65-704d18bc) is deleted successfully
I1009 16:20:35.171] I1009 16:20:35.169720    8806 utils.go:118] GRPC response: 
I1009 16:20:35.171] •
I1009 16:20:35.172] ------------------------------
I1009 16:20:35.172] Controller Service [Controller Server] ValidateVolumeCapabilities 
I1009 16:20:35.172]   should fail when no volume id is provided
I1009 16:20:35.173]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:920
I1009 16:20:35.173] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:35.173] STEP: creating mount and staging directories
I1009 16:20:35.174] I1009 16:20:35.170890    8806 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1009 16:20:35.174] I1009 16:20:35.170912    8806 utils.go:112] GRPC request: 
I1009 16:20:35.174] E1009 16:20:35.170918    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1009 16:20:35.174] •
I1009 16:20:35.175] ------------------------------
I1009 16:20:35.175] Controller Service [Controller Server] ValidateVolumeCapabilities 
I1009 16:20:35.175]   should fail when no volume capabilities are provided
I1009 16:20:35.175]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:934
I1009 16:20:35.176] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:35.176] STEP: creating mount and staging directories
I1009 16:20:35.176] STEP: creating a single node writer volume
I1009 16:20:35.176] I1009 16:20:35.171870    8806 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1009 16:20:35.177] I1009 16:20:35.171917    8806 utils.go:112] GRPC request: name:"sanity-controller-validate-nocaps-B53FCB65-704D18BC" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
... skipping 2 lines ...
I1009 16:20:35.251] I1009 16:20:35.251199    8806 azure_storageaccount.go:103] found a matching account fa9480518eab011e98d7e9a type Standard_LRS location eastus2
I1009 16:20:35.370] I1009 16:20:35.370154    8806 azure_storage.go:49] created share sanity-controller-validate-nocaps-b53fcb65-704d18bc in account fa9480518eab011e98d7e9a
I1009 16:20:35.371] I1009 16:20:35.370282    8806 controllerserver.go:108] create file share sanity-controller-validate-nocaps-b53fcb65-704d18bc on storage account fa9480518eab011e98d7e9a successfully
I1009 16:20:35.407] I1009 16:20:35.406570    8806 utils.go:118] GRPC response: volume:<volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-validate-nocaps-b53fcb65-704d18bc" > 
I1009 16:20:35.408] I1009 16:20:35.407611    8806 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1009 16:20:35.409] I1009 16:20:35.407661    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-validate-nocaps-b53fcb65-704d18bc" 
I1009 16:20:35.409] E1009 16:20:35.407668    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities missing in request
I1009 16:20:35.409] STEP: cleaning up deleting the volume
I1009 16:20:35.409] I1009 16:20:35.408108    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1009 16:20:35.409] I1009 16:20:35.408153    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-validate-nocaps-b53fcb65-704d18bc" 
I1009 16:20:35.529] I1009 16:20:35.528625    8806 controllerserver.go:149] azure file(sanity-controller-validate-nocaps-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-controller-validate-nocaps-b53fcb65-704d18bc) is deleted successfully
I1009 16:20:35.530] I1009 16:20:35.528741    8806 utils.go:118] GRPC response: 
I1009 16:20:35.530] •
... skipping 21 lines ...
I1009 16:20:35.884] I1009 16:20:35.883675    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-controller-validate-b53fcb65-704d18bc" 
I1009 16:20:36.004] I1009 16:20:36.001270    8806 controllerserver.go:149] azure file(sanity-controller-validate-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-controller-validate-b53fcb65-704d18bc) is deleted successfully
I1009 16:20:36.005] I1009 16:20:36.001373    8806 utils.go:118] GRPC response: 
I1009 16:20:36.005] •
I1009 16:20:36.005] ------------------------------
I1009 16:20:36.005] Controller Service [Controller Server] ValidateVolumeCapabilities 
I1009 16:20:36.005]   should fail when the requested volume does not exist
I1009 16:20:36.006]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1059
I1009 16:20:36.006] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.006] STEP: creating mount and staging directories
I1009 16:20:36.006] I1009 16:20:36.002575    8806 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1009 16:20:36.006] I1009 16:20:36.002636    8806 utils.go:112] GRPC request: volume_id:"fake-vol-id-1ea9a39f-6" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1009 16:20:36.007] E1009 16:20:36.002662    8806 utils.go:116] GRPC error: rpc error: code = NotFound desc = error getting volume(fake-vol-id-1ea9a39f-6) info: error parsing volume id: "fake-vol-id-1ea9a39f-6", should at least contain two #
I1009 16:20:36.007] •
I1009 16:20:36.007] ------------------------------
I1009 16:20:36.007] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.007]   should fail when no volume id is provided
I1009 16:20:36.008]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1093
I1009 16:20:36.008] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.008] STEP: creating mount and staging directories
I1009 16:20:36.008] I1009 16:20:36.003342    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.008] I1009 16:20:36.003385    8806 utils.go:112] GRPC request: 
I1009 16:20:36.008] I1009 16:20:36.003401    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.009] I1009 16:20:36.003416    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.009] 
I1009 16:20:36.009] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.009] Controller Service [Controller Server]
I1009 16:20:36.009] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.010]   ControllerPublishVolume
I1009 16:20:36.010]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.010]     should fail when no volume id is provided [BeforeEach]
I1009 16:20:36.010]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1093
I1009 16:20:36.010] 
I1009 16:20:36.010]     ControllerPublishVolume not supported
I1009 16:20:36.010] 
I1009 16:20:36.010]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.011] ------------------------------
I1009 16:20:36.011] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.011]   should fail when no node id is provided
I1009 16:20:36.011]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1108
I1009 16:20:36.011] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.011] STEP: creating mount and staging directories
I1009 16:20:36.011] I1009 16:20:36.004429    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.011] I1009 16:20:36.004518    8806 utils.go:112] GRPC request: 
I1009 16:20:36.011] I1009 16:20:36.004539    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.012] I1009 16:20:36.004553    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.012] 
I1009 16:20:36.012] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.012] Controller Service [Controller Server]
I1009 16:20:36.012] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.012]   ControllerPublishVolume
I1009 16:20:36.012]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.013]     should fail when no node id is provided [BeforeEach]
I1009 16:20:36.013]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1108
I1009 16:20:36.013] 
I1009 16:20:36.013]     ControllerPublishVolume not supported
I1009 16:20:36.013] 
I1009 16:20:36.013]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.013] ------------------------------
I1009 16:20:36.014] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.014]   should fail when no volume capability is provided
I1009 16:20:36.014]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1124
I1009 16:20:36.014] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.014] STEP: creating mount and staging directories
I1009 16:20:36.014] I1009 16:20:36.005553    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.015] I1009 16:20:36.005613    8806 utils.go:112] GRPC request: 
I1009 16:20:36.015] I1009 16:20:36.005631    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.015] I1009 16:20:36.005645    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.015] 
I1009 16:20:36.015] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.015] Controller Service [Controller Server]
I1009 16:20:36.015] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.016]   ControllerPublishVolume
I1009 16:20:36.016]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.016]     should fail when no volume capability is provided [BeforeEach]
I1009 16:20:36.016]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1124
I1009 16:20:36.016] 
I1009 16:20:36.016]     ControllerPublishVolume not supported
I1009 16:20:36.016] 
I1009 16:20:36.016]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.016] ------------------------------
... skipping 17 lines ...
I1009 16:20:36.019] 
I1009 16:20:36.020]     ControllerPublishVolume not supported
I1009 16:20:36.020] 
I1009 16:20:36.020]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.020] ------------------------------
I1009 16:20:36.020] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.020]   should fail when publishing more volumes than the node max attach limit
I1009 16:20:36.020]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1241
I1009 16:20:36.020] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.020] STEP: creating mount and staging directories
I1009 16:20:36.021] I1009 16:20:36.007688    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.021] I1009 16:20:36.007829    8806 utils.go:112] GRPC request: 
I1009 16:20:36.021] I1009 16:20:36.007860    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.021] I1009 16:20:36.007877    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.021] 
I1009 16:20:36.021] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.021] Controller Service [Controller Server]
I1009 16:20:36.022] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.022]   ControllerPublishVolume
I1009 16:20:36.022]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.022]     should fail when publishing more volumes than the node max attach limit [BeforeEach]
I1009 16:20:36.022]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1241
I1009 16:20:36.022] 
I1009 16:20:36.023]     ControllerPublishVolume not supported
I1009 16:20:36.023] 
I1009 16:20:36.023]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.023] ------------------------------
I1009 16:20:36.023] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.023]   should fail when the volume does not exist
I1009 16:20:36.024]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1283
I1009 16:20:36.024] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.024] STEP: creating mount and staging directories
I1009 16:20:36.024] I1009 16:20:36.008799    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.024] I1009 16:20:36.008924    8806 utils.go:112] GRPC request: 
I1009 16:20:36.024] I1009 16:20:36.008956    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.024] I1009 16:20:36.008973    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.025] 
I1009 16:20:36.025] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.025] Controller Service [Controller Server]
I1009 16:20:36.025] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.025]   ControllerPublishVolume
I1009 16:20:36.025]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.025]     should fail when the volume does not exist [BeforeEach]
I1009 16:20:36.026]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1283
I1009 16:20:36.026] 
I1009 16:20:36.026]     ControllerPublishVolume not supported
I1009 16:20:36.026] 
I1009 16:20:36.026]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.027] ------------------------------
I1009 16:20:36.027] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.027]   should fail when the node does not exist
I1009 16:20:36.027]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1312
I1009 16:20:36.028] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.028] STEP: creating mount and staging directories
I1009 16:20:36.028] I1009 16:20:36.009858    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.028] I1009 16:20:36.009997    8806 utils.go:112] GRPC request: 
I1009 16:20:36.028] I1009 16:20:36.010069    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.029] I1009 16:20:36.010092    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.029] 
I1009 16:20:36.029] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.029] Controller Service [Controller Server]
I1009 16:20:36.029] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.030]   ControllerPublishVolume
I1009 16:20:36.030]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.030]     should fail when the node does not exist [BeforeEach]
I1009 16:20:36.030]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1312
I1009 16:20:36.030] 
I1009 16:20:36.031]     ControllerPublishVolume not supported
I1009 16:20:36.031] 
I1009 16:20:36.031]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.031] ------------------------------
I1009 16:20:36.031] Controller Service [Controller Server] ControllerPublishVolume 
I1009 16:20:36.031]   should fail when the volume is already published but is incompatible
I1009 16:20:36.032]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1382
I1009 16:20:36.032] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.032] STEP: creating mount and staging directories
I1009 16:20:36.032] I1009 16:20:36.011307    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.032] I1009 16:20:36.011427    8806 utils.go:112] GRPC request: 
I1009 16:20:36.032] I1009 16:20:36.011542    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.033] I1009 16:20:36.011563    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.033] 
I1009 16:20:36.033] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.033] Controller Service [Controller Server]
I1009 16:20:36.033] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.034]   ControllerPublishVolume
I1009 16:20:36.034]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1086
I1009 16:20:36.034]     should fail when the volume is already published but is incompatible [BeforeEach]
I1009 16:20:36.034]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1382
I1009 16:20:36.034] 
I1009 16:20:36.034]     ControllerPublishVolume not supported
I1009 16:20:36.034] 
I1009 16:20:36.034]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1089
I1009 16:20:36.034] ------------------------------
I1009 16:20:36.035] Controller Service [Controller Server] ControllerUnpublishVolume 
I1009 16:20:36.035]   should fail when no volume id is provided
I1009 16:20:36.035]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1492
I1009 16:20:36.035] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.035] STEP: creating mount and staging directories
I1009 16:20:36.035] I1009 16:20:36.012464    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.035] I1009 16:20:36.012517    8806 utils.go:112] GRPC request: 
I1009 16:20:36.035] I1009 16:20:36.012545    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.036] I1009 16:20:36.012645    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.036] 
I1009 16:20:36.036] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1009 16:20:36.036] Controller Service [Controller Server]
I1009 16:20:36.036] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.036]   ControllerUnpublishVolume
I1009 16:20:36.036]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1485
I1009 16:20:36.036]     should fail when no volume id is provided [BeforeEach]
I1009 16:20:36.036]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1492
I1009 16:20:36.036] 
I1009 16:20:36.037]     ControllerUnpublishVolume not supported
I1009 16:20:36.037] 
I1009 16:20:36.037]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1488
I1009 16:20:36.037] ------------------------------
... skipping 76 lines ...
I1009 16:20:36.049] I1009 16:20:36.020695    8806 utils.go:112] GRPC request: 
I1009 16:20:36.049] I1009 16:20:36.020718    8806 nodeserver.go:240] Using default NodeGetInfo
I1009 16:20:36.049] I1009 16:20:36.020738    8806 utils.go:118] GRPC response: node_id:"CSINode" 
I1009 16:20:36.049] •
I1009 16:20:36.049] ------------------------------
I1009 16:20:36.049] Node Service NodePublishVolume 
I1009 16:20:36.049]   should fail when no volume id is provided
I1009 16:20:36.050]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:172
I1009 16:20:36.050] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.050] STEP: creating mount and staging directories
I1009 16:20:36.050] I1009 16:20:36.021479    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.050] I1009 16:20:36.021522    8806 utils.go:112] GRPC request: 
I1009 16:20:36.050] I1009 16:20:36.021544    8806 identityserver.go:58] Using default capabilities
... skipping 9 lines ...
I1009 16:20:36.051] I1009 16:20:36.022772    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1009 16:20:36.052] I1009 16:20:36.022818    8806 utils.go:112] GRPC request: 
I1009 16:20:36.052] I1009 16:20:36.022838    8806 nodeserver.go:231] Using default NodeGetCapabilities
I1009 16:20:36.052] I1009 16:20:36.022856    8806 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1009 16:20:36.052] I1009 16:20:36.025790    8806 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1009 16:20:36.052] I1009 16:20:36.025865    8806 utils.go:112] GRPC request: 
I1009 16:20:36.052] E1009 16:20:36.025895    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1009 16:20:36.052] •
I1009 16:20:36.052] ------------------------------
I1009 16:20:36.053] Node Service NodePublishVolume 
I1009 16:20:36.053]   should fail when no target path is provided
I1009 16:20:36.053]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:186
I1009 16:20:36.053] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.053] STEP: creating mount and staging directories
I1009 16:20:36.053] I1009 16:20:36.026926    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.054] I1009 16:20:36.027137    8806 utils.go:112] GRPC request: 
I1009 16:20:36.054] I1009 16:20:36.027207    8806 identityserver.go:58] Using default capabilities
... skipping 9 lines ...
I1009 16:20:36.055] I1009 16:20:36.028719    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1009 16:20:36.056] I1009 16:20:36.028739    8806 utils.go:112] GRPC request: 
I1009 16:20:36.056] I1009 16:20:36.028746    8806 nodeserver.go:231] Using default NodeGetCapabilities
I1009 16:20:36.056] I1009 16:20:36.028757    8806 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1009 16:20:36.056] I1009 16:20:36.029162    8806 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1009 16:20:36.056] I1009 16:20:36.029184    8806 utils.go:112] GRPC request: volume_id:"fake-vol-id-724dd72e-0" 
I1009 16:20:36.056] E1009 16:20:36.029193    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1009 16:20:36.056] •
I1009 16:20:36.057] ------------------------------
I1009 16:20:36.057] Node Service NodePublishVolume 
I1009 16:20:36.057]   should fail when no volume capability is provided
I1009 16:20:36.057]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:201
I1009 16:20:36.057] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.057] STEP: creating mount and staging directories
I1009 16:20:36.058] I1009 16:20:36.029845    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.058] I1009 16:20:36.029869    8806 utils.go:112] GRPC request: 
I1009 16:20:36.058] I1009 16:20:36.029877    8806 identityserver.go:58] Using default capabilities
... skipping 9 lines ...
I1009 16:20:36.060] I1009 16:20:36.031015    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1009 16:20:36.060] I1009 16:20:36.031032    8806 utils.go:112] GRPC request: 
I1009 16:20:36.060] I1009 16:20:36.031038    8806 nodeserver.go:231] Using default NodeGetCapabilities
I1009 16:20:36.060] I1009 16:20:36.031045    8806 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1009 16:20:36.060] I1009 16:20:36.031427    8806 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1009 16:20:36.060] I1009 16:20:36.031591    8806 utils.go:112] GRPC request: volume_id:"fake-vol-id-b2b0026c-b" target_path:"/tmp/csi-mount/target" 
I1009 16:20:36.060] E1009 16:20:36.031612    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1009 16:20:36.060] •
I1009 16:20:36.061] ------------------------------
I1009 16:20:36.061] Node Service NodeUnpublishVolume 
I1009 16:20:36.061]   should fail when no volume id is provided
I1009 16:20:36.061]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:219
I1009 16:20:36.061] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.061] STEP: creating mount and staging directories
I1009 16:20:36.061] I1009 16:20:36.032398    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.061] I1009 16:20:36.032469    8806 utils.go:112] GRPC request: 
I1009 16:20:36.061] I1009 16:20:36.032503    8806 identityserver.go:58] Using default capabilities
... skipping 9 lines ...
I1009 16:20:36.063] I1009 16:20:36.034295    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1009 16:20:36.063] I1009 16:20:36.034408    8806 utils.go:112] GRPC request: 
I1009 16:20:36.063] I1009 16:20:36.034564    8806 nodeserver.go:231] Using default NodeGetCapabilities
I1009 16:20:36.063] I1009 16:20:36.034612    8806 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1009 16:20:36.063] I1009 16:20:36.035219    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1009 16:20:36.063] I1009 16:20:36.035938    8806 utils.go:112] GRPC request: 
I1009 16:20:36.063] E1009 16:20:36.036000    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1009 16:20:36.064] •
I1009 16:20:36.064] ------------------------------
I1009 16:20:36.064] Node Service NodeUnpublishVolume 
I1009 16:20:36.064]   should fail when no target path is provided
I1009 16:20:36.064]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:231
I1009 16:20:36.064] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.064] STEP: creating mount and staging directories
I1009 16:20:36.064] I1009 16:20:36.037174    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.065] I1009 16:20:36.037234    8806 utils.go:112] GRPC request: 
I1009 16:20:36.065] I1009 16:20:36.037263    8806 identityserver.go:58] Using default capabilities
... skipping 9 lines ...
I1009 16:20:36.067] I1009 16:20:36.038763    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1009 16:20:36.067] I1009 16:20:36.038815    8806 utils.go:112] GRPC request: 
I1009 16:20:36.067] I1009 16:20:36.038838    8806 nodeserver.go:231] Using default NodeGetCapabilities
I1009 16:20:36.067] I1009 16:20:36.038858    8806 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1009 16:20:36.067] I1009 16:20:36.039407    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1009 16:20:36.067] I1009 16:20:36.039481    8806 utils.go:112] GRPC request: volume_id:"fake-vol-id-c9a2ddbe-8" 
I1009 16:20:36.067] E1009 16:20:36.039508    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
I1009 16:20:36.068] •
I1009 16:20:36.068] ------------------------------
I1009 16:20:36.068] Node Service NodeStageVolume 
I1009 16:20:36.068]   should fail when no volume id is provided
I1009 16:20:36.068]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:259
I1009 16:20:36.068] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.069] STEP: creating mount and staging directories
I1009 16:20:36.069] I1009 16:20:36.040425    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.069] I1009 16:20:36.040559    8806 utils.go:112] GRPC request: 
I1009 16:20:36.069] I1009 16:20:36.040586    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.071] 
I1009 16:20:36.071] S [SKIPPING] in Spec Setup (BeforeEach) [0.004 seconds]
I1009 16:20:36.071] Node Service
I1009 16:20:36.071] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.071]   NodeStageVolume
I1009 16:20:36.072]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:246
I1009 16:20:36.072]     should fail when no volume id is provided [BeforeEach]
I1009 16:20:36.072]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:259
I1009 16:20:36.072] 
I1009 16:20:36.072]     NodeStageVolume not supported
I1009 16:20:36.072] 
I1009 16:20:36.072]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:253
I1009 16:20:36.072] ------------------------------
I1009 16:20:36.073] Node Service NodeStageVolume 
I1009 16:20:36.073]   should fail when no staging target path is provided
I1009 16:20:36.073]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:285
I1009 16:20:36.073] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.073] STEP: creating mount and staging directories
I1009 16:20:36.073] I1009 16:20:36.046108    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.073] I1009 16:20:36.046177    8806 utils.go:112] GRPC request: 
I1009 16:20:36.073] I1009 16:20:36.046200    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.075] 
I1009 16:20:36.075] S [SKIPPING] in Spec Setup (BeforeEach) [0.005 seconds]
I1009 16:20:36.075] Node Service
I1009 16:20:36.076] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.076]   NodeStageVolume
I1009 16:20:36.076]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:246
I1009 16:20:36.076]     should fail when no staging target path is provided [BeforeEach]
I1009 16:20:36.076]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:285
I1009 16:20:36.076] 
I1009 16:20:36.076]     NodeStageVolume not supported
I1009 16:20:36.076] 
I1009 16:20:36.077]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:253
I1009 16:20:36.077] ------------------------------
I1009 16:20:36.077] Node Service NodeStageVolume 
I1009 16:20:36.077]   should fail when no volume capability is provided
I1009 16:20:36.077]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:311
I1009 16:20:36.077] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.077] STEP: creating mount and staging directories
I1009 16:20:36.077] I1009 16:20:36.050505    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.078] I1009 16:20:36.050578    8806 utils.go:112] GRPC request: 
I1009 16:20:36.078] I1009 16:20:36.050608    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.080] 
I1009 16:20:36.080] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.080] Node Service
I1009 16:20:36.080] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.080]   NodeStageVolume
I1009 16:20:36.081]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:246
I1009 16:20:36.081]     should fail when no volume capability is provided [BeforeEach]
I1009 16:20:36.081]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:311
I1009 16:20:36.081] 
I1009 16:20:36.081]     NodeStageVolume not supported
I1009 16:20:36.081] 
I1009 16:20:36.081]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:253
I1009 16:20:36.082] ------------------------------
I1009 16:20:36.082] Node Service NodeUnstageVolume 
I1009 16:20:36.082]   should fail when no volume id is provided
I1009 16:20:36.082]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:379
I1009 16:20:36.082] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.082] STEP: creating mount and staging directories
I1009 16:20:36.083] I1009 16:20:36.053627    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.083] I1009 16:20:36.053743    8806 utils.go:112] GRPC request: 
I1009 16:20:36.083] I1009 16:20:36.053781    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.085] 
I1009 16:20:36.085] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.085] Node Service
I1009 16:20:36.085] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.085]   NodeUnstageVolume
I1009 16:20:36.085]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:372
I1009 16:20:36.085]     should fail when no volume id is provided [BeforeEach]
I1009 16:20:36.085]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:379
I1009 16:20:36.085] 
I1009 16:20:36.086]     NodeUnstageVolume not supported
I1009 16:20:36.086] 
I1009 16:20:36.086]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:375
I1009 16:20:36.086] ------------------------------
I1009 16:20:36.086] Node Service NodeUnstageVolume 
I1009 16:20:36.086]   should fail when no staging target path is provided
I1009 16:20:36.086]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:393
I1009 16:20:36.086] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.086] STEP: creating mount and staging directories
I1009 16:20:36.087] I1009 16:20:36.056929    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.087] I1009 16:20:36.056982    8806 utils.go:112] GRPC request: 
I1009 16:20:36.087] I1009 16:20:36.057005    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.089] 
I1009 16:20:36.089] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.089] Node Service
I1009 16:20:36.089] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.089]   NodeUnstageVolume
I1009 16:20:36.089]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:372
I1009 16:20:36.089]     should fail when no staging target path is provided [BeforeEach]
I1009 16:20:36.090]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:393
I1009 16:20:36.090] 
I1009 16:20:36.090]     NodeUnstageVolume not supported
I1009 16:20:36.090] 
I1009 16:20:36.090]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:375
I1009 16:20:36.090] ------------------------------
I1009 16:20:36.090] Node Service NodeGetVolumeStats 
I1009 16:20:36.090]   should fail when no volume id is provided
I1009 16:20:36.090]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:415
I1009 16:20:36.090] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.091] STEP: creating mount and staging directories
I1009 16:20:36.091] I1009 16:20:36.060645    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.091] I1009 16:20:36.060827    8806 utils.go:112] GRPC request: 
I1009 16:20:36.091] I1009 16:20:36.061054    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.093] 
I1009 16:20:36.093] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.093] Node Service
I1009 16:20:36.093] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.093]   NodeGetVolumeStats
I1009 16:20:36.093]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:408
I1009 16:20:36.093]     should fail when no volume id is provided [BeforeEach]
I1009 16:20:36.093]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:415
I1009 16:20:36.094] 
I1009 16:20:36.094]     NodeGetVolume not supported
I1009 16:20:36.094] 
I1009 16:20:36.094]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:411
I1009 16:20:36.094] ------------------------------
I1009 16:20:36.094] Node Service NodeGetVolumeStats 
I1009 16:20:36.094]   should fail when no volume path is provided
I1009 16:20:36.094]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:429
I1009 16:20:36.094] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.094] STEP: creating mount and staging directories
I1009 16:20:36.095] I1009 16:20:36.064480    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.095] I1009 16:20:36.064495    8806 utils.go:112] GRPC request: 
I1009 16:20:36.095] I1009 16:20:36.064502    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.097] 
I1009 16:20:36.097] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.097] Node Service
I1009 16:20:36.097] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.097]   NodeGetVolumeStats
I1009 16:20:36.097]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:408
I1009 16:20:36.097]     should fail when no volume path is provided [BeforeEach]
I1009 16:20:36.097]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:429
I1009 16:20:36.097] 
I1009 16:20:36.098]     NodeGetVolume not supported
I1009 16:20:36.098] 
I1009 16:20:36.098]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:411
I1009 16:20:36.098] ------------------------------
I1009 16:20:36.098] Node Service NodeGetVolumeStats 
I1009 16:20:36.098]   should fail when volume is not found
I1009 16:20:36.098]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:443
I1009 16:20:36.098] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.098] STEP: creating mount and staging directories
I1009 16:20:36.098] I1009 16:20:36.068009    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.099] I1009 16:20:36.068023    8806 utils.go:112] GRPC request: 
I1009 16:20:36.099] I1009 16:20:36.068029    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.101] 
I1009 16:20:36.101] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.101] Node Service
I1009 16:20:36.101] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.102]   NodeGetVolumeStats
I1009 16:20:36.102]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:408
I1009 16:20:36.102]     should fail when volume is not found [BeforeEach]
I1009 16:20:36.102]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:443
I1009 16:20:36.102] 
I1009 16:20:36.102]     NodeGetVolume not supported
I1009 16:20:36.102] 
I1009 16:20:36.102]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:411
I1009 16:20:36.102] ------------------------------
I1009 16:20:36.102] Node Service NodeGetVolumeStats 
I1009 16:20:36.103]   should fail when volume does not exist on the specified path
I1009 16:20:36.103]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:458
I1009 16:20:36.103] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.103] STEP: creating mount and staging directories
I1009 16:20:36.103] I1009 16:20:36.071017    8806 utils.go:111] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I1009 16:20:36.103] I1009 16:20:36.071034    8806 utils.go:112] GRPC request: 
I1009 16:20:36.104] I1009 16:20:36.071109    8806 identityserver.go:58] Using default capabilities
... skipping 13 lines ...
I1009 16:20:36.106] 
I1009 16:20:36.106] S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
I1009 16:20:36.106] Node Service
I1009 16:20:36.106] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.106]   NodeGetVolumeStats
I1009 16:20:36.106]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:408
I1009 16:20:36.107]     should fail when volume does not exist on the specified path [BeforeEach]
I1009 16:20:36.107]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:458
I1009 16:20:36.107] 
I1009 16:20:36.107]     NodeGetVolume not supported
I1009 16:20:36.107] 
I1009 16:20:36.107]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:411
I1009 16:20:36.107] ------------------------------
... skipping 40 lines ...
I1009 16:20:36.444] 
I1009 16:20:36.444] readonly false
I1009 16:20:36.444] volumeId #fa9480518eab011e98d7e9a#sanity-node-full-b53fcb65-704d18bc
I1009 16:20:36.444] context map[]
I1009 16:20:36.445] mountflags []
I1009 16:20:36.525] I1009 16:20:36.523755    8806 mount_linux.go:138] Mounting cmd (mount) with arguments ([-t cifs -o username=fa9480518eab011e98d7e9a,password=DDx8q8QLFBT1z777dyHeIbuaEl93IGfzgYszFYx43I4yko+RjToap4C3XmsEhqW5YolueCYUEIAUfZjjhuCccA==,file_mode=0777,dir_mode=0777,vers=3.0 //fa9480518eab011e98d7e9a.file.core.windows.net/sanity-node-full-b53fcb65-704d18bc /tmp/csi-mount/target])
I1009 16:20:36.698] E1009 16:20:36.698119    8806 mount_linux.go:143] Mount failed: exit status 32
I1009 16:20:36.701] Mounting command: mount
I1009 16:20:36.701] Mounting arguments: -t cifs -o username=fa9480518eab011e98d7e9a,password=DDx8q8QLFBT1z777dyHeIbuaEl93IGfzgYszFYx43I4yko+RjToap4C3XmsEhqW5YolueCYUEIAUfZjjhuCccA==,file_mode=0777,dir_mode=0777,vers=3.0 //fa9480518eab011e98d7e9a.file.core.windows.net/sanity-node-full-b53fcb65-704d18bc /tmp/csi-mount/target
I1009 16:20:36.702] Output: mount error: cifs filesystem not supported by the system
I1009 16:20:36.702] mount error(19): No such device
I1009 16:20:36.702] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1009 16:20:36.702] 
I1009 16:20:36.703] E1009 16:20:36.698337    8806 utils.go:116] GRPC error: mount failed: exit status 32
I1009 16:20:36.703] Mounting command: mount
I1009 16:20:36.703] Mounting arguments: -t cifs -o username=fa9480518eab011e98d7e9a,password=DDx8q8QLFBT1z777dyHeIbuaEl93IGfzgYszFYx43I4yko+RjToap4C3XmsEhqW5YolueCYUEIAUfZjjhuCccA==,file_mode=0777,dir_mode=0777,vers=3.0 //fa9480518eab011e98d7e9a.file.core.windows.net/sanity-node-full-b53fcb65-704d18bc /tmp/csi-mount/target
I1009 16:20:36.703] Output: mount error: cifs filesystem not supported by the system
I1009 16:20:36.703] mount error(19): No such device
I1009 16:20:36.704] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1009 16:20:36.704] 
I1009 16:20:36.704] cleanup: deleting sanity-node-full-B53FCB65-704D18BC = #fa9480518eab011e98d7e9a#sanity-node-full-b53fcb65-704d18bc
I1009 16:20:36.704] I1009 16:20:36.700518    8806 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1009 16:20:36.704] I1009 16:20:36.700546    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-node-full-b53fcb65-704d18bc" target_path:"/tmp/csi-mount/target" 
I1009 16:20:36.705] I1009 16:20:36.700560    8806 mount_linux.go:203] Unmounting /tmp/csi-mount/target
I1009 16:20:36.708] E1009 16:20:36.707131    8806 utils.go:116] GRPC error: rpc error: code = Internal desc = Unmount failed: exit status 32
I1009 16:20:36.708] Unmounting arguments: /tmp/csi-mount/target
I1009 16:20:36.709] Output: umount: /tmp/csi-mount/target: mountpoint not found
I1009 16:20:36.709] 
I1009 16:20:36.709] cleanup: warning: NodeUnpublishVolume: rpc error: code = Internal desc = Unmount failed: exit status 32
I1009 16:20:36.709] Unmounting arguments: /tmp/csi-mount/target
I1009 16:20:36.709] Output: umount: /tmp/csi-mount/target: mountpoint not found
I1009 16:20:36.710] 
I1009 16:20:36.710] I1009 16:20:36.708365    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1009 16:20:36.710] I1009 16:20:36.708523    8806 utils.go:112] GRPC request: volume_id:"#fa9480518eab011e98d7e9a#sanity-node-full-b53fcb65-704d18bc" 
I1009 16:20:36.827] I1009 16:20:36.826600    8806 controllerserver.go:149] azure file(sanity-node-full-b53fcb65-704d18bc) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#sanity-node-full-b53fcb65-704d18bc) is deleted successfully
... skipping 2 lines ...
I1009 16:20:36.829] • Failure [0.754 seconds]
I1009 16:20:36.829] Node Service
I1009 16:20:36.829] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/tests.go:44
I1009 16:20:36.829]   should work [It]
I1009 16:20:36.829]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:643
I1009 16:20:36.829] 
I1009 16:20:36.829]   Expected error:
I1009 16:20:36.829]       <*status.statusError | 0xc000248780>: {
I1009 16:20:36.829]           Code: 2,
I1009 16:20:36.830]           Message: "mount failed: exit status 32\nMounting command: mount\nMounting arguments: -t cifs -o username=fa9480518eab011e98d7e9a,password=DDx8q8QLFBT1z777dyHeIbuaEl93IGfzgYszFYx43I4yko+RjToap4C3XmsEhqW5YolueCYUEIAUfZjjhuCccA==,file_mode=0777,dir_mode=0777,vers=3.0 //fa9480518eab011e98d7e9a.file.core.windows.net/sanity-node-full-b53fcb65-704d18bc /tmp/csi-mount/target\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",
I1009 16:20:36.830]           Details: nil,
I1009 16:20:36.830]           XXX_NoUnkeyedLiteral: {},
I1009 16:20:36.830]           XXX_unrecognized: nil,
I1009 16:20:36.830]           XXX_sizecache: 0,
I1009 16:20:36.830]       }
I1009 16:20:36.830]       rpc error: code = Unknown desc = mount failed: exit status 32
I1009 16:20:36.831]       Mounting command: mount
I1009 16:20:36.831]       Mounting arguments: -t cifs -o username=fa9480518eab011e98d7e9a,password=DDx8q8QLFBT1z777dyHeIbuaEl93IGfzgYszFYx43I4yko+RjToap4C3XmsEhqW5YolueCYUEIAUfZjjhuCccA==,file_mode=0777,dir_mode=0777,vers=3.0 //fa9480518eab011e98d7e9a.file.core.windows.net/sanity-node-full-b53fcb65-704d18bc /tmp/csi-mount/target
I1009 16:20:36.831]       Output: mount error: cifs filesystem not supported by the system
I1009 16:20:36.831]       mount error(19): No such device
I1009 16:20:36.831]       Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1009 16:20:36.831]       
I1009 16:20:36.831]       
I1009 16:20:36.832]   not to have occurred
I1009 16:20:36.832] 
I1009 16:20:36.832]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:768
... skipping 31 lines ...
I1009 16:20:36.836] I1009 16:20:36.832424    8806 identityserver.go:32] Using default GetPluginInfo
I1009 16:20:36.836] I1009 16:20:36.832431    8806 utils.go:118] GRPC response: name:"file.csi.azure.com" vendor_version:"e2e-805050614da9e862384ec27de19fbc34a1a27499" 
I1009 16:20:36.836] STEP: verifying name size and characters
I1009 16:20:36.836] •
I1009 16:20:36.836] ------------------------------
I1009 16:20:36.836] DeleteSnapshot [Controller Server] 
I1009 16:20:36.836]   should fail when no snapshot id is provided
I1009 16:20:36.836]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1876
I1009 16:20:36.836] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.837] STEP: creating mount and staging directories
I1009 16:20:36.837] I1009 16:20:36.833799    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.837] I1009 16:20:36.833873    8806 utils.go:112] GRPC request: 
I1009 16:20:36.837] I1009 16:20:36.833901    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.837] I1009 16:20:36.833923    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.837] I1009 16:20:36.834428    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1009 16:20:36.837] I1009 16:20:36.834458    8806 utils.go:112] GRPC request: 
I1009 16:20:36.837] E1009 16:20:36.834466    8806 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
I1009 16:20:36.838] •
I1009 16:20:36.838] ------------------------------
I1009 16:20:36.838] DeleteSnapshot [Controller Server] 
I1009 16:20:36.838]   should succeed when an invalid snapshot id is used
I1009 16:20:36.838]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1892
I1009 16:20:36.838] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1009 16:20:36.838] STEP: creating mount and staging directories
I1009 16:20:36.838] I1009 16:20:36.835170    8806 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1009 16:20:36.838] I1009 16:20:36.835184    8806 utils.go:112] GRPC request: 
I1009 16:20:36.839] I1009 16:20:36.835191    8806 controllerserver.go:183] Using default ControllerGetCapabilities
I1009 16:20:36.839] I1009 16:20:36.835198    8806 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1009 16:20:36.839] I1009 16:20:36.835664    8806 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1009 16:20:36.839] I1009 16:20:36.835679    8806 utils.go:112] GRPC request: snapshot_id:"reallyfakesnapshotid" 
I1009 16:20:36.839] E1009 16:20:36.835695    8806 controllerserver.go:342] getFileShareInfo(reallyfakesnapshotid) failed with error: error parsing volume id: "reallyfakesnapshotid", should at least contain two #
I1009 16:20:36.839] I1009 16:20:36.835704    8806 controllerserver.go:295] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I1009 16:20:36.839] I1009 16:20:36.835712    8806 utils.go:118] GRPC response: 
I1009 16:20:36.840] •
I1009 16:20:36.840] ------------------------------
I1009 16:20:36.840] DeleteSnapshot [Controller Server] 
I1009 16:20:36.840]   should return appropriate values (no optional values added)
I1009 16:20:36.840]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/controller.go:1899
... skipping 28 lines ...
I1009 16:20:37.695] I1009 16:20:37.692728    8806 controllerserver.go:149] azure file(deletesnapshot-volume-1) under rg() account(fa9480518eab011e98d7e9a) volumeID(#fa9480518eab011e98d7e9a#deletesnapshot-volume-1) is deleted successfully
I1009 16:20:37.695] I1009 16:20:37.692851    8806 utils.go:118] GRPC response: 
I1009 16:20:37.695] •
I1009 16:20:37.695] 
I1009 16:20:37.695] Summarizing 1 Failure:
I1009 16:20:37.695] 
I1009 16:20:37.695] [Fail] Node Service [It] should work 
I1009 16:20:37.696] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v2.2.0+incompatible/pkg/sanity/node.go:768
I1009 16:20:37.696] 
I1009 16:20:37.696] Ran 35 of 72 Specs in 30.537 seconds
I1009 16:20:37.696] FAIL! -- 34 Passed | 1 Failed | 0 Pending | 37 Skipped
I1009 16:20:37.696] --- FAIL: TestSanity (30.55s)
I1009 16:20:37.696] FAIL
I1009 16:20:37.698] pkill -f azurefileplugin
I1009 16:20:37.710] Deleting CSI sanity test binary
I1009 16:20:37.794] test/sanity/run-test.sh: line 19:  8806 Terminated              _output/azurefileplugin --endpoint "$endpoint" --nodeid "$nodeid" -v=5
I1009 16:20:37.795] 2019/10/09 16:20:37 Deleting resource group azurefile-csi-driver-test-9a4154b7-eab0-11e9-a15d-9aa73a704504
I1009 16:22:24.790] --- FAIL: TestSanity (161.33s)
I1009 16:22:24.791]     sanity_test.go:78: Sanity test failed exit status 1
I1009 16:22:24.791] FAIL
I1009 16:22:24.794] FAIL	github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity	161.344s
I1009 16:22:24.864] Makefile:54: recipe for target 'sanity-test' failed
W1009 16:22:24.877] make: *** [sanity-test] Error 1
W1009 16:22:24.878] Traceback (most recent call last):
W1009 16:22:24.878]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module>
W1009 16:22:24.879]     main(ARGS.env, ARGS.cmd + ARGS.args)
W1009 16:22:24.879]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main
W1009 16:22:24.880]     check(*cmd)
W1009 16:22:24.880]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check
W1009 16:22:24.880]     subprocess.check_call(cmd)
W1009 16:22:24.881]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W1009 16:22:24.881]     raise CalledProcessError(retcode, cmd)
W1009 16:22:24.882] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2
E1009 16:22:24.883] Command failed
I1009 16:22:24.883] process 456 exited with code 1 after 3.8m
E1009 16:22:24.884] FAIL: pull-azurefile-csi-driver-sanity
I1009 16:22:24.884] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1009 16:22:25.495] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1009 16:22:25.555] process 8834 exited with code 0 after 0.0m
I1009 16:22:25.555] Call:  gcloud config get-value account
I1009 16:22:25.994] process 8846 exited with code 0 after 0.0m
I1009 16:22:25.994] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
... skipping 28 lines ...