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 16:52
Elapsed4m5s
Revision
Buildergke-prow-ssd-pool-1a225945-kp0f
Refs master:daea6716
120:0469787c
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/ae82d38f-2b9d-433a-9be9-47b1f2c94d58/targets/test'}}
podc4ff3d5d-e9eb-11e9-94c6-b6a3998ca3c7
resultstorehttps://source.cloud.google.com/results/invocations/ae82d38f-2b9d-433a-9be9-47b1f2c94d58/targets/test
infra-commitb2bb51680
podc4ff3d5d-e9eb-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 16:53:50.158] Running Suite: CSI Driver Test Suite
I1008 16:53:50.159] ====================================
I1008 16:53:50.159] Random Seed: 1570553630
I1008 16:53:50.160] Will run 57 of 57 specs
I1008 16:53:50.160] 
I1008 16:53:50.160] CreateSnapshot [Controller Server] 
I1008 16:53:50.160]   should fail when no name is provided
I1008 16:53:50.161]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1502
I1008 16:53:50.161] STEP: connecting to CSI driver
I1008 16:53:50.172] I1008 16:53:50.172242    8765 main.go:82] set up prometheus server on [::]:10252
I1008 16:53:50.173] I1008 16:53:50.173215    8765 azurefile.go:84] 
I1008 16:53:50.173] DRIVER INFORMATION:
I1008 16:53:50.174] -------------------
... skipping 23 lines ...
I1008 16:53:51.166] I1008 16:53:51.165608    8765 utils.go:112] GRPC request: 
I1008 16:53:51.166] I1008 16:53:51.165616    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:53:51.167] I1008 16:53:51.165620    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:53:51.167] I1008 16:53:51.167455    8765 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 16:53:51.168] I1008 16:53:51.167573    8765 utils.go:112] GRPC request: source_volume_id:"testId" 
I1008 16:53:51.168] I1008 16:53:51.167597    8765 controllerserver.go:214] CreateSnapshot called with request {testId  map[] map[] {} [] 0}
I1008 16:53:51.168] E1008 16:53:51.167620    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
I1008 16:53:51.169] •
I1008 16:53:51.169] ------------------------------
I1008 16:53:51.169] CreateSnapshot [Controller Server] 
I1008 16:53:51.170]   should fail when no source volume id is provided
I1008 16:53:51.170]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1519
I1008 16:53:51.170] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:53:51.170] STEP: creating mount and staging directories
I1008 16:53:51.171] I1008 16:53:51.168341    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:53:51.171] I1008 16:53:51.168371    8765 utils.go:112] GRPC request: 
I1008 16:53:51.171] I1008 16:53:51.168384    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:53:51.171] I1008 16:53:51.168422    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:53:51.172] I1008 16:53:51.168837    8765 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 16:53:51.172] I1008 16:53:51.168890    8765 utils.go:112] GRPC request: name:"name" 
I1008 16:53:51.172] I1008 16:53:51.168909    8765 controllerserver.go:214] CreateSnapshot called with request { name map[] map[] {} [] 0}
I1008 16:53:51.172] E1008 16:53:51.168982    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
I1008 16:53:51.173] •
I1008 16:53:51.173] ------------------------------
I1008 16:53:51.173] CreateSnapshot [Controller Server] 
I1008 16:53:51.173]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1008 16:53:51.174]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1536
I1008 16:53:51.174] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:53:51.174] STEP: creating mount and staging directories
I1008 16:53:51.174] I1008 16:53:51.169648    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:53:51.175] I1008 16:53:51.169712    8765 utils.go:112] GRPC request: 
I1008 16:53:51.175] I1008 16:53:51.169730    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 29 lines ...
I1008 16:54:12.086] I1008 16:54:12.084679    8765 controllerserver.go:149] azure file(createsnapshot-volume-1) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-1) is deleted successfully
I1008 16:54:12.087] I1008 16:54:12.084808    8765 utils.go:118] GRPC response: 
I1008 16:54:12.087] 
I1008 16:54:12.087] • [SLOW TEST:20.916 seconds]
I1008 16:54:12.087] CreateSnapshot [Controller Server]
I1008 16:54:12.088] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:12.088]   should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId.
I1008 16:54:12.088]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1536
I1008 16:54:12.088] ------------------------------
I1008 16:54:12.088] CreateSnapshot [Controller Server] 
I1008 16:54:12.088]   should fail when requesting to create a snapshot with already existing name and different SourceVolumeId.
I1008 16:54:12.089]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1566
I1008 16:54:12.089] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:12.089] STEP: creating mount and staging directories
I1008 16:54:12.089] I1008 16:54:12.085730    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:12.089] I1008 16:54:12.085763    8765 utils.go:112] GRPC request: 
I1008 16:54:12.089] I1008 16:54:12.085801    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 20 lines ...
I1008 16:54:12.922] I1008 16:54:12.922205    8765 controllerserver.go:108] create file share createsnapshot-volume-3 on storage account f34cde4c7e9ec11e9884e7a successfully
I1008 16:54:12.959] I1008 16:54:12.958665    8765 utils.go:118] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-3" > 
I1008 16:54:12.959] STEP: creating a snapshot with the same name but different volume source id
I1008 16:54:12.960] I1008 16:54:12.959705    8765 utils.go:111] GRPC call: /csi.v1.Controller/CreateSnapshot
I1008 16:54:12.961] I1008 16:54:12.959798    8765 utils.go:112] GRPC request: source_volume_id:"#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-3" name:"CreateSnapshot-snapshot-2" 
I1008 16:54:12.961] I1008 16:54:12.959901    8765 controllerserver.go:214] CreateSnapshot called with request {#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-3 CreateSnapshot-snapshot-2 map[] map[] {} [] 0}
I1008 16:54:13.074] E1008 16:54:13.073971    8765 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(#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-3)
I1008 16:54:13.075] STEP: cleaning up deleting the snapshot
I1008 16:54:13.075] I1008 16:54:13.074908    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 16:54:13.075] I1008 16:54:13.074989    8765 utils.go:112] GRPC request: snapshot_id:"#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-2#2019-10-08T16:54:12.0000000Z" 
I1008 16:54:13.231] I1008 16:54:13.230642    8765 controllerserver.go:313] delete snapshot(2019-10-08T16:54:12.0000000Z) successfully
I1008 16:54:13.232] I1008 16:54:13.230761    8765 utils.go:118] GRPC response: 
I1008 16:54:13.232] STEP: cleaning up deleting the volume
I1008 16:54:13.232] I1008 16:54:13.231496    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 16:54:13.232] I1008 16:54:13.231560    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-2" 
I1008 16:54:13.349] I1008 16:54:13.349106    8765 controllerserver.go:149] azure file(createsnapshot-volume-2) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#createsnapshot-volume-2) is deleted successfully
I1008 16:54:13.349] I1008 16:54:13.349135    8765 utils.go:118] GRPC response: 
I1008 16:54:13.350] •
I1008 16:54:13.350] ------------------------------
I1008 16:54:13.350] CreateSnapshot [Controller Server] 
I1008 16:54:13.350]   should not fail when creating snapshot with maximum-length name
I1008 16:54:13.350]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1601
I1008 16:54:13.350] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:13.350] STEP: creating mount and staging directories
I1008 16:54:13.351] I1008 16:54:13.349748    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:13.351] I1008 16:54:13.349761    8765 utils.go:112] GRPC request: 
I1008 16:54:13.351] I1008 16:54:13.349767    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 107 lines ...
I1008 16:54:14.543] I1008 16:54:14.539016    8765 utils.go:112] GRPC request: 
I1008 16:54:14.543] I1008 16:54:14.539036    8765 nodeserver.go:228] Using default NodeGetInfo
I1008 16:54:14.544] I1008 16:54:14.539048    8765 utils.go:118] GRPC response: node_id:"CSINode" 
I1008 16:54:14.544] •
I1008 16:54:14.544] ------------------------------
I1008 16:54:14.544] Node Service NodePublishVolume 
I1008 16:54:14.544]   should fail when no volume id is provided
I1008 16:54:14.544]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:158
I1008 16:54:14.545] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.545] STEP: creating mount and staging directories
I1008 16:54:14.545] I1008 16:54:14.539432    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.545] I1008 16:54:14.539583    8765 utils.go:112] GRPC request: 
I1008 16:54:14.545] I1008 16:54:14.539637    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:14.545] I1008 16:54:14.539655    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:14.546] I1008 16:54:14.540136    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 16:54:14.546] I1008 16:54:14.540231    8765 utils.go:112] GRPC request: 
I1008 16:54:14.546] I1008 16:54:14.540258    8765 nodeserver.go:219] Using default NodeGetCapabilities
I1008 16:54:14.546] I1008 16:54:14.540277    8765 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 16:54:14.546] I1008 16:54:14.540906    8765 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 16:54:14.547] I1008 16:54:14.540963    8765 utils.go:112] GRPC request: 
I1008 16:54:14.547] E1008 16:54:14.540979    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 16:54:14.547] •
I1008 16:54:14.547] ------------------------------
I1008 16:54:14.547] Node Service NodePublishVolume 
I1008 16:54:14.547]   should fail when no target path is provided
I1008 16:54:14.547]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:172
I1008 16:54:14.548] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.548] STEP: creating mount and staging directories
I1008 16:54:14.548] I1008 16:54:14.541356    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.548] I1008 16:54:14.541403    8765 utils.go:112] GRPC request: 
I1008 16:54:14.548] I1008 16:54:14.541422    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:14.549] I1008 16:54:14.541438    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:14.549] I1008 16:54:14.541769    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 16:54:14.549] I1008 16:54:14.541803    8765 utils.go:112] GRPC request: 
I1008 16:54:14.549] I1008 16:54:14.541821    8765 nodeserver.go:219] Using default NodeGetCapabilities
I1008 16:54:14.549] I1008 16:54:14.541860    8765 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 16:54:14.550] I1008 16:54:14.542209    8765 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 16:54:14.550] I1008 16:54:14.542263    8765 utils.go:112] GRPC request: volume_id:"id" 
I1008 16:54:14.550] E1008 16:54:14.542281    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 16:54:14.550] •
I1008 16:54:14.550] ------------------------------
I1008 16:54:14.551] Node Service NodePublishVolume 
I1008 16:54:14.551]   should fail when no volume capability is provided
I1008 16:54:14.551]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:187
I1008 16:54:14.551] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.551] STEP: creating mount and staging directories
I1008 16:54:14.551] I1008 16:54:14.542710    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.552] I1008 16:54:14.542774    8765 utils.go:112] GRPC request: 
I1008 16:54:14.552] I1008 16:54:14.542793    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:14.552] I1008 16:54:14.542811    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:14.552] I1008 16:54:14.543186    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 16:54:14.552] I1008 16:54:14.543223    8765 utils.go:112] GRPC request: 
I1008 16:54:14.552] I1008 16:54:14.543261    8765 nodeserver.go:219] Using default NodeGetCapabilities
I1008 16:54:14.553] I1008 16:54:14.543283    8765 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 16:54:14.553] I1008 16:54:14.543620    8765 utils.go:111] GRPC call: /csi.v1.Node/NodePublishVolume
I1008 16:54:14.553] I1008 16:54:14.543673    8765 utils.go:112] GRPC request: volume_id:"id" target_path:"/tmp/csi" 
I1008 16:54:14.553] E1008 16:54:14.543693    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
I1008 16:54:14.553] •
I1008 16:54:14.554] ------------------------------
I1008 16:54:14.554] Node Service NodeUnpublishVolume 
I1008 16:54:14.554]   should fail when no volume id is provided
I1008 16:54:14.554]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:205
I1008 16:54:14.554] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.554] STEP: creating mount and staging directories
I1008 16:54:14.554] I1008 16:54:14.544159    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.555] I1008 16:54:14.544196    8765 utils.go:112] GRPC request: 
I1008 16:54:14.555] I1008 16:54:14.544213    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:14.555] I1008 16:54:14.544247    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:14.555] I1008 16:54:14.544624    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 16:54:14.555] I1008 16:54:14.544686    8765 utils.go:112] GRPC request: 
I1008 16:54:14.555] I1008 16:54:14.544707    8765 nodeserver.go:219] Using default NodeGetCapabilities
I1008 16:54:14.556] I1008 16:54:14.544738    8765 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 16:54:14.556] I1008 16:54:14.545164    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 16:54:14.556] I1008 16:54:14.545204    8765 utils.go:112] GRPC request: 
I1008 16:54:14.556] E1008 16:54:14.545223    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 16:54:14.556] •
I1008 16:54:14.556] ------------------------------
I1008 16:54:14.557] Node Service NodeUnpublishVolume 
I1008 16:54:14.557]   should fail when no target path is provided
I1008 16:54:14.557]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:217
I1008 16:54:14.557] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.557] STEP: creating mount and staging directories
I1008 16:54:14.558] I1008 16:54:14.545646    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.558] I1008 16:54:14.545679    8765 utils.go:112] GRPC request: 
I1008 16:54:14.558] I1008 16:54:14.545696    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:14.558] I1008 16:54:14.545731    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:14.558] I1008 16:54:14.546130    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeGetCapabilities
I1008 16:54:14.559] I1008 16:54:14.546189    8765 utils.go:112] GRPC request: 
I1008 16:54:14.559] I1008 16:54:14.546213    8765 nodeserver.go:219] Using default NodeGetCapabilities
I1008 16:54:14.559] I1008 16:54:14.546230    8765 utils.go:118] GRPC response: capabilities:<rpc:<> > 
I1008 16:54:14.559] I1008 16:54:14.546595    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 16:54:14.559] I1008 16:54:14.546644    8765 utils.go:112] GRPC request: volume_id:"id" 
I1008 16:54:14.560] E1008 16:54:14.546660    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
I1008 16:54:14.560] •
I1008 16:54:14.560] ------------------------------
I1008 16:54:14.560] Node Service NodeStageVolume 
I1008 16:54:14.560]   should fail when no volume id is provided
I1008 16:54:14.561]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 16:54:14.561] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.561] STEP: creating mount and staging directories
I1008 16:54:14.561] I1008 16:54:14.547012    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.561] I1008 16:54:14.547043    8765 utils.go:112] GRPC request: 
I1008 16:54:14.561] I1008 16:54:14.547059    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 16:54:14.562] 
I1008 16:54:14.563] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:14.563] Node Service
I1008 16:54:14.563] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:14.563]   NodeStageVolume
I1008 16:54:14.563]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 16:54:14.563]     should fail when no volume id is provided [BeforeEach]
I1008 16:54:14.564]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:245
I1008 16:54:14.564] 
I1008 16:54:14.564]     NodeStageVolume not supported
I1008 16:54:14.564] 
I1008 16:54:14.564]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 16:54:14.564] ------------------------------
I1008 16:54:14.564] Node Service NodeStageVolume 
I1008 16:54:14.565]   should fail when no staging target path is provided
I1008 16:54:14.565]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 16:54:14.565] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.565] STEP: creating mount and staging directories
I1008 16:54:14.565] I1008 16:54:14.548095    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.566] I1008 16:54:14.548124    8765 utils.go:112] GRPC request: 
I1008 16:54:14.566] I1008 16:54:14.548140    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 16:54:14.567] 
I1008 16:54:14.567] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:14.567] Node Service
I1008 16:54:14.567] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:14.567]   NodeStageVolume
I1008 16:54:14.568]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 16:54:14.568]     should fail when no staging target path is provided [BeforeEach]
I1008 16:54:14.568]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:271
I1008 16:54:14.568] 
I1008 16:54:14.568]     NodeStageVolume not supported
I1008 16:54:14.568] 
I1008 16:54:14.568]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 16:54:14.569] ------------------------------
I1008 16:54:14.569] Node Service NodeStageVolume 
I1008 16:54:14.569]   should fail when no volume capability is provided
I1008 16:54:14.569]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 16:54:14.569] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.569] STEP: creating mount and staging directories
I1008 16:54:14.569] I1008 16:54:14.549210    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.570] I1008 16:54:14.549348    8765 utils.go:112] GRPC request: 
I1008 16:54:14.570] I1008 16:54:14.549372    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 16:54:14.571] 
I1008 16:54:14.571] S [SKIPPING] in Spec Setup (BeforeEach) [0.002 seconds]
I1008 16:54:14.571] Node Service
I1008 16:54:14.571] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:14.571]   NodeStageVolume
I1008 16:54:14.572]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:232
I1008 16:54:14.572]     should fail when no volume capability is provided [BeforeEach]
I1008 16:54:14.572]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:297
I1008 16:54:14.572] 
I1008 16:54:14.572]     NodeStageVolume not supported
I1008 16:54:14.572] 
I1008 16:54:14.572]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:239
I1008 16:54:14.573] ------------------------------
I1008 16:54:14.573] Node Service NodeUnstageVolume 
I1008 16:54:14.573]   should fail when no volume id is provided
I1008 16:54:14.573]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 16:54:14.573] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.573] STEP: creating mount and staging directories
I1008 16:54:14.574] I1008 16:54:14.551118    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.574] I1008 16:54:14.551167    8765 utils.go:112] GRPC request: 
I1008 16:54:14.574] I1008 16:54:14.551182    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 16:54:14.575] 
I1008 16:54:14.575] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:14.575] Node Service
I1008 16:54:14.575] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:14.575]   NodeUnstageVolume
I1008 16:54:14.576]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 16:54:14.576]     should fail when no volume id is provided [BeforeEach]
I1008 16:54:14.576]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:324
I1008 16:54:14.576] 
I1008 16:54:14.576]     NodeUnstageVolume not supported
I1008 16:54:14.576] 
I1008 16:54:14.576]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 16:54:14.576] ------------------------------
I1008 16:54:14.577] Node Service NodeUnstageVolume 
I1008 16:54:14.577]   should fail when no staging target path is provided
I1008 16:54:14.577]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 16:54:14.577] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:14.577] STEP: creating mount and staging directories
I1008 16:54:14.577] I1008 16:54:14.552172    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:14.577] I1008 16:54:14.552183    8765 utils.go:112] GRPC request: 
I1008 16:54:14.578] I1008 16:54:14.552187    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 5 lines ...
I1008 16:54:14.579] 
I1008 16:54:14.579] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:14.579] Node Service
I1008 16:54:14.579] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:14.579]   NodeUnstageVolume
I1008 16:54:14.579]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:317
I1008 16:54:14.579]     should fail when no staging target path is provided [BeforeEach]
I1008 16:54:14.580]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:338
I1008 16:54:14.580] 
I1008 16:54:14.580]     NodeUnstageVolume not supported
I1008 16:54:14.580] 
I1008 16:54:14.580]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:320
I1008 16:54:14.580] ------------------------------
... skipping 32 lines ...
I1008 16:54:14.808] 
I1008 16:54:14.808] readonly false
I1008 16:54:14.808] volumeId #f34cde4c7e9ec11e9884e7a#sanity-node-full-f3ef7b1b-e41b70ab
I1008 16:54:14.808] context map[]
I1008 16:54:14.808] mountflags []
I1008 16:54:14.886] I1008 16:54:14.886284    8765 mount_linux.go:138] Mounting cmd (mount) with arguments ([-t cifs -o username=f34cde4c7e9ec11e9884e7a,password=DeouNiv63dUgAyjaIP9qB9UQdwsfCEx7lIH3eTg2wDQ2xxp1CQxDq99Ep/dTDGrprxr36UzrNk28U1viuWJ4rw==,file_mode=0777,dir_mode=0777,vers=3.0 //f34cde4c7e9ec11e9884e7a.file.core.windows.net/sanity-node-full-f3ef7b1b-e41b70ab /tmp/csi])
I1008 16:54:14.955] E1008 16:54:14.954943    8765 mount_linux.go:143] Mount failed: exit status 32
I1008 16:54:14.957] Mounting command: mount
I1008 16:54:14.957] Mounting arguments: -t cifs -o username=f34cde4c7e9ec11e9884e7a,password=DeouNiv63dUgAyjaIP9qB9UQdwsfCEx7lIH3eTg2wDQ2xxp1CQxDq99Ep/dTDGrprxr36UzrNk28U1viuWJ4rw==,file_mode=0777,dir_mode=0777,vers=3.0 //f34cde4c7e9ec11e9884e7a.file.core.windows.net/sanity-node-full-f3ef7b1b-e41b70ab /tmp/csi
I1008 16:54:14.958] Output: mount error: cifs filesystem not supported by the system
I1008 16:54:14.958] mount error(19): No such device
I1008 16:54:14.958] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 16:54:14.958] 
I1008 16:54:14.958] E1008 16:54:14.955152    8765 utils.go:116] GRPC error: mount failed: exit status 32
I1008 16:54:14.958] Mounting command: mount
I1008 16:54:14.959] Mounting arguments: -t cifs -o username=f34cde4c7e9ec11e9884e7a,password=DeouNiv63dUgAyjaIP9qB9UQdwsfCEx7lIH3eTg2wDQ2xxp1CQxDq99Ep/dTDGrprxr36UzrNk28U1viuWJ4rw==,file_mode=0777,dir_mode=0777,vers=3.0 //f34cde4c7e9ec11e9884e7a.file.core.windows.net/sanity-node-full-f3ef7b1b-e41b70ab /tmp/csi
I1008 16:54:14.959] Output: mount error: cifs filesystem not supported by the system
I1008 16:54:14.959] mount error(19): No such device
I1008 16:54:14.959] Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 16:54:14.959] 
I1008 16:54:14.959] cleanup: deleting sanity-node-full-F3EF7B1B-E41B70AB = #f34cde4c7e9ec11e9884e7a#sanity-node-full-f3ef7b1b-e41b70ab
I1008 16:54:14.959] I1008 16:54:14.956269    8765 utils.go:111] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I1008 16:54:14.959] I1008 16:54:14.956336    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-node-full-f3ef7b1b-e41b70ab" target_path:"/tmp/csi" 
I1008 16:54:14.960] I1008 16:54:14.956356    8765 mount_linux.go:203] Unmounting /tmp/csi
I1008 16:54:14.963] E1008 16:54:14.962364    8765 utils.go:116] GRPC error: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 16:54:14.963] Unmounting arguments: /tmp/csi
I1008 16:54:14.963] Output: umount: /tmp/csi: mountpoint not found
I1008 16:54:14.963] 
I1008 16:54:14.964] cleanup: warning: NodeUnpublishVolume: rpc error: code = Internal desc = Unmount failed: exit status 32
I1008 16:54:14.964] Unmounting arguments: /tmp/csi
I1008 16:54:14.964] Output: umount: /tmp/csi: mountpoint not found
I1008 16:54:14.964] 
I1008 16:54:14.964] I1008 16:54:14.962949    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 16:54:14.964] I1008 16:54:14.963098    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-node-full-f3ef7b1b-e41b70ab" 
I1008 16:54:15.118] I1008 16:54:15.118063    8765 controllerserver.go:149] azure file(sanity-node-full-f3ef7b1b-e41b70ab) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#sanity-node-full-f3ef7b1b-e41b70ab) is deleted successfully
... skipping 2 lines ...
I1008 16:54:15.119] • Failure [0.565 seconds]
I1008 16:54:15.119] Node Service
I1008 16:54:15.119] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:15.119]   should work [It]
I1008 16:54:15.119]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:353
I1008 16:54:15.119] 
I1008 16:54:15.119]   Expected error:
I1008 16:54:15.119]       <*status.statusError | 0xc0002d1a40>: {
I1008 16:54:15.119]           Code: 2,
I1008 16:54:15.120]           Message: "mount failed: exit status 32\nMounting command: mount\nMounting arguments: -t cifs -o username=f34cde4c7e9ec11e9884e7a,password=DeouNiv63dUgAyjaIP9qB9UQdwsfCEx7lIH3eTg2wDQ2xxp1CQxDq99Ep/dTDGrprxr36UzrNk28U1viuWJ4rw==,file_mode=0777,dir_mode=0777,vers=3.0 //f34cde4c7e9ec11e9884e7a.file.core.windows.net/sanity-node-full-f3ef7b1b-e41b70ab /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 16:54:15.120]           Details: nil,
I1008 16:54:15.120]           XXX_NoUnkeyedLiteral: {},
I1008 16:54:15.120]           XXX_unrecognized: nil,
I1008 16:54:15.120]           XXX_sizecache: 0,
I1008 16:54:15.120]       }
I1008 16:54:15.120]       rpc error: code = Unknown desc = mount failed: exit status 32
I1008 16:54:15.120]       Mounting command: mount
I1008 16:54:15.121]       Mounting arguments: -t cifs -o username=f34cde4c7e9ec11e9884e7a,password=DeouNiv63dUgAyjaIP9qB9UQdwsfCEx7lIH3eTg2wDQ2xxp1CQxDq99Ep/dTDGrprxr36UzrNk28U1viuWJ4rw==,file_mode=0777,dir_mode=0777,vers=3.0 //f34cde4c7e9ec11e9884e7a.file.core.windows.net/sanity-node-full-f3ef7b1b-e41b70ab /tmp/csi
I1008 16:54:15.121]       Output: mount error: cifs filesystem not supported by the system
I1008 16:54:15.121]       mount error(19): No such device
I1008 16:54:15.121]       Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I1008 16:54:15.121]       
I1008 16:54:15.121]       
I1008 16:54:15.121]   not to have occurred
I1008 16:54:15.121] 
I1008 16:54:15.121]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
I1008 16:54:15.121] ------------------------------
I1008 16:54:15.121] DeleteSnapshot [Controller Server] 
I1008 16:54:15.121]   should fail when no snapshot id is provided
I1008 16:54:15.122]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1439
I1008 16:54:15.122] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:15.122] STEP: creating mount and staging directories
I1008 16:54:15.122] I1008 16:54:15.119198    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:15.122] I1008 16:54:15.119236    8765 utils.go:112] GRPC request: 
I1008 16:54:15.122] I1008 16:54:15.119251    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:15.122] I1008 16:54:15.119295    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:15.122] I1008 16:54:15.119855    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 16:54:15.122] I1008 16:54:15.119920    8765 utils.go:112] GRPC request: 
I1008 16:54:15.123] E1008 16:54:15.119935    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
I1008 16:54:15.123] •
I1008 16:54:15.123] ------------------------------
I1008 16:54:15.123] DeleteSnapshot [Controller Server] 
I1008 16:54:15.123]   should succeed when an invalid snapshot id is used
I1008 16:54:15.123]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1455
I1008 16:54:15.123] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:15.123] STEP: creating mount and staging directories
I1008 16:54:15.123] I1008 16:54:15.120259    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:15.123] I1008 16:54:15.120489    8765 utils.go:112] GRPC request: 
I1008 16:54:15.123] I1008 16:54:15.120570    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:15.124] I1008 16:54:15.120589    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:15.124] I1008 16:54:15.120964    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteSnapshot
I1008 16:54:15.124] I1008 16:54:15.121006    8765 utils.go:112] GRPC request: snapshot_id:"reallyfakesnapshotid" 
I1008 16:54:15.124] E1008 16:54:15.121034    8765 controllerserver.go:342] getFileShareInfo(reallyfakesnapshotid) failed with error: error parsing volume id: "reallyfakesnapshotid", should at least contain two #
I1008 16:54:15.124] I1008 16:54:15.121050    8765 controllerserver.go:295] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I1008 16:54:15.124] I1008 16:54:15.121078    8765 utils.go:118] GRPC response: 
I1008 16:54:15.124] •
I1008 16:54:15.125] ------------------------------
I1008 16:54:15.125] DeleteSnapshot [Controller Server] 
I1008 16:54:15.125]   should return appropriate values (no optional values added)
I1008 16:54:15.125]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1462
... skipping 223 lines ...
I1008 16:54:15.966] 
I1008 16:54:15.966]     ListVolumes not supported
I1008 16:54:15.966] 
I1008 16:54:15.966]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:157
I1008 16:54:15.966] ------------------------------
I1008 16:54:15.967] Controller Service CreateVolume 
I1008 16:54:15.967]   should fail when no name is provided
I1008 16:54:15.967]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:186
I1008 16:54:15.967] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:15.967] STEP: creating mount and staging directories
I1008 16:54:15.967] I1008 16:54:15.946540    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:15.968] I1008 16:54:15.946549    8765 utils.go:112] GRPC request: 
I1008 16:54:15.968] I1008 16:54:15.946553    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:15.968] I1008 16:54:15.946557    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:15.968] I1008 16:54:15.946880    8765 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 16:54:15.969] I1008 16:54:15.946925    8765 utils.go:112] GRPC request: 
I1008 16:54:15.969] E1008 16:54:15.946944    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
I1008 16:54:15.969] •
I1008 16:54:15.969] ------------------------------
I1008 16:54:15.969] Controller Service CreateVolume 
I1008 16:54:15.969]   should fail when no volume capabilities are provided
I1008 16:54:15.970]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:202
I1008 16:54:15.970] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:15.970] STEP: creating mount and staging directories
I1008 16:54:15.970] I1008 16:54:15.947206    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:15.970] I1008 16:54:15.947268    8765 utils.go:112] GRPC request: 
I1008 16:54:15.971] I1008 16:54:15.947296    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:15.971] I1008 16:54:15.947310    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:15.971] I1008 16:54:15.947576    8765 utils.go:111] GRPC call: /csi.v1.Controller/CreateVolume
I1008 16:54:15.971] I1008 16:54:15.947616    8765 utils.go:112] GRPC request: name:"sanity-controller-create-no-volume-capabilities-F3EF7B1B-E41B70AB" 
I1008 16:54:15.972] E1008 16:54:15.947632    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities must be provided
I1008 16:54:15.972] •
I1008 16:54:15.972] ------------------------------
I1008 16:54:15.972] Controller Service CreateVolume 
I1008 16:54:15.972]   should return appropriate values SingleNodeWriter NoCapacity Type:Mount
I1008 16:54:15.973]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:220
I1008 16:54:15.973] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 40 lines ...
I1008 16:54:16.615] I1008 16:54:16.614289    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-controller-create-single-with-capacity-f3ef7b1b-e41b70ab" 
I1008 16:54:16.725] I1008 16:54:16.725409    8765 controllerserver.go:149] azure file(sanity-controller-create-single-with-capacity-f3ef7b1b-e41b70ab) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#sanity-controller-create-single-with-capacity-f3ef7b1b-e41b70ab) is deleted successfully
I1008 16:54:16.726] I1008 16:54:16.725440    8765 utils.go:118] GRPC response: 
I1008 16:54:16.726] •
I1008 16:54:16.726] ------------------------------
I1008 16:54:16.726] Controller Service CreateVolume 
I1008 16:54:16.726]   should not fail when requesting to create a volume with already existing name and same capacity.
I1008 16:54:16.726]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:311
I1008 16:54:16.726] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:16.726] STEP: creating mount and staging directories
I1008 16:54:16.727] I1008 16:54:16.726453    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:16.727] I1008 16:54:16.726631    8765 utils.go:112] GRPC request: 
I1008 16:54:16.727] I1008 16:54:16.726690    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 19 lines ...
I1008 16:54:17.228] I1008 16:54:17.228234    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-controller-create-twice-f3ef7b1b-e41b70ab" 
I1008 16:54:17.384] I1008 16:54:17.383931    8765 controllerserver.go:149] azure file(sanity-controller-create-twice-f3ef7b1b-e41b70ab) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#sanity-controller-create-twice-f3ef7b1b-e41b70ab) is deleted successfully
I1008 16:54:17.384] I1008 16:54:17.384155    8765 utils.go:118] GRPC response: 
I1008 16:54:17.385] •
I1008 16:54:17.385] ------------------------------
I1008 16:54:17.385] Controller Service CreateVolume 
I1008 16:54:17.385]   should fail when requesting to create a volume with already existing name and different capacity.
I1008 16:54:17.385]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:385
I1008 16:54:17.385] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:17.385] STEP: creating mount and staging directories
I1008 16:54:17.385] I1008 16:54:17.385009    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:17.385] I1008 16:54:17.385077    8765 utils.go:112] GRPC request: 
I1008 16:54:17.386] I1008 16:54:17.385100    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 10 lines ...
I1008 16:54:17.607] I1008 16:54:17.606941    8765 utils.go:112] GRPC request: name:"sanity-controller-create-twice-different-F3EF7B1B-E41B70AB" capacity_range:<required_bytes:21474836480 limit_bytes:21474836480 > volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 16:54:17.607] I1008 16:54:17.606971    8765 controllerserver.go:95] begin to create file share(sanity-controller-create-twice-different-f3ef7b1b-e41b70ab) on account() type() rg() location() size(20)
I1008 16:54:17.672] I1008 16:54:17.671710    8765 azure_storageaccount.go:103] found a matching account f34cde4c7e9ec11e9884e7a type Standard_LRS location eastus2
I1008 16:54:17.856] I1008 16:54:17.855993    8765 azure_file.go:68] file share(sanity-controller-create-twice-different-f3ef7b1b-e41b70ab) under account(f34cde4c7e9ec11e9884e7a) already exists
I1008 16:54:17.856] I1008 16:54:17.856025    8765 azure_storage.go:49] created share sanity-controller-create-twice-different-f3ef7b1b-e41b70ab in account f34cde4c7e9ec11e9884e7a
I1008 16:54:17.856] I1008 16:54:17.856036    8765 controllerserver.go:108] create file share sanity-controller-create-twice-different-f3ef7b1b-e41b70ab on storage account f34cde4c7e9ec11e9884e7a successfully
I1008 16:54:17.893] E1008 16:54:17.892117    8765 utils.go:116] GRPC error: rpc error: code = AlreadyExists desc = the request volume already exists, but its capacity(10) is different from (20)
I1008 16:54:17.893] STEP: cleaning up deleting the volume
I1008 16:54:17.893] I1008 16:54:17.892679    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 16:54:17.893] I1008 16:54:17.892693    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-controller-create-twice-different-f3ef7b1b-e41b70ab" 
I1008 16:54:18.030] I1008 16:54:18.030024    8765 controllerserver.go:149] azure file(sanity-controller-create-twice-different-f3ef7b1b-e41b70ab) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#sanity-controller-create-twice-different-f3ef7b1b-e41b70ab) is deleted successfully
I1008 16:54:18.030] I1008 16:54:18.030061    8765 utils.go:118] GRPC response: 
I1008 16:54:18.031] •
I1008 16:54:18.031] ------------------------------
I1008 16:54:18.031] Controller Service CreateVolume 
I1008 16:54:18.031]   should not fail when creating volume with maximum-length name
I1008 16:54:18.031]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:460
I1008 16:54:18.031] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:18.031] STEP: creating mount and staging directories
I1008 16:54:18.031] I1008 16:54:18.030827    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:18.031] I1008 16:54:18.030848    8765 utils.go:112] GRPC request: 
I1008 16:54:18.031] I1008 16:54:18.030854    8765 controllerserver.go:183] Using default ControllerGetCapabilities
... skipping 11 lines ...
I1008 16:54:18.272] I1008 16:54:18.271037    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa" 
I1008 16:54:18.448] I1008 16:54:18.447297    8765 controllerserver.go:149] azure file(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) is deleted successfully
I1008 16:54:18.448] I1008 16:54:18.447427    8765 utils.go:118] GRPC response: 
I1008 16:54:18.448] •
I1008 16:54:18.448] ------------------------------
I1008 16:54:18.448] Controller Service DeleteVolume 
I1008 16:54:18.448]   should fail when no volume id is provided
I1008 16:54:18.449]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:519
I1008 16:54:18.449] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:18.449] STEP: creating mount and staging directories
I1008 16:54:18.449] I1008 16:54:18.448549    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:18.449] I1008 16:54:18.448603    8765 utils.go:112] GRPC request: 
I1008 16:54:18.450] I1008 16:54:18.448619    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:18.450] I1008 16:54:18.448631    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:18.450] I1008 16:54:18.449752    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 16:54:18.450] I1008 16:54:18.449801    8765 utils.go:112] GRPC request: 
I1008 16:54:18.450] E1008 16:54:18.449809    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 16:54:18.451] •
I1008 16:54:18.451] ------------------------------
I1008 16:54:18.451] Controller Service DeleteVolume 
I1008 16:54:18.451]   should succeed when an invalid volume id is used
I1008 16:54:18.451]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:534
I1008 16:54:18.451] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:18.451] STEP: creating mount and staging directories
I1008 16:54:18.452] I1008 16:54:18.450338    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:18.452] I1008 16:54:18.450543    8765 utils.go:112] GRPC request: 
I1008 16:54:18.452] I1008 16:54:18.450585    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:18.452] I1008 16:54:18.450605    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:18.452] I1008 16:54:18.450998    8765 utils.go:111] GRPC call: /csi.v1.Controller/DeleteVolume
I1008 16:54:18.452] I1008 16:54:18.451009    8765 utils.go:112] GRPC request: volume_id:"reallyfakevolumeid" 
I1008 16:54:18.452] E1008 16:54:18.451021    8765 controllerserver.go:342] getFileShareInfo(reallyfakevolumeid) failed with error: error parsing volume id: "reallyfakevolumeid", should at least contain two #
I1008 16:54:18.453] I1008 16:54:18.451029    8765 controllerserver.go:137] failed to get share url with (reallyfakevolumeid): error parsing volume id: "reallyfakevolumeid", should at least contain two #, returning with success
I1008 16:54:18.453] I1008 16:54:18.451059    8765 utils.go:118] GRPC response: 
I1008 16:54:18.453] •
I1008 16:54:18.453] ------------------------------
I1008 16:54:18.453] Controller Service DeleteVolume 
I1008 16:54:18.453]   should return appropriate values (no optional values added)
I1008 16:54:18.453]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:546
... skipping 17 lines ...
I1008 16:54:18.738] I1008 16:54:18.737778    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-controller-create-appropriate-f3ef7b1b-e41b70ab" 
I1008 16:54:18.881] I1008 16:54:18.880835    8765 controllerserver.go:149] azure file(sanity-controller-create-appropriate-f3ef7b1b-e41b70ab) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#sanity-controller-create-appropriate-f3ef7b1b-e41b70ab) is deleted successfully
I1008 16:54:18.881] I1008 16:54:18.880931    8765 utils.go:118] GRPC response: 
I1008 16:54:18.881] •
I1008 16:54:18.881] ------------------------------
I1008 16:54:18.881] Controller Service ValidateVolumeCapabilities 
I1008 16:54:18.882]   should fail when no volume id is provided
I1008 16:54:18.882]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:592
I1008 16:54:18.882] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:18.882] STEP: creating mount and staging directories
I1008 16:54:18.882] I1008 16:54:18.881763    8765 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 16:54:18.882] I1008 16:54:18.881825    8765 utils.go:112] GRPC request: 
I1008 16:54:18.882] E1008 16:54:18.881841    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
I1008 16:54:18.882] •
I1008 16:54:18.882] ------------------------------
I1008 16:54:18.882] Controller Service ValidateVolumeCapabilities 
I1008 16:54:18.882]   should fail when no volume capabilities are provided
I1008 16:54:18.883]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:604
I1008 16:54:18.883] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:18.883] STEP: creating mount and staging directories
I1008 16:54:18.883] I1008 16:54:18.882331    8765 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 16:54:18.883] I1008 16:54:18.882369    8765 utils.go:112] GRPC request: volume_id:"id" 
I1008 16:54:18.883] E1008 16:54:18.882383    8765 utils.go:116] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities missing in request
I1008 16:54:18.883] •
I1008 16:54:18.883] ------------------------------
I1008 16:54:18.883] Controller Service ValidateVolumeCapabilities 
I1008 16:54:18.883]   should return appropriate values (no optional values added)
I1008 16:54:18.883]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:618
I1008 16:54:18.883] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 16 lines ...
I1008 16:54:19.274] I1008 16:54:19.274083    8765 utils.go:112] GRPC request: volume_id:"#f34cde4c7e9ec11e9884e7a#sanity-controller-validate-f3ef7b1b-e41b70ab" 
I1008 16:54:19.424] I1008 16:54:19.414771    8765 controllerserver.go:149] azure file(sanity-controller-validate-f3ef7b1b-e41b70ab) under rg() account(f34cde4c7e9ec11e9884e7a) volumeID(#f34cde4c7e9ec11e9884e7a#sanity-controller-validate-f3ef7b1b-e41b70ab) is deleted successfully
I1008 16:54:19.424] I1008 16:54:19.414865    8765 utils.go:118] GRPC response: 
I1008 16:54:19.425] •
I1008 16:54:19.425] ------------------------------
I1008 16:54:19.425] Controller Service ValidateVolumeCapabilities 
I1008 16:54:19.425]   should fail when the requested volume does not exist
I1008 16:54:19.426]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:687
I1008 16:54:19.426] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.426] STEP: creating mount and staging directories
I1008 16:54:19.427] I1008 16:54:19.415661    8765 utils.go:111] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I1008 16:54:19.427] I1008 16:54:19.415705    8765 utils.go:112] GRPC request: volume_id:"some-vol-id" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > 
I1008 16:54:19.427] E1008 16:54:19.415724    8765 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 16:54:19.427] •
I1008 16:54:19.427] ------------------------------
I1008 16:54:19.428] Controller Service ControllerPublishVolume 
I1008 16:54:19.428]   should fail when no volume id is provided
I1008 16:54:19.428]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 16:54:19.428] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.428] STEP: creating mount and staging directories
I1008 16:54:19.428] I1008 16:54:19.416101    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.429] I1008 16:54:19.416139    8765 utils.go:112] GRPC request: 
I1008 16:54:19.429] I1008 16:54:19.416154    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.429] I1008 16:54:19.416167    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.429] 
I1008 16:54:19.429] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.430] Controller Service
I1008 16:54:19.430] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.430]   ControllerPublishVolume
I1008 16:54:19.431]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 16:54:19.431]     should fail when no volume id is provided [BeforeEach]
I1008 16:54:19.431]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:720
I1008 16:54:19.432] 
I1008 16:54:19.432]     ControllerPublishVolume not supported
I1008 16:54:19.432] 
I1008 16:54:19.433]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.433] ------------------------------
I1008 16:54:19.433] Controller Service ControllerPublishVolume 
I1008 16:54:19.434]   should fail when no node id is provided
I1008 16:54:19.434]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 16:54:19.434] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.435] STEP: creating mount and staging directories
I1008 16:54:19.435] I1008 16:54:19.416828    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.436] I1008 16:54:19.416865    8765 utils.go:112] GRPC request: 
I1008 16:54:19.436] I1008 16:54:19.416891    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.436] I1008 16:54:19.416921    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.437] 
I1008 16:54:19.437] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.438] Controller Service
I1008 16:54:19.438] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.438]   ControllerPublishVolume
I1008 16:54:19.439]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 16:54:19.439]     should fail when no node id is provided [BeforeEach]
I1008 16:54:19.439]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:735
I1008 16:54:19.440] 
I1008 16:54:19.440]     ControllerPublishVolume not supported
I1008 16:54:19.440] 
I1008 16:54:19.441]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.441] ------------------------------
I1008 16:54:19.442] Controller Service ControllerPublishVolume 
I1008 16:54:19.442]   should fail when no volume capability is provided
I1008 16:54:19.442]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 16:54:19.442] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.443] STEP: creating mount and staging directories
I1008 16:54:19.443] I1008 16:54:19.417627    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.443] I1008 16:54:19.417666    8765 utils.go:112] GRPC request: 
I1008 16:54:19.443] I1008 16:54:19.417683    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.444] I1008 16:54:19.417695    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.444] 
I1008 16:54:19.444] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.444] Controller Service
I1008 16:54:19.444] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.444]   ControllerPublishVolume
I1008 16:54:19.445]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 16:54:19.445]     should fail when no volume capability is provided [BeforeEach]
I1008 16:54:19.445]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:751
I1008 16:54:19.445] 
I1008 16:54:19.445]     ControllerPublishVolume not supported
I1008 16:54:19.446] 
I1008 16:54:19.446]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.446] ------------------------------
... skipping 17 lines ...
I1008 16:54:19.449] 
I1008 16:54:19.449]     ControllerPublishVolume not supported
I1008 16:54:19.450] 
I1008 16:54:19.450]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.450] ------------------------------
I1008 16:54:19.450] Controller Service ControllerPublishVolume 
I1008 16:54:19.450]   should fail when the volume does not exist
I1008 16:54:19.450]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 16:54:19.451] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.451] STEP: creating mount and staging directories
I1008 16:54:19.451] I1008 16:54:19.419262    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.451] I1008 16:54:19.419294    8765 utils.go:112] GRPC request: 
I1008 16:54:19.451] I1008 16:54:19.419309    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.452] I1008 16:54:19.419321    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.452] 
I1008 16:54:19.452] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.452] Controller Service
I1008 16:54:19.452] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.452]   ControllerPublishVolume
I1008 16:54:19.453]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 16:54:19.453]     should fail when the volume does not exist [BeforeEach]
I1008 16:54:19.453]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:857
I1008 16:54:19.453] 
I1008 16:54:19.453]     ControllerPublishVolume not supported
I1008 16:54:19.453] 
I1008 16:54:19.454]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.454] ------------------------------
I1008 16:54:19.454] Controller Service ControllerPublishVolume 
I1008 16:54:19.454]   should fail when the node does not exist
I1008 16:54:19.454]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 16:54:19.455] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.455] STEP: creating mount and staging directories
I1008 16:54:19.455] I1008 16:54:19.419912    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.455] I1008 16:54:19.419942    8765 utils.go:112] GRPC request: 
I1008 16:54:19.455] I1008 16:54:19.419956    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.456] I1008 16:54:19.419972    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.456] 
I1008 16:54:19.456] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.456] Controller Service
I1008 16:54:19.456] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.456]   ControllerPublishVolume
I1008 16:54:19.456]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 16:54:19.457]     should fail when the node does not exist [BeforeEach]
I1008 16:54:19.457]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:886
I1008 16:54:19.457] 
I1008 16:54:19.457]     ControllerPublishVolume not supported
I1008 16:54:19.457] 
I1008 16:54:19.457]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.457] ------------------------------
I1008 16:54:19.458] Controller Service ControllerPublishVolume 
I1008 16:54:19.458]   should fail when the volume is already published but is incompatible
I1008 16:54:19.458]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 16:54:19.458] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.458] STEP: creating mount and staging directories
I1008 16:54:19.459] I1008 16:54:19.420539    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.459] I1008 16:54:19.420623    8765 utils.go:112] GRPC request: 
I1008 16:54:19.459] I1008 16:54:19.420667    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.459] I1008 16:54:19.420693    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.459] 
I1008 16:54:19.459] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.460] Controller Service
I1008 16:54:19.460] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.460]   ControllerPublishVolume
I1008 16:54:19.460]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:713
I1008 16:54:19.460]     should fail when the volume is already published but is incompatible [BeforeEach]
I1008 16:54:19.460]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:956
I1008 16:54:19.461] 
I1008 16:54:19.461]     ControllerPublishVolume not supported
I1008 16:54:19.461] 
I1008 16:54:19.461]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:716
I1008 16:54:19.461] ------------------------------
I1008 16:54:19.461] Controller Service ControllerUnpublishVolume 
I1008 16:54:19.461]   should fail when no volume id is provided
I1008 16:54:19.462]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 16:54:19.462] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
I1008 16:54:19.462] STEP: creating mount and staging directories
I1008 16:54:19.462] I1008 16:54:19.421321    8765 utils.go:111] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I1008 16:54:19.462] I1008 16:54:19.421393    8765 utils.go:112] GRPC request: 
I1008 16:54:19.463] I1008 16:54:19.421440    8765 controllerserver.go:183] Using default ControllerGetCapabilities
I1008 16:54:19.463] I1008 16:54:19.421476    8765 utils.go:118] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > 
I1008 16:54:19.463] 
I1008 16:54:19.463] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
I1008 16:54:19.463] Controller Service
I1008 16:54:19.463] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/tests.go:44
I1008 16:54:19.464]   ControllerUnpublishVolume
I1008 16:54:19.464]   /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1059
I1008 16:54:19.464]     should fail when no volume id is provided [BeforeEach]
I1008 16:54:19.464]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1066
I1008 16:54:19.464] 
I1008 16:54:19.464]     ControllerUnpublishVolume not supported
I1008 16:54:19.465] 
I1008 16:54:19.465]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 16:54:19.465] ------------------------------
... skipping 20 lines ...
I1008 16:54:19.469]     /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/controller.go:1062
I1008 16:54:19.469] ------------------------------
I1008 16:54:19.469] 
I1008 16:54:19.469] 
I1008 16:54:19.469] Summarizing 1 Failure:
I1008 16:54:19.469] 
I1008 16:54:19.470] [Fail] Node Service [It] should work 
I1008 16:54:19.470] /go/pkg/mod/github.com/kubernetes-csi/csi-test@v1.1.0/pkg/sanity/node.go:464
I1008 16:54:19.470] 
I1008 16:54:19.470] Ran 34 of 57 Specs in 29.264 seconds
I1008 16:54:19.470] FAIL! -- 33 Passed | 1 Failed | 0 Pending | 23 Skipped
I1008 16:54:19.470] --- FAIL: TestSanity (29.28s)
I1008 16:54:19.470] FAIL
I1008 16:54:19.471] pkill -f azurefileplugin
I1008 16:54:19.471] Deleting CSI sanity test binary
I1008 16:54:19.480] test/sanity/run-test.sh: line 19:  8765 Terminated              _output/azurefileplugin --endpoint "$endpoint" --nodeid "$nodeid" -v=5
I1008 16:54:19.480] 2019/10/08 16:54:19 Deleting resource group azurefile-csi-driver-test-277755a3-e9ec-11e9-a8d5-7a357b15a3d0
I1008 16:56:20.981] --- FAIL: TestSanity (171.46s)
I1008 16:56:20.981]     sanity_test.go:78: Sanity test failed exit status 1
I1008 16:56:20.982] FAIL
I1008 16:56:20.985] FAIL	github.com/kubernetes-sigs/azurefile-csi-driver/test/sanity	171.472s
I1008 16:56:21.027] Makefile:54: recipe for target 'sanity-test' failed
W1008 16:56:21.037] make: *** [sanity-test] Error 1
W1008 16:56:21.037] Traceback (most recent call last):
W1008 16:56:21.037]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module>
W1008 16:56:21.038]     main(ARGS.env, ARGS.cmd + ARGS.args)
W1008 16:56:21.038]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main
W1008 16:56:21.038]     check(*cmd)
W1008 16:56:21.038]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check
W1008 16:56:21.038]     subprocess.check_call(cmd)
W1008 16:56:21.038]   File "/usr/lib/python2.7/subprocess.py", line 186, in check_call
W1008 16:56:21.039]     raise CalledProcessError(retcode, cmd)
W1008 16:56:21.039] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2
E1008 16:56:21.039] Command failed
I1008 16:56:21.039] process 456 exited with code 1 after 3.8m
E1008 16:56:21.039] FAIL: pull-azurefile-csi-driver-sanity
I1008 16:56:21.040] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W1008 16:56:21.553] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I1008 16:56:21.598] process 8792 exited with code 0 after 0.0m
I1008 16:56:21.598] Call:  gcloud config get-value account
I1008 16:56:21.885] process 8804 exited with code 0 after 0.0m
I1008 16:56:21.886] Will upload results to gs://kubernetes-jenkins/pr-logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
... skipping 28 lines ...