This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: fix: account matching issue in account search
ResultABORTED
Tests 0 failed / 0 succeeded
Started2023-01-09 12:56
Elapsed3m55s
Revision63dc67b2f90865b1331c4d7d1c4187ad15c82a37
Refs 1156

No Test Failures!

Error lines from build-log.txt

... skipping 92 lines ...
Git Commit: 00b35c11ff9ca9b14cbdb2f3a2852ba140f2058a
Go Version: go1.19.4
Platform: linux/amd64

Streaming logs below:
I0109 12:59:43.144369   11180 azurefile.go:299] driver userAgent: file.csi.azure.com/e2e-00b35c11ff9ca9b14cbdb2f3a2852ba140f2058a
W0109 12:59:43.144428   11180 azure.go:73] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
I0109 12:59:43.144455   11180 azure.go:92] could not read cloud config from secret kube-system/azure-cloud-provider
I0109 12:59:43.144618   11180 azure.go:95] AZURE_CREDENTIAL_FILE env var set as /tmp/azure.json
I0109 12:59:43.144749   11180 azure.go:110] read cloud config from file: /tmp/azure.json successfully
I0109 12:59:43.145601   11180 azure_auth.go:253] Using AzurePublicCloud environment
I0109 12:59:43.145672   11180 azure_auth.go:138] azure: using client_id+client_secret to retrieve access token
I0109 12:59:43.145726   11180 azure.go:776] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 99 lines ...
  << End Captured GinkgoWriter Output

  ListVolumes not supported
  In [BeforeEach] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:178
------------------------------
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:194
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 12:59:44.147
STEP: creating mount and staging directories 01/09/23 12:59:44.147
I0109 12:59:44.148907   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 12:59:44.148932   11180 utils.go:77] GRPC request: {}
I0109 12:59:44.148987   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
------------------------------
S [SKIPPED] [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ListVolumes [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:176
    should fail when an invalid starting_token is passed
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:194

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 12:59:44.147
    STEP: creating mount and staging directories 01/09/23 12:59:44.147
  << End Captured GinkgoWriter Output
... skipping 28 lines ...
------------------------------
P [PENDING]
Controller Service [Controller Server] ListVolumes 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:268
------------------------------
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:376
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 12:59:44.152
STEP: creating mount and staging directories 01/09/23 12:59:44.152
I0109 12:59:44.153715   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 12:59:44.153756   11180 utils.go:77] GRPC request: {}
I0109 12:59:44.153863   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
I0109 12:59:44.155146   11180 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0109 12:59:44.155180   11180 utils.go:77] GRPC request: {}
E0109 12:59:44.155517   11180 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
------------------------------
• [0.003 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:369
    should fail when no name is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:376

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 12:59:44.152
    STEP: creating mount and staging directories 01/09/23 12:59:44.152
  << End Captured GinkgoWriter Output
------------------------------
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:391
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 12:59:44.156
STEP: creating mount and staging directories 01/09/23 12:59:44.156
I0109 12:59:44.157268   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 12:59:44.157298   11180 utils.go:77] GRPC request: {}
I0109 12:59:44.157359   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
I0109 12:59:44.158050   11180 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0109 12:59:44.158073   11180 utils.go:77] GRPC request: {"name":"sanity-controller-create-no-volume-capabilities-B107BB30-4080F867"}
E0109 12:59:44.158208   11180 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities not valid: CreateVolume Volume capabilities must be provided
------------------------------
• [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:369
    should fail when no volume capabilities are provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:391

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 12:59:44.156
    STEP: creating mount and staging directories 01/09/23 12:59:44.156
  << End Captured GinkgoWriter Output
... skipping 27 lines ...
I0109 13:00:05.450958   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:05.451066   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-no-capacity-b107bb30-4080f867###default"}
I0109 13:00:05.451186   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-no-capacity-b107bb30-4080f867###default on /tmp/csi-staging
I0109 13:00:05.451196   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:05.451222   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:05.451248   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:05.454836   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:05.455029   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:05.455238   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-no-capacity-b107bb30-4080f867###default on /tmp/proxy-mount
I0109 13:00:05.455339   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:05.455489   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:05.455558   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-no-capacity-b107bb30-4080f867###default on /tmp/csi-staging successfully
I0109 13:00:05.455632   11180 utils.go:83] GRPC response: {}
... skipping 46 lines ...
I0109 13:00:06.415259   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:06.415284   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-with-capacity-b107bb30-4080f867###default"}
I0109 13:00:06.415342   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-with-capacity-b107bb30-4080f867###default on /tmp/csi-staging
I0109 13:00:06.415350   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:06.415539   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:06.415569   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:06.419330   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:06.419371   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:06.419510   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-with-capacity-b107bb30-4080f867###default on /tmp/proxy-mount
I0109 13:00:06.419542   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:06.419581   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:06.419598   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-single-with-capacity-b107bb30-4080f867###default on /tmp/csi-staging successfully
I0109 13:00:06.419618   11180 utils.go:83] GRPC response: {}
... skipping 16 lines ...
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:05.881
    STEP: creating mount and staging directories 01/09/23 13:00:05.882
    STEP: creating a volume 01/09/23 13:00:05.883
  << End Captured GinkgoWriter Output
------------------------------
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:460
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:06.711
STEP: creating mount and staging directories 01/09/23 13:00:06.711
I0109 13:00:06.712090   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 13:00:06.712212   11180 utils.go:77] GRPC request: {}
I0109 13:00:06.712376   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
... skipping 22 lines ...
I0109 13:00:07.801208   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:07.801234   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-b107bb30-4080f867###default"}
I0109 13:00:07.801301   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-b107bb30-4080f867###default on /tmp/csi-staging
I0109 13:00:07.801311   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:07.801339   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:07.801359   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:07.804755   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:07.804791   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:07.804938   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-b107bb30-4080f867###default on /tmp/proxy-mount
I0109 13:00:07.804967   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:07.804991   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:07.804997   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-b107bb30-4080f867###default on /tmp/csi-staging successfully
I0109 13:00:07.805024   11180 utils.go:83] GRPC response: {}
... skipping 29 lines ...
------------------------------
• [1.738 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:369
    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:460

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:06.711
    STEP: creating mount and staging directories 01/09/23 13:00:06.711
    STEP: creating a volume 01/09/23 13:00:06.713
  << End Captured GinkgoWriter Output
------------------------------
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:501
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:08.449
STEP: creating mount and staging directories 01/09/23 13:00:08.449
I0109 13:00:08.450269   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 13:00:08.450356   11180 utils.go:77] GRPC request: {}
I0109 13:00:08.450428   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
... skipping 4 lines ...
I0109 13:00:09.053348   11180 controllerserver.go:482] create file share sanity-controller-create-twice-different-b107bb30-4080f867 on storage account f25be7011572b473e9caaf9 successfully
W0109 13:00:09.053413   11180 azurefile.go:952] could not create secret: kubeClient is nil
I0109 13:00:09.053480   11180 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.314874012 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default" result_code="succeeded"
I0109 13:00:09.053515   11180 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default"}}
I0109 13:00:09.054801   11180 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0109 13:00:09.055054   11180 utils.go:77] GRPC request: {"capacity_range":{"limit_bytes":21474836480,"required_bytes":21474836480},"name":"sanity-controller-create-twice-different-B107BB30-4080F867","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
E0109 13:00:09.322775   11180 utils.go:81] GRPC error: rpc error: code = AlreadyExists desc = request file share(sanity-controller-create-twice-different-b107bb30-4080f867) already exists, but its capacity 10 is smaller than 20
I0109 13:00:09.324857   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0109 13:00:09.325274   11180 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default"}
I0109 13:00:09.325688   11180 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default on /tmp/csi-mount/target
I0109 13:00:09.326161   11180 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0109 13:00:09.326595   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0109 13:00:09.327023   11180 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default on /tmp/csi-mount/target successfully
I0109 13:00:09.327212   11180 utils.go:83] GRPC response: {}
I0109 13:00:09.328829   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:09.328873   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default"}
I0109 13:00:09.329007   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default on /tmp/csi-staging
I0109 13:00:09.329026   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:09.329060   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:09.329074   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:09.332416   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:09.332461   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:09.332655   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default on /tmp/proxy-mount
I0109 13:00:09.332689   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:09.332723   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:09.332734   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-create-twice-different-b107bb30-4080f867###default on /tmp/csi-staging successfully
I0109 13:00:09.332753   11180 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [1.181 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:369
    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:501

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:08.449
    STEP: creating mount and staging directories 01/09/23 13:00:08.449
    STEP: creating a volume 01/09/23 13:00:08.45
  << End Captured GinkgoWriter Output
------------------------------
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:545
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:09.63
STEP: creating mount and staging directories 01/09/23 13:00:09.63
I0109 13:00:09.631109   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 13:00:09.631144   11180 utils.go:77] GRPC request: {}
I0109 13:00:09.631219   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
... skipping 15 lines ...
I0109 13:00:10.182770   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:10.182814   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default"}
I0109 13:00:10.183281   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default on /tmp/csi-staging
I0109 13:00:10.183295   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:10.183331   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:10.183355   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:10.187187   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:10.187227   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:10.187337   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default on /tmp/proxy-mount
I0109 13:00:10.187355   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:10.187382   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:10.187398   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default on /tmp/csi-staging successfully
I0109 13:00:10.187412   11180 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [0.767 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:369
    should not fail when creating volume with maximum-length name
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:545

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:09.63
    STEP: creating mount and staging directories 01/09/23 13:00:09.63
    STEP: creating a volume 01/09/23 13:00:09.632
... skipping 41 lines ...
I0109 13:00:12.012497   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:12.012601   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-vol-from-snap-b107bb30-4080f867###default"}
I0109 13:00:12.012885   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-vol-from-snap-b107bb30-4080f867###default on /tmp/csi-staging
I0109 13:00:12.012900   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:12.012932   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:12.013215   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:12.016597   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:12.016646   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:12.016799   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-vol-from-snap-b107bb30-4080f867###default on /tmp/proxy-mount
I0109 13:00:12.016823   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:12.016853   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:12.016868   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-vol-from-snap-b107bb30-4080f867###default on /tmp/csi-staging successfully
I0109 13:00:12.016888   11180 utils.go:83] GRPC response: {}
... skipping 76 lines ...
  << End Captured GinkgoWriter Output

  Volume Cloning not supported
  In [It] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:619
------------------------------
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:641
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:12.995
STEP: creating mount and staging directories 01/09/23 13:00:12.995
I0109 13:00:12.996367   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 13:00:12.996383   11180 utils.go:77] GRPC request: {}
I0109 13:00:12.996441   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
... skipping 3 lines ...
------------------------------
S [SKIPPED] [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  CreateVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:369
    [It] should fail when the volume source volume is not found
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:641

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:12.995
    STEP: creating mount and staging directories 01/09/23 13:00:12.995
  << End Captured GinkgoWriter Output

  Volume Cloning not supported
  In [It] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:643
------------------------------
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:671
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:12.998
STEP: creating mount and staging directories 01/09/23 13:00:12.998
I0109 13:00:12.999030   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 13:00:12.999217   11180 utils.go:77] GRPC request: {}
I0109 13:00:12.999377   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
I0109 13:00:13.000394   11180 utils.go:76] GRPC call: /csi.v1.Controller/DeleteVolume
I0109 13:00:13.000423   11180 utils.go:77] GRPC request: {}
E0109 13:00:13.000514   11180 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.003 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  DeleteVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:664
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:671

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:12.998
    STEP: creating mount and staging directories 01/09/23 13:00:12.998
  << End Captured GinkgoWriter Output
... skipping 5 lines ...
STEP: creating mount and staging directories 01/09/23 13:00:13.001
I0109 13:00:13.001982   11180 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0109 13:00:13.002190   11180 utils.go:77] GRPC request: {}
I0109 13:00:13.002351   11180 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":9}}},{"Type":{"Rpc":{"type":13}}}]}
I0109 13:00:13.003027   11180 utils.go:76] GRPC call: /csi.v1.Controller/DeleteVolume
I0109 13:00:13.003060   11180 utils.go:77] GRPC request: {"volume_id":"fake-vol-id"}
E0109 13:00:13.003197   11180 controllerserver.go:581] GetFileShareInfo(fake-vol-id) in DeleteVolume failed with error: error parsing volume id: "fake-vol-id", should at least contain two #
I0109 13:00:13.003337   11180 utils.go:83] GRPC response: {}
------------------------------
• [0.003 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  DeleteVolume
... skipping 43 lines ...
    STEP: creating mount and staging directories 01/09/23 13:00:13.004
    STEP: creating a volume 01/09/23 13:00:13.005
    STEP: deleting a volume 01/09/23 13:00:13.538
  << End Captured GinkgoWriter Output
------------------------------
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:734
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:13.835
STEP: creating mount and staging directories 01/09/23 13:00:13.835
I0109 13:00:13.837629   11180 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0109 13:00:13.837928   11180 utils.go:77] GRPC request: {}
E0109 13:00:13.838083   11180 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
------------------------------
• [0.003 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ValidateVolumeCapabilities
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:733
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:734

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:13.835
    STEP: creating mount and staging directories 01/09/23 13:00:13.835
  << End Captured GinkgoWriter Output
------------------------------
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:748
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:13.838
STEP: creating mount and staging directories 01/09/23 13:00:13.839
STEP: creating a single node writer volume 01/09/23 13:00:13.839
I0109 13:00:13.839886   11180 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0109 13:00:13.839920   11180 utils.go:77] GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"sanity-controller-validate-nocaps-B107BB30-4080F867","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0109 13:00:13.974079   11180 controllerserver.go:460] begin to create file share(sanity-controller-validate-nocaps-b107bb30-4080f867) on account(f25be7011572b473e9caaf9) type() subID() rg(azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582) location() size(10) protocol(SMB)
I0109 13:00:14.287655   11180 controllerserver.go:482] create file share sanity-controller-validate-nocaps-b107bb30-4080f867 on storage account f25be7011572b473e9caaf9 successfully
W0109 13:00:14.287840   11180 azurefile.go:952] could not create secret: kubeClient is nil
I0109 13:00:14.287929   11180 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.313852807 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default" result_code="succeeded"
I0109 13:00:14.287961   11180 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default"}}
I0109 13:00:14.289103   11180 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0109 13:00:14.289133   11180 utils.go:77] GRPC request: {"volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default"}
E0109 13:00:14.289398   11180 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities not provided
I0109 13:00:14.290102   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0109 13:00:14.290119   11180 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default"}
I0109 13:00:14.290215   11180 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default on /tmp/csi-mount/target
I0109 13:00:14.290251   11180 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0109 13:00:14.290353   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0109 13:00:14.290372   11180 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default on /tmp/csi-mount/target successfully
I0109 13:00:14.290384   11180 utils.go:83] GRPC response: {}
I0109 13:00:14.291187   11180 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0109 13:00:14.291208   11180 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default"}
I0109 13:00:14.291266   11180 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default on /tmp/csi-staging
I0109 13:00:14.291274   11180 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0109 13:00:14.291296   11180 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0109 13:00:14.291313   11180 mount_linux.go:779] Unmounting /tmp/csi-staging
I0109 13:00:14.294453   11180 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0109 13:00:14.294485   11180 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0109 13:00:14.294603   11180 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default on /tmp/proxy-mount
I0109 13:00:14.294643   11180 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0109 13:00:14.294675   11180 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0109 13:00:14.294682   11180 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-6510ed1d-901d-11ed-adf8-9aa54ef40582#f25be7011572b473e9caaf9#sanity-controller-validate-nocaps-b107bb30-4080f867###default on /tmp/csi-staging successfully
I0109 13:00:14.294695   11180 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [0.800 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ValidateVolumeCapabilities
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:733
    should fail when no volume capabilities are provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:748

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/09/23 13:00:13.838
    STEP: creating mount and staging directories 01/09/23 13:00:13.839
    STEP: creating a single node writer volume 01/09/23 13:00:13.839
... skipping 12 lines ...