This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: feat: support private endpoint for smb protocol
ResultABORTED
Tests 0 failed / 0 succeeded
Started2021-07-22 12:17
Elapsed5m5s
Revisiond98949b870b65a7dcd7e8db2f22637372cd9d133
Refs 738

No Test Failures!


Error lines from build-log.txt

... skipping 85 lines ...
Running Suite: CSI Driver Test Suite
====================================
Random Seed: 1626956392
Will run 69 of 77 specs

CreateSnapshot [Controller Server] 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1383
STEP: connecting to CSI driver
I0722 12:19:52.773528    8937 main.go:95] set up prometheus server on [::]:29614
I0722 12:19:52.773957    8937 azurefile.go:221] 
DRIVER INFORMATION:
-------------------
... skipping 30 lines ...
STEP: creating mount and staging directories
I0722 12:19:53.770897    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:19:53.770940    8937 utils.go:119] GRPC request: {}
I0722 12:19:53.773362    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:19:53.774874    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:19:53.774897    8937 utils.go:119] GRPC request: {"source_volume_id":"testId"}
E0722 12:19:53.775001    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when no source volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1400
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:19:53.775683    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:19:53.775747    8937 utils.go:119] GRPC request: {}
I0722 12:19:53.775811    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:19:53.776310    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:19:53.776331    8937 utils.go:119] GRPC request: {"name":"name"}
E0722 12:19:53.776405    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
•
------------------------------
CreateSnapshot [Controller Server] 
  should succeed when requesting to create a snapshot with already existing name and same source volume ID
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1417
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 44 lines ...
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  should succeed when requesting to create a snapshot with already existing name and same source volume ID
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1417
------------------------------
CreateSnapshot [Controller Server] 
  should fail when requesting to create a snapshot with already existing name and different source volume ID
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1431
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:15.385296    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:15.385317    8937 utils.go:119] GRPC request: {}
I0722 12:20:15.385380    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 13 lines ...
I0722 12:20:15.858428    8937 controllerserver.go:342] begin to create file share(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
I0722 12:20:16.053829    8937 controllerserver.go:364] create file share createsnapshot-volume-3 on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:20:16.053879    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#"}}
STEP: creating a snapshot with the same name but different source volume ID
I0722 12:20:16.054858    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:20:16.054891    8937 utils.go:119] GRPC request: {"name":"CreateSnapshot-snapshot-2","source_volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#"}
E0722 12:20:16.089312    8937 utils.go:123] 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(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#)
cleanup snapshots: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-2##2021-07-22T12:20:15.0000000Z
I0722 12:20:16.090094    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0722 12:20:16.090146    8937 utils.go:119] GRPC request: {"snapshot_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-2##2021-07-22T12:20:15.0000000Z"}
I0722 12:20:16.129192    8937 controllerserver.go:778] delete snapshot(2021-07-22T12:20:15.0000000Z) successfully
I0722 12:20:16.129252    8937 utils.go:125] GRPC response: {}
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-2#
... skipping 35 lines ...
I0722 12:20:16.403526    8937 utils.go:119] GRPC request: {}
I0722 12:20:16.403588    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
STEP: creating a volume
I0722 12:20:16.404139    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:20:16.404159    8937 utils.go:119] GRPC request: {"capacity_range":{"limit_bytes":10737418240,"required_bytes":10737418240},"name":"CreateSnapshot-volume-3","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0722 12:20:16.498450    8937 controllerserver.go:342] begin to create file share(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
W0722 12:20:17.013921    8937 azurefile.go:601] CreateFileShare(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3cca-a01a-0005-18f3-7ea295000000\nTime:2021-07-22T12:20:16.9532776Z"), waiting for retrying
W0722 12:20:25.064374    8937 azurefile.go:601] CreateFileShare(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3cef-a01a-0005-32f3-7ea295000000\nTime:2021-07-22T12:20:25.0000069Z"), waiting for retrying
W0722 12:20:39.170597    8937 azurefile.go:601] CreateFileShare(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3d06-a01a-0005-3bf3-7ea295000000\nTime:2021-07-22T12:20:39.1110450Z"), waiting for retrying
I0722 12:20:53.004016    8937 controllerserver.go:364] create file share createsnapshot-volume-3 on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:20:53.004284    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#"}}
STEP: creating a snapshot
I0722 12:20:53.005290    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:20:53.005308    8937 utils.go:119] GRPC request: {"name":"aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa","source_volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#"}
I0722 12:20:53.085794    8937 controllerserver.go:720] Created share snapshot: 2021-07-22T12:20:53.0000000Z
... skipping 66 lines ...
I0722 12:20:53.355101    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeGetInfo
I0722 12:20:53.355120    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.355163    8937 utils.go:125] GRPC response: {"node_id":"sanity-test-node"}
•
------------------------------
Node Service NodePublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:379
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.356156    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.356176    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.356238    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.356673    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.357983    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.358004    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.358044    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.358714    8937 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0722 12:20:53.358732    8937 utils.go:119] GRPC request: {}
E0722 12:20:53.358821    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
•
------------------------------
Node Service NodePublishVolume 
  should fail when no target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:393
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.359613    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.359633    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.359683    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.361414    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.362780    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.362799    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.362839    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.363228    8937 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0722 12:20:53.363389    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id-39923366-e"}
E0722 12:20:53.363487    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
•
------------------------------
Node Service NodePublishVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:408
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.364103    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.364123    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.364166    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.364553    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.365656    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.365671    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.365699    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.366099    8937 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0722 12:20:53.366107    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"fake-vol-id-b9d906b3-c"}
E0722 12:20:53.366198    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
•
------------------------------
Node Service NodeUnpublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:427
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.366829    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.366841    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.366886    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.367253    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.368747    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.368759    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.368798    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.369171    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:20:53.369184    8937 utils.go:119] GRPC request: {}
E0722 12:20:53.369224    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•
------------------------------
Node Service NodeUnpublishVolume 
  should fail when no target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:439
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.370184    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.370206    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.370249    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.370708    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.371864    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.371878    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.371921    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.372457    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:20:53.372479    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id-1916ccdf-2"}
E0722 12:20:53.372525    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
•
------------------------------
Node Service NodeStageVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:467
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.373260    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.373279    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.373316    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.373704    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.375012    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.375033    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.375069    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.375651    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:20:53.375678    8937 utils.go:119] GRPC request: {"publish_context":{"device":"/dev/mock"},"staging_target_path":"/tmp/csi-staging","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}}
E0722 12:20:53.375820    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•
------------------------------
Node Service NodeStageVolume 
  should fail when no staging target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:486
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.376475    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.376495    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.376535    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.376914    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.378171    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.378191    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.378240    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.378704    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:20:53.378721    8937 utils.go:119] GRPC request: {"publish_context":{"device":"/dev/mock"},"volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_id":"fake-vol-id-f1c02306-3"}
E0722 12:20:53.378797    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided
•
------------------------------
Node Service NodeStageVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:505
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.379469    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.379489    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.379520    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 9 lines ...
W0722 12:20:53.381000    8937 controllerserver.go:91] no quota specified, set as default value(100 GiB)
I0722 12:20:53.476915    8937 controllerserver.go:342] begin to create file share(sanity-node-stage-nocaps-9c7d180b-1902abc3) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(100) protocol(SMB)
I0722 12:20:53.643742    8937 controllerserver.go:364] create file share sanity-node-stage-nocaps-9c7d180b-1902abc3 on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:20:53.643823    8937 utils.go:125] GRPC response: {"volume":{"volume_context":{"secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-stage-nocaps-9c7d180b-1902abc3#"}}
I0722 12:20:53.644467    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:20:53.644494    8937 utils.go:119] GRPC request: {"publish_context":{"device":"/dev/mock"},"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-stage-nocaps-9c7d180b-1902abc3#"}
E0722 12:20:53.644588    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-stage-nocaps-9c7d180b-1902abc3#
I0722 12:20:53.645016    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:20:53.645032    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-stage-nocaps-9c7d180b-1902abc3#"}
I0722 12:20:53.645090    8937 nodeserver.go:119] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-stage-nocaps-9c7d180b-1902abc3# on /tmp/csi-mount/target
I0722 12:20:53.645098    8937 mount_linux.go:266] Unmounting /tmp/csi-mount/target
W0722 12:20:53.648001    8937 mount_helper_common.go:34] Warning: Unmount skipped because path does not exist: /tmp/csi-mount/target
... skipping 14 lines ...
I0722 12:20:53.837395    8937 controllerserver.go:482] azure file(sanity-node-stage-nocaps-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-stage-nocaps-9c7d180b-1902abc3#) is deleted successfully
I0722 12:20:53.837407    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:20:53.837431    8937 utils.go:125] GRPC response: {}
•
------------------------------
Node Service NodeUnstageVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:556
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.838504    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.838528    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.838586    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.839142    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.840470    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.840486    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.840520    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.840960    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0722 12:20:53.840980    8937 utils.go:119] GRPC request: {"staging_target_path":"/tmp/csi-staging"}
E0722 12:20:53.841039    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•
------------------------------
Node Service NodeUnstageVolume 
  should fail when no staging target path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:570
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.841821    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.841840    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.841876    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0722 12:20:53.842463    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.843798    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.843816    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.843847    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.844225    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0722 12:20:53.844244    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id-f491332d-e"}
E0722 12:20:53.844292    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:592
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.844958    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.845041    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.845139    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0722 12:20:53.845589    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.845696    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.845870    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.847394    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.847411    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.847442    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
E0722 12:20:53.847985    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = NodeGetVolumeStats volume ID was empty
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when no volume path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:606
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.848615    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.848629    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.848661    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0722 12:20:53.848936    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.848949    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.848971    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.850064    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.850081    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.850113    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
E0722 12:20:53.850584    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = NodeGetVolumeStats volume path was empty
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:620
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.851386    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.851407    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.851452    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0722 12:20:53.851939    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.851955    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.851987    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:53.853259    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:53.853275    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.853304    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
E0722 12:20:53.853799    8937 utils.go:123] GRPC error: rpc error: code = NotFound desc = path some/path does not exist
•
------------------------------
Node Service NodeGetVolumeStats 
  should fail when volume does not exist on the specified path
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:635
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:53.854574    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:53.854597    8937 utils.go:119] GRPC request: {}
I0722 12:20:53.854638    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 31 lines ...
I0722 12:20:54.828098    8937 nodeserver.go:96] NodePublishVolume: mounting /tmp/csi-staging at /tmp/csi-mount/target with mountOptions: [bind]
I0722 12:20:54.828144    8937 mount_linux.go:175] Mounting cmd (mount) with arguments ( -o bind /tmp/csi-staging /tmp/csi-mount/target)
I0722 12:20:54.831115    8937 mount_linux.go:175] Mounting cmd (mount) with arguments ( -o bind,remount /tmp/csi-staging /tmp/csi-mount/target)
I0722 12:20:54.833621    8937 nodeserver.go:103] NodePublishVolume: mount /tmp/csi-staging at /tmp/csi-mount/target successfully
I0722 12:20:54.833658    8937 utils.go:125] GRPC response: {}
STEP: Get node volume stats
E0722 12:20:54.834458    8937 utils.go:123] GRPC error: rpc error: code = NotFound desc = path some/path does not exist
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-get-volume-stats-9c7d180b-1902abc3#
I0722 12:20:54.834982    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:20:54.835026    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-get-volume-stats-9c7d180b-1902abc3#"}
I0722 12:20:54.835099    8937 nodeserver.go:119] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-get-volume-stats-9c7d180b-1902abc3# on /tmp/csi-mount/target
I0722 12:20:54.835131    8937 mount_linux.go:266] Unmounting /tmp/csi-mount/target
W0722 12:20:54.884015    8937 mount_helper_common.go:133] Warning: "/tmp/csi-mount/target" is not a mountpoint, deleting
... skipping 18 lines ...
I0722 12:20:55.138776    8937 controllerserver.go:482] azure file(sanity-node-get-volume-stats-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-get-volume-stats-9c7d180b-1902abc3#) is deleted successfully
I0722 12:20:55.138788    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:20:55.138812    8937 utils.go:125] GRPC response: {}
•
------------------------------
Node Service NodeExpandVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:682
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:55.140081    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:55.140103    8937 utils.go:119] GRPC request: {}
I0722 12:20:55.140163    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  NodeExpandVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:674
    should fail when no volume id is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:682

    NodeExpandVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:677
------------------------------
Node Service NodeExpandVolume 
  should fail when no volume path is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:697
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:55.144272    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:55.144293    8937 utils.go:119] GRPC request: {}
I0722 12:20:55.144356    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  NodeExpandVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:674
    should fail when no volume path is provided [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:697

    NodeExpandVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:677
------------------------------
Node Service NodeExpandVolume 
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:716
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:55.147882    8937 utils.go:118] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0722 12:20:55.147941    8937 utils.go:119] GRPC request: {}
I0722 12:20:55.148061    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...

S [SKIPPING] in Spec Setup (BeforeEach) [0.003 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  NodeExpandVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:674
    should fail when volume is not found [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:716

    NodeExpandVolume not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:677
------------------------------
... skipping 189 lines ...
I0722 12:20:58.718435    8937 controllerserver.go:482] azure file(sanity-node-full-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-node-full-9c7d180b-1902abc3#) is deleted successfully
I0722 12:20:58.718450    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:20:58.718474    8937 utils.go:125] GRPC response: {}
•
------------------------------
ExpandVolume [Controller Server] 
  should fail if no volume id is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1489
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:58.719376    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:58.719396    8937 utils.go:119] GRPC request: {}
I0722 12:20:58.719468    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:58.720074    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerExpandVolume
I0722 12:20:58.720103    8937 utils.go:119] GRPC request: {"capacity_range":{"required_bytes":11811160064}}
E0722 12:20:58.720213    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•
------------------------------
ExpandVolume [Controller Server] 
  should fail if no capacity range is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1506
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:58.720917    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:58.720935    8937 utils.go:119] GRPC request: {}
I0722 12:20:58.720972    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:58.721681    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerExpandVolume
I0722 12:20:58.721723    8937 utils.go:119] GRPC request: {}
E0722 12:20:58.721808    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•S
------------------------------
ListSnapshots [Controller Server] 
  should return appropriate values (no optional values added)
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1114
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 154 lines ...
I0722 12:20:58.735627    8937 utils.go:119] GRPC request: {}
I0722 12:20:58.735678    8937 utils.go:125] GRPC response: {"name":"file.csi.azure.com","vendor_version":"e2e-db68c6fb1343f59b717bc3c8a1e1572bf65d742c"}
STEP: verifying name size and characters
•
------------------------------
DeleteSnapshot [Controller Server] 
  should fail when no snapshot id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1327
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:58.737130    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:58.737150    8937 utils.go:119] GRPC request: {}
I0722 12:20:58.737191    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:58.737754    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0722 12:20:58.737771    8937 utils.go:119] GRPC request: {}
E0722 12:20:58.737844    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
•
------------------------------
DeleteSnapshot [Controller Server] 
  should succeed when an invalid snapshot id is used
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1343
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:58.738836    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:58.738853    8937 utils.go:119] GRPC request: {}
I0722 12:20:58.738889    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:58.739378    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0722 12:20:58.739403    8937 utils.go:119] GRPC request: {"snapshot_id":"reallyfakesnapshotid"}
W0722 12:20:58.739499    8937 azurefile.go:492] parsing volumeID(reallyfakesnapshotid) return with error: error parsing volume id: "reallyfakesnapshotid", should at least contain two #
I0722 12:20:58.739530    8937 controllerserver.go:755] failed to get share url with (reallyfakesnapshotid): failed to get file share from reallyfakesnapshotid, returning with success
I0722 12:20:58.739687    8937 utils.go:125] GRPC response: {}
•
------------------------------
DeleteSnapshot [Controller Server] 
  should return appropriate values (no optional values added)
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1350
... skipping 85 lines ...

    ListVolumes not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:177
------------------------------
Controller Service [Controller Server] ListVolumes 
  should fail when an invalid starting_token is passed
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:193
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:59.287459    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:59.287488    8937 utils.go:119] GRPC request: {}
I0722 12:20:59.287553    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  ListVolumes
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:174
    should fail when an invalid starting_token is passed [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:193

    ListVolumes not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:177
------------------------------
... skipping 24 lines ...
  ListVolumes
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:174
    pagination should detect volumes added between pages and accept tokens when the last volume from a page is deleted
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:267
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:375
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:59.290165    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:59.290190    8937 utils.go:119] GRPC request: {}
I0722 12:20:59.290244    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:59.290657    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:20:59.290675    8937 utils.go:119] GRPC request: {}
E0722 12:20:59.290750    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when no volume capabilities are provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:390
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:20:59.291411    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:20:59.291432    8937 utils.go:119] GRPC request: {}
I0722 12:20:59.291479    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:20:59.292002    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:20:59.292031    8937 utils.go:119] GRPC request: {"name":"sanity-controller-create-no-volume-capabilities-9C7D180B-1902ABC3"}
E0722 12:20:59.292120    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities not valid: CreateVolume Volume capabilities must be provided
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should return appropriate values SingleNodeWriter NoCapacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:408
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 51 lines ...
I0722 12:21:00.098735    8937 controllerserver.go:482] azure file(sanity-controller-create-single-with-capacity-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-single-with-capacity-9c7d180b-1902abc3#) is deleted successfully
I0722 12:21:00.098746    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:00.098772    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should not fail when requesting to create a volume with already existing name and same capacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:459
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:00.099942    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:00.099990    8937 utils.go:119] GRPC request: {}
I0722 12:21:00.100040    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 22 lines ...
I0722 12:21:00.778709    8937 controllerserver.go:482] azure file(sanity-controller-create-twice-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-9c7d180b-1902abc3#) is deleted successfully
I0722 12:21:00.778723    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:00.778763    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when requesting to create a volume with already existing name and different capacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:500
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:00.780134    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:00.780163    8937 utils.go:119] GRPC request: {}
I0722 12:21:00.780219    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 2 lines ...
I0722 12:21:00.780820    8937 utils.go:119] GRPC request: {"capacity_range":{"limit_bytes":10737418240,"required_bytes":10737418240},"name":"sanity-controller-create-twice-different-9C7D180B-1902ABC3","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0722 12:21:00.870465    8937 controllerserver.go:342] begin to create file share(sanity-controller-create-twice-different-9c7d180b-1902abc3) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
I0722 12:21:01.036779    8937 controllerserver.go:364] create file share sanity-controller-create-twice-different-9c7d180b-1902abc3 on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:21:01.036841    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-9c7d180b-1902abc3#"}}
I0722 12:21:01.037532    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:01.037613    8937 utils.go:119] GRPC request: {"capacity_range":{"limit_bytes":21474836480,"required_bytes":21474836480},"name":"sanity-controller-create-twice-different-9C7D180B-1902ABC3","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
E0722 12:21:01.128529    8937 utils.go:123] GRPC error: rpc error: code = AlreadyExists desc = request file share(sanity-controller-create-twice-different-9c7d180b-1902abc3) already exists, but its capacity(10) is different from (20)
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-9c7d180b-1902abc3#
I0722 12:21:01.129409    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:21:01.129430    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-9c7d180b-1902abc3#"}
I0722 12:21:01.129502    8937 nodeserver.go:119] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-9c7d180b-1902abc3# on /tmp/csi-mount/target
I0722 12:21:01.129512    8937 mount_linux.go:266] Unmounting /tmp/csi-mount/target
W0722 12:21:01.133023    8937 mount_helper_common.go:34] Warning: Unmount skipped because path does not exist: /tmp/csi-mount/target
... skipping 5 lines ...
I0722 12:21:01.278383    8937 controllerserver.go:482] azure file(sanity-controller-create-twice-different-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-9c7d180b-1902abc3#) is deleted successfully
I0722 12:21:01.278397    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:01.278428    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should not fail when creating volume with maximum-length name
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:544
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:01.279574    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:01.279604    8937 utils.go:119] GRPC request: {}
I0722 12:21:01.279677    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 103 lines ...

    Volume Cloning not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:618
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when the volume source volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:640
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:02.703476    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:02.703500    8937 utils.go:119] GRPC request: {}
I0722 12:21:02.703541    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 3 lines ...

S [SKIPPING] [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:368
    should fail when the volume source volume is not found [It]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:640

    Volume Cloning not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:642
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:670
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:02.705374    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:02.705391    8937 utils.go:119] GRPC request: {}
I0722 12:21:02.705421    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:02.705863    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteVolume
I0722 12:21:02.705876    8937 utils.go:119] GRPC request: {}
E0722 12:21:02.705933    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should succeed when an invalid volume id is used
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:685
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:02.706466    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:02.706584    8937 utils.go:119] GRPC request: {}
I0722 12:21:02.706712    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:02.707182    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteVolume
I0722 12:21:02.707196    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id"}
E0722 12:21:02.707255    8937 controllerserver.go:453] GetFileShareInfo(fake-vol-id) in DeleteVolume failed with error: error parsing volume id: "fake-vol-id", should at least contain two #
I0722 12:21:02.707262    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should return appropriate values (no optional values added)
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:697
... skipping 15 lines ...
I0722 12:21:03.122776    8937 controllerserver.go:482] azure file(sanity-controller-create-appropriate-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-appropriate-9c7d180b-1902abc3#) is deleted successfully
I0722 12:21:03.122790    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:03.122813    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:733
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:03.123929    8937 utils.go:118] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0722 12:21:03.123963    8937 utils.go:119] GRPC request: {}
E0722 12:21:03.124081    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when no volume capabilities are provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:747
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
STEP: creating a single node writer volume
I0722 12:21:03.124841    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:03.124889    8937 utils.go:119] GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"sanity-controller-validate-nocaps-9C7D180B-1902ABC3","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0722 12:21:03.217686    8937 controllerserver.go:342] begin to create file share(sanity-controller-validate-nocaps-9c7d180b-1902abc3) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
I0722 12:21:03.379223    8937 controllerserver.go:364] create file share sanity-controller-validate-nocaps-9c7d180b-1902abc3 on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:21:03.379297    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-9c7d180b-1902abc3#"}}
I0722 12:21:03.380103    8937 utils.go:118] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0722 12:21:03.380124    8937 utils.go:119] GRPC request: {"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-9c7d180b-1902abc3#"}
E0722 12:21:03.380209    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities not provided
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-9c7d180b-1902abc3#
I0722 12:21:03.380776    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:21:03.380853    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-9c7d180b-1902abc3#"}
I0722 12:21:03.380948    8937 nodeserver.go:119] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-9c7d180b-1902abc3# on /tmp/csi-mount/target
I0722 12:21:03.380959    8937 mount_linux.go:266] Unmounting /tmp/csi-mount/target
W0722 12:21:03.384007    8937 mount_helper_common.go:34] Warning: Unmount skipped because path does not exist: /tmp/csi-mount/target
... skipping 36 lines ...
I0722 12:21:04.067821    8937 controllerserver.go:482] azure file(sanity-controller-validate-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-9c7d180b-1902abc3#) is deleted successfully
I0722 12:21:04.067836    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:04.067867    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when the requested volume does not exist
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:824
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.068909    8937 utils.go:118] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0722 12:21:04.068932    8937 utils.go:119] GRPC request: {"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}],"volume_id":"fake-vol-id-cab66ab1-9"}
W0722 12:21:04.069030    8937 azurefile.go:492] parsing volumeID(fake-vol-id-cab66ab1-9) return with error: error parsing volume id: "fake-vol-id-cab66ab1-9", should at least contain two #
E0722 12:21:04.069039    8937 utils.go:123] GRPC error: rpc error: code = NotFound desc = get account info from(fake-vol-id-cab66ab1-9) failed with error: <nil>
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:851
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.069697    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.069724    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.069848    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:04.070388    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0722 12:21:04.070452    8937 utils.go:119] GRPC request: {}
E0722 12:21:04.070525    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no node id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:866
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.071278    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.071292    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.071329    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:04.071778    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0722 12:21:04.071791    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id-a29bf96d-a"}
E0722 12:21:04.071841    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:882
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.072410    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.072431    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.072471    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:04.072899    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0722 12:21:04.072917    8937 utils.go:119] GRPC request: {"node_id":"fake-node-id-813d9ac0-c","volume_id":"fake-vol-id-bb539ca1-c"}
E0722 12:21:04.072979    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when publishing more volumes than the node max attach limit
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:899
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.073684    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.073703    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.073736    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}

S [SKIPPING] [0.001 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when publishing more volumes than the node max attach limit [It]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:899

    testnodevolumeattachlimit not enabled

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:901
------------------------------
SS
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when the volume is already published but is incompatible
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1000
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.074990    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.075015    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.075058    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 3 lines ...

S [SKIPPING] [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when the volume is already published but is incompatible [It]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1000

    ControllerPublishVolume.readonly field not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1002
------------------------------
... skipping 82 lines ...
I0722 12:21:04.483686    8937 controllerserver.go:482] azure file(sanity-controller-publish-9c7d180b-1902abc3) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-publish-9c7d180b-1902abc3#) is deleted successfully
I0722 12:21:04.483732    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:04.483756    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] ControllerUnpublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1078
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.484998    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.485022    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.485084    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:04.485677    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerUnpublishVolume
I0722 12:21:04.485696    8937 utils.go:119] GRPC request: {}
E0722 12:21:04.485758    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
•
Ran 51 of 77 Specs in 71.721 seconds
SUCCESS! -- 51 Passed | 0 Failed | 1 Pending | 25 Skipped
Begin to run sanity test for vhd disk feature...
Running Suite: CSI Driver Test Suite
====================================
Random Seed: 1626956464
Will run 66 of 77 specs

... skipping 211 lines ...

    ListVolumes not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:177
------------------------------
Controller Service [Controller Server] ListVolumes 
  should fail when an invalid starting_token is passed
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:193
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.527856    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.527872    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.527903    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}

S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  ListVolumes
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:174
    should fail when an invalid starting_token is passed [BeforeEach]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:193

    ListVolumes not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:177
------------------------------
... skipping 24 lines ...
  ListVolumes
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:174
    pagination should detect volumes added between pages and accept tokens when the last volume from a page is deleted
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:267
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:375
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.530274    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.530304    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.530346    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:04.530989    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:04.531008    8937 utils.go:119] GRPC request: {"parameters":{"fstype":"ext4"}}
E0722 12:21:04.531059    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when no volume capabilities are provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:390
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:04.531648    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:04.531662    8937 utils.go:119] GRPC request: {}
I0722 12:21:04.531689    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:04.532246    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:04.532271    8937 utils.go:119] GRPC request: {"name":"sanity-controller-create-no-volume-capabilities-C52C4278-68C14C4E","parameters":{"fstype":"ext4"}}
E0722 12:21:04.532366    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities not valid: CreateVolume Volume capabilities must be provided
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should return appropriate values SingleNodeWriter NoCapacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:408
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 55 lines ...
I0722 12:21:05.512781    8937 controllerserver.go:482] azure file(sanity-controller-create-single-with-capacity-c52c4278-68c14c4e) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-single-with-capacity-c52c4278-68c14c4e#sanity-controller-create-single-with-capacity-c52c4278-68c14c4e.vhd) is deleted successfully
I0722 12:21:05.512795    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:05.512816    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should not fail when requesting to create a volume with already existing name and same capacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:459
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:05.513922    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:05.513954    8937 utils.go:119] GRPC request: {}
I0722 12:21:05.514002    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 26 lines ...
I0722 12:21:06.330104    8937 controllerserver.go:482] azure file(sanity-controller-create-twice-c52c4278-68c14c4e) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-c52c4278-68c14c4e#sanity-controller-create-twice-c52c4278-68c14c4e.vhd) is deleted successfully
I0722 12:21:06.330160    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:06.330192    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when requesting to create a volume with already existing name and different capacity
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:500
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:06.331458    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:06.331483    8937 utils.go:119] GRPC request: {}
I0722 12:21:06.331544    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 4 lines ...
I0722 12:21:06.581698    8937 controllerserver.go:364] create file share sanity-controller-create-twice-different-c52c4278-68c14c4e on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:21:06.581741    8937 controllerserver.go:380] begin to create vhd file(sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd) size(10737418240) on share(sanity-controller-create-twice-different-c52c4278-68c14c4e) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location()
I0722 12:21:06.660262    8937 controllerserver.go:385] create vhd file(sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd) size(10737418240) on share(sanity-controller-create-twice-different-c52c4278-68c14c4e) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() successfully
I0722 12:21:06.660332    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd","fstype":"ext4","secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-c52c4278-68c14c4e#sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd"}}
I0722 12:21:06.661284    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:06.661306    8937 utils.go:119] GRPC request: {"capacity_range":{"limit_bytes":21474836480,"required_bytes":21474836480},"name":"sanity-controller-create-twice-different-C52C4278-68C14C4E","parameters":{"fstype":"ext4"},"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
E0722 12:21:06.750262    8937 utils.go:123] GRPC error: rpc error: code = AlreadyExists desc = request file share(sanity-controller-create-twice-different-c52c4278-68c14c4e) already exists, but its capacity(10) is different from (20)
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-c52c4278-68c14c4e#sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd
I0722 12:21:06.750936    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:21:06.750953    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-c52c4278-68c14c4e#sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd"}
I0722 12:21:06.751028    8937 nodeserver.go:119] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-c52c4278-68c14c4e#sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd on /tmp/csi-mount/target
I0722 12:21:06.751038    8937 mount_linux.go:266] Unmounting /tmp/csi-mount/target
W0722 12:21:06.753886    8937 mount_helper_common.go:34] Warning: Unmount skipped because path does not exist: /tmp/csi-mount/target
... skipping 5 lines ...
I0722 12:21:06.908942    8937 controllerserver.go:482] azure file(sanity-controller-create-twice-different-c52c4278-68c14c4e) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-twice-different-c52c4278-68c14c4e#sanity-controller-create-twice-different-c52c4278-68c14c4e.vhd) is deleted successfully
I0722 12:21:06.908954    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:06.908979    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] CreateVolume 
  should not fail when creating volume with maximum-length name
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:544
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:06.909987    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:06.910011    8937 utils.go:119] GRPC request: {}
I0722 12:21:06.910071    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
STEP: creating a volume
I0722 12:21:06.910808    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:06.910826    8937 utils.go:119] GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa","parameters":{"fstype":"ext4"},"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0722 12:21:06.997256    8937 controllerserver.go:342] begin to create file share(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
W0722 12:21:07.515241    8937 azurefile.go:601] CreateFileShare(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3dd7-a01a-0005-41f4-7ea295000000\nTime:2021-07-22T12:21:07.4542056Z"), waiting for retrying
W0722 12:21:16.047193    8937 azurefile.go:601] CreateFileShare(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3dff-a01a-0005-47f4-7ea295000000\nTime:2021-07-22T12:21:15.9862728Z"), waiting for retrying
W0722 12:21:24.548590    8937 azurefile.go:601] CreateFileShare(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3e18-a01a-0005-55f4-7ea295000000\nTime:2021-07-22T12:21:24.4863181Z"), waiting for retrying
I0722 12:21:43.771706    8937 controllerserver.go:364] create file share aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:21:43.771757    8937 controllerserver.go:380] begin to create vhd file(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd) size(10737418240) on share(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location()
I0722 12:21:43.854639    8937 controllerserver.go:385] create vhd file(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd) size(10737418240) on share(aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() successfully
I0722 12:21:43.854714    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd","fstype":"ext4","secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd"}}
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd
I0722 12:21:43.855607    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
... skipping 12 lines ...

• [SLOW TEST:37.185 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:368
    should not fail when creating volume with maximum-length name
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:544
------------------------------
Controller Service [Controller Server] CreateVolume 
  should create volume from an existing source snapshot
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:571
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 82 lines ...

    Volume Cloning not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:618
------------------------------
Controller Service [Controller Server] CreateVolume 
  should fail when the volume source volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:640
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:45.555078    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:45.555103    8937 utils.go:119] GRPC request: {}
I0722 12:21:45.555151    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 3 lines ...

S [SKIPPING] [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:368
    should fail when the volume source volume is not found [It]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:640

    Volume Cloning not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:642
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:670
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:45.557081    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:45.557099    8937 utils.go:119] GRPC request: {}
I0722 12:21:45.557131    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:45.557645    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteVolume
I0722 12:21:45.557662    8937 utils.go:119] GRPC request: {}
E0722 12:21:45.557721    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
•
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should succeed when an invalid volume id is used
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:685
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:45.558510    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:45.558523    8937 utils.go:119] GRPC request: {}
I0722 12:21:45.558555    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:45.558977    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteVolume
I0722 12:21:45.558999    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id"}
E0722 12:21:45.559082    8937 controllerserver.go:453] GetFileShareInfo(fake-vol-id) in DeleteVolume failed with error: error parsing volume id: "fake-vol-id", should at least contain two #
I0722 12:21:45.559092    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] DeleteVolume 
  should return appropriate values (no optional values added)
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:697
... skipping 17 lines ...
I0722 12:21:46.128289    8937 controllerserver.go:482] azure file(sanity-controller-create-appropriate-c52c4278-68c14c4e) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-create-appropriate-c52c4278-68c14c4e#sanity-controller-create-appropriate-c52c4278-68c14c4e.vhd) is deleted successfully
I0722 12:21:46.128302    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:46.128325    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:733
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:46.129602    8937 utils.go:118] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0722 12:21:46.129622    8937 utils.go:119] GRPC request: {}
E0722 12:21:46.129770    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when no volume capabilities are provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:747
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
STEP: creating a single node writer volume
I0722 12:21:46.130870    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:46.130893    8937 utils.go:119] GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"sanity-controller-validate-nocaps-C52C4278-68C14C4E","parameters":{"fstype":"ext4"},"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0722 12:21:46.218015    8937 controllerserver.go:342] begin to create file share(sanity-controller-validate-nocaps-c52c4278-68c14c4e) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
I0722 12:21:46.380851    8937 controllerserver.go:364] create file share sanity-controller-validate-nocaps-c52c4278-68c14c4e on storage account f584ecf9b0e2e4b08b51d39 successfully
I0722 12:21:46.380903    8937 controllerserver.go:380] begin to create vhd file(sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd) size(10737418240) on share(sanity-controller-validate-nocaps-c52c4278-68c14c4e) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location()
I0722 12:21:46.458853    8937 controllerserver.go:385] create vhd file(sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd) size(10737418240) on share(sanity-controller-validate-nocaps-c52c4278-68c14c4e) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() successfully
I0722 12:21:46.458926    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd","fstype":"ext4","secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-c52c4278-68c14c4e#sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd"}}
I0722 12:21:46.459784    8937 utils.go:118] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0722 12:21:46.459813    8937 utils.go:119] GRPC request: {"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-c52c4278-68c14c4e#sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd"}
E0722 12:21:46.459922    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities not provided
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-c52c4278-68c14c4e#sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd
I0722 12:21:46.460624    8937 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:21:46.460643    8937 utils.go:119] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-c52c4278-68c14c4e#sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd"}
I0722 12:21:46.460710    8937 nodeserver.go:119] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-nocaps-c52c4278-68c14c4e#sanity-controller-validate-nocaps-c52c4278-68c14c4e.vhd on /tmp/csi-mount/target
I0722 12:21:46.460884    8937 mount_linux.go:266] Unmounting /tmp/csi-mount/target
W0722 12:21:46.463731    8937 mount_helper_common.go:34] Warning: Unmount skipped because path does not exist: /tmp/csi-mount/target
... skipping 38 lines ...
I0722 12:21:47.218088    8937 controllerserver.go:482] azure file(sanity-controller-validate-c52c4278-68c14c4e) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#sanity-controller-validate-c52c4278-68c14c4e#sanity-controller-validate-c52c4278-68c14c4e.vhd) is deleted successfully
I0722 12:21:47.218100    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:47.218153    8937 utils.go:125] GRPC response: {}
•
------------------------------
Controller Service [Controller Server] ValidateVolumeCapabilities 
  should fail when the requested volume does not exist
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:824
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.219349    8937 utils.go:118] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0722 12:21:47.219382    8937 utils.go:119] GRPC request: {"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}],"volume_id":"fake-vol-id-aecccfec-e"}
W0722 12:21:47.219509    8937 azurefile.go:492] parsing volumeID(fake-vol-id-aecccfec-e) return with error: error parsing volume id: "fake-vol-id-aecccfec-e", should at least contain two #
E0722 12:21:47.219522    8937 utils.go:123] GRPC error: rpc error: code = NotFound desc = get account info from(fake-vol-id-aecccfec-e) failed with error: <nil>
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:851
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.220409    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.220438    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.220481    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:47.221107    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0722 12:21:47.221131    8937 utils.go:119] GRPC request: {}
E0722 12:21:47.221275    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no node id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:866
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.222267    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.222296    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.222397    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:47.223003    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0722 12:21:47.223027    8937 utils.go:119] GRPC request: {"volume_id":"fake-vol-id-0dab7de3-b"}
E0722 12:21:47.223203    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when no volume capability is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:882
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.224068    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.224090    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.224135    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:47.224804    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0722 12:21:47.224824    8937 utils.go:119] GRPC request: {"node_id":"fake-node-id-28feacc9-8","volume_id":"fake-vol-id-e421003d-b"}
E0722 12:21:47.224889    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
•
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when publishing more volumes than the node max attach limit
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:899
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.225601    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.225619    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.225652    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}

S [SKIPPING] [0.001 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when publishing more volumes than the node max attach limit [It]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:899

    testnodevolumeattachlimit not enabled

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:901
------------------------------
SS
------------------------------
Controller Service [Controller Server] ControllerPublishVolume 
  should fail when the volume is already published but is incompatible
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1000
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.227122    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.227139    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.227176    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 3 lines ...

S [SKIPPING] [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:844
    should fail when the volume is already published but is incompatible [It]
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1000

    ControllerPublishVolume.readonly field not supported

    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1002
------------------------------
SS
------------------------------
Controller Service [Controller Server] ControllerUnpublishVolume 
  should fail when no volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1078
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.229016    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.229032    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.229078    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:47.229870    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerUnpublishVolume
I0722 12:21:47.229893    8937 utils.go:119] GRPC request: {}
E0722 12:21:47.229963    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1383
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.230807    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.230825    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.230867    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:47.231374    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:21:47.231389    8937 utils.go:119] GRPC request: {"source_volume_id":"testId"}
E0722 12:21:47.231440    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when no source volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1400
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:47.232114    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:47.232131    8937 utils.go:119] GRPC request: {}
I0722 12:21:47.232169    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
I0722 12:21:47.232698    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:21:47.232723    8937 utils.go:119] GRPC request: {"name":"name"}
E0722 12:21:47.232792    8937 utils.go:123] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
•
------------------------------
CreateSnapshot [Controller Server] 
  should succeed when requesting to create a snapshot with already existing name and same source volume ID
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1417
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
... skipping 39 lines ...
I0722 12:21:48.014456    8937 controllerserver.go:482] azure file(createsnapshot-volume-1) under rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) account(f584ecf9b0e2e4b08b51d39) volume(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-1#createsnapshot-volume-1.vhd) is deleted successfully
I0722 12:21:48.014470    8937 azurefile.go:670] skip remove tag(skip-matching) on account(f584ecf9b0e2e4b08b51d39) resourceGroup(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) since tag already removed in a short time
I0722 12:21:48.014508    8937 utils.go:125] GRPC response: {}
•
------------------------------
CreateSnapshot [Controller Server] 
  should fail when requesting to create a snapshot with already existing name and different source volume ID
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1431
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock
STEP: creating mount and staging directories
I0722 12:21:48.015804    8937 utils.go:118] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0722 12:21:48.015862    8937 utils.go:119] GRPC request: {}
I0722 12:21:48.015921    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
... skipping 17 lines ...
I0722 12:21:48.865442    8937 controllerserver.go:380] begin to create vhd file(createsnapshot-volume-3.vhd) size(10737418240) on share(createsnapshot-volume-3) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location()
I0722 12:21:48.942961    8937 controllerserver.go:385] create vhd file(createsnapshot-volume-3.vhd) size(10737418240) on share(createsnapshot-volume-3) on account() type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() successfully
I0722 12:21:48.943015    8937 utils.go:125] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"createsnapshot-volume-3.vhd","fstype":"ext4","secretnamespace":""},"volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#createsnapshot-volume-3.vhd"}}
STEP: creating a snapshot with the same name but different source volume ID
I0722 12:21:48.944063    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateSnapshot
I0722 12:21:48.944086    8937 utils.go:119] GRPC request: {"name":"CreateSnapshot-snapshot-2","source_volume_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#createsnapshot-volume-3.vhd"}
E0722 12:21:48.978285    8937 utils.go:123] 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(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-3#createsnapshot-volume-3.vhd)
cleanup snapshots: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-2#createsnapshot-volume-2.vhd#2021-07-22T12:21:48.0000000Z
I0722 12:21:48.979628    8937 utils.go:118] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0722 12:21:48.979653    8937 utils.go:119] GRPC request: {"snapshot_id":"azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-2#createsnapshot-volume-2.vhd#2021-07-22T12:21:48.0000000Z"}
I0722 12:21:49.018105    8937 controllerserver.go:778] delete snapshot(2021-07-22T12:21:48.0000000Z) successfully
I0722 12:21:49.018197    8937 utils.go:125] GRPC response: {}
cleanup volumes: deleting azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81#f584ecf9b0e2e4b08b51d39#createsnapshot-volume-2#createsnapshot-volume-2.vhd
... skipping 35 lines ...
I0722 12:21:49.312728    8937 utils.go:119] GRPC request: {}
I0722 12:21:49.312789    8937 utils.go:125] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}}]}
STEP: creating a volume
I0722 12:21:49.313489    8937 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:21:49.313508    8937 utils.go:119] GRPC request: {"capacity_range":{"limit_bytes":10737418240,"required_bytes":10737418240},"name":"CreateSnapshot-volume-3","parameters":{"fstype":"ext4"},"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0722 12:21:49.400068    8937 controllerserver.go:342] begin to create file share(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) type() rg(azurefile-csi-driver-test-f5ae63cf-eae6-11eb-adb2-ce4fd9ce4a81) location() size(10) protocol(SMB)
W0722 12:21:49.972597    8937 azurefile.go:601] CreateFileShare(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3e84-a01a-0005-0ff4-7ea295000000\nTime:2021-07-22T12:21:49.9104053Z"), waiting for retrying
W0722 12:21:58.098579    8937 azurefile.go:601] CreateFileShare(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3e93-a01a-0005-15f4-7ea295000000\nTime:2021-07-22T12:21:58.0351869Z"), waiting for retrying
W0722 12:22:13.050299    8937 azurefile.go:601] CreateFileShare(createsnapshot-volume-3) on account(f584ecf9b0e2e4b08b51d39) failed with error(storage.FileSharesClient#Create: Failure sending request: StatusCode=409 -- Original Error: autorest/azure: Service returned an error. Status=<nil> Code="ShareBeingDeleted" Message="The specified share is being deleted. Try operation later.\nRequestId:5acd3ebd-a01a-0005-1af4-7ea295000000\nTime:2021-07-22T12:22:12.9878253Z"), waiting for retrying