pull-azurefile-csi-driver-sanity #1613716618238496768 Results

Error lines from build-log.txt

Raw build-log.txt
... skipping 92 lines ...
Git Commit: 58d9cc8196d4f58378cd04b7f209f5d340a1c12d
Go Version: go1.19.4
Platform: linux/amd64

Streaming logs below:
I0113 02:01:56.782311   11052 azurefile.go:299] driver userAgent: file.csi.azure.com/e2e-58d9cc8196d4f58378cd04b7f209f5d340a1c12d
W0113 02:01:56.782450   11052 azure.go:73] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
I0113 02:01:56.782496   11052 azure.go:92] could not read cloud config from secret kube-system/azure-cloud-provider
I0113 02:01:56.782526   11052 azure.go:95] AZURE_CREDENTIAL_FILE env var set as /tmp/azure.json
I0113 02:01:56.782614   11052 azure.go:110] read cloud config from file: /tmp/azure.json successfully
I0113 02:01:56.783526   11052 azure_auth.go:253] Using AzurePublicCloud environment
I0113 02:01:56.783676   11052 azure_auth.go:138] azure: using client_id+client_secret to retrieve access token
I0113 02:01:56.783820   11052 azure.go:776] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 60 lines ...
  Begin Captured GinkgoWriter Output >>
    STEP: connecting to CSI driver 01/13/23 02:01:57.744
    STEP: creating mount and staging directories 01/13/23 02:01:57.747
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:01:57.769
STEP: creating mount and staging directories 01/13/23 02:01:57.77
I0113 02:01:57.771041   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.771054   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.771097   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.771537   11052 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}}}]}
I0113 02:01:57.773067   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.773108   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.773183   11052 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}}}]}
I0113 02:01:57.775379   11052 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
I0113 02:01:57.775396   11052 utils.go:77] GRPC request: {}
E0113 02:01:57.775733   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
------------------------------
• [0.006 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodePublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:378
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:379

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.769
    STEP: creating mount and staging directories 01/13/23 02:01:57.77
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:01:57.776
STEP: creating mount and staging directories 01/13/23 02:01:57.776
I0113 02:01:57.776956   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.776967   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.777006   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.777680   11052 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}}}]}
I0113 02:01:57.780637   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.780659   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.780700   11052 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}}}]}
I0113 02:01:57.781259   11052 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
I0113 02:01:57.781307   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-9a28743f-5"}
E0113 02:01:57.781384   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
------------------------------
• [0.006 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodePublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:378
    should fail when no target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:393

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.776
    STEP: creating mount and staging directories 01/13/23 02:01:57.776
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:01:57.782
STEP: creating mount and staging directories 01/13/23 02:01:57.782
I0113 02:01:57.784335   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.784354   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.784397   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.784919   11052 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}}}]}
I0113 02:01:57.787570   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.788024   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.788386   11052 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}}}]}
I0113 02:01:57.789104   11052 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
I0113 02:01:57.789115   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"fake-vol-id-4531701e-7"}
E0113 02:01:57.789175   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodePublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:378
    should fail when no volume capability is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:408

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.782
    STEP: creating mount and staging directories 01/13/23 02:01:57.782
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:01:57.79
STEP: creating mount and staging directories 01/13/23 02:01:57.79
I0113 02:01:57.791284   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.791347   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.791454   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.792826   11052 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}}}]}
I0113 02:01:57.795239   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.795261   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.795303   11052 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}}}]}
I0113 02:01:57.796167   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:01:57.796190   11052 utils.go:77] GRPC request: {}
E0113 02:01:57.796305   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:426
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:427

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.79
    STEP: creating mount and staging directories 01/13/23 02:01:57.79
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:01:57.797
STEP: creating mount and staging directories 01/13/23 02:01:57.798
I0113 02:01:57.799377   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.799396   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.799448   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.800182   11052 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}}}]}
I0113 02:01:57.801658   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.801676   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.801723   11052 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}}}]}
I0113 02:01:57.803344   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:01:57.803495   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-3b336cf9-a"}
E0113 02:01:57.803612   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
------------------------------
• [0.006 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:426
    should fail when no target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:439

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.797
    STEP: creating mount and staging directories 01/13/23 02:01:57.798
  << End Captured GinkgoWriter Output
------------------------------
S
------------------------------
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:525
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.804
STEP: creating mount and staging directories 01/13/23 02:01:57.805
I0113 02:01:57.806735   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.806936   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.807213   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.808126   11052 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}}}]}
I0113 02:01:57.810588   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.810610   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.810651   11052 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}}}]}
I0113 02:01:57.813115   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeStageVolume
I0113 02:01:57.813190   11052 utils.go:77] GRPC request: {"publish_context":{"device":"/dev/mock"},"staging_target_path":"/tmp/csi-staging","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}}
E0113 02:01:57.813660   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.009 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeStageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:512
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:525

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.804
    STEP: creating mount and staging directories 01/13/23 02:01:57.805
  << End Captured GinkgoWriter Output
------------------------------
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:544
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.814
STEP: creating mount and staging directories 01/13/23 02:01:57.814
I0113 02:01:57.814802   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.814851   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.814899   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:01:57.815685   11052 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}}}]}
I0113 02:01:57.820399   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:01:57.820417   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.820453   11052 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}}}]}
I0113 02:01:57.820938   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeStageVolume
I0113 02:01:57.820954   11052 utils.go:77] GRPC request: {"publish_context":{"device":"/dev/mock"},"volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_id":"fake-vol-id-2b973299-e"}
E0113 02:01:57.821016   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeStageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:512
    should fail when no staging target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:544

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.814
    STEP: creating mount and staging directories 01/13/23 02:01:57.814
  << End Captured GinkgoWriter Output
------------------------------
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:563
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.822
STEP: creating mount and staging directories 01/13/23 02:01:57.822
I0113 02:01:57.823022   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:01:57.823074   11052 utils.go:77] GRPC request: {}
I0113 02:01:57.823120   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 14 lines ...
I0113 02:02:19.836205   11052 controllerserver.go:495] create file share sanity-node-stage-nocaps-7329ecf1-728820b0 on storage account f40d6e94b7ea84eb2a8bcd4 successfully
W0113 02:02:19.836307   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:02:19.836492   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.640624638 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default" result_code="succeeded"
I0113 02:02:19.836887   11052 utils.go:83] GRPC response: {"volume":{"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default"}}
I0113 02:02:19.839037   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeStageVolume
I0113 02:02:19.839211   11052 utils.go:77] GRPC request: {"publish_context":{"device":"/dev/mock"},"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default"}
E0113 02:02:19.839491   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
I0113 02:02:19.841071   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:02:19.841131   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default"}
I0113 02:02:19.841271   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default on /tmp/csi-mount/target
I0113 02:02:19.841280   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:02:19.841366   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:02:19.841384   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default on /tmp/csi-mount/target successfully
I0113 02:02:19.841396   11052 utils.go:83] GRPC response: {}
I0113 02:02:19.843715   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:19.844152   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default"}
I0113 02:02:19.844403   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:19.844491   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:19.844590   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:19.844671   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:19.854556   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:19.854595   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:19.854712   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:19.854811   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:19.854978   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:19.855034   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:19.855070   11052 utils.go:83] GRPC response: {}
... skipping 8 lines ...
------------------------------
• [SLOW TEST] [22.467 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeStageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:512
    should fail when no volume capability is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:563

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:01:57.822
    STEP: creating mount and staging directories 01/13/23 02:01:57.822
    STEP: creating a single node writer volume 01/13/23 02:01:57.828
  << End Captured GinkgoWriter Output
------------------------------
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:614
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.289
STEP: creating mount and staging directories 01/13/23 02:02:20.289
I0113 02:02:20.290069   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:20.290089   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.290157   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:02:20.291049   11052 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}}}]}
I0113 02:02:20.293282   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.293341   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.293456   11052 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}}}]}
I0113 02:02:20.295200   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:20.295750   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging"}
E0113 02:02:20.295831   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnstageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:607
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:614

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.289
    STEP: creating mount and staging directories 01/13/23 02:02:20.289
  << End Captured GinkgoWriter Output
------------------------------
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:628
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.296
STEP: creating mount and staging directories 01/13/23 02:02:20.296
I0113 02:02:20.297336   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:20.297357   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.297400   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:02:20.298625   11052 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}}}]}
I0113 02:02:20.302338   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.302368   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.302410   11052 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}}}]}
I0113 02:02:20.302982   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:20.303005   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-9aa4ca87-d"}
E0113 02:02:20.303287   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnstageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:607
    should fail when no staging target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:628

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.296
    STEP: creating mount and staging directories 01/13/23 02:02:20.296
  << End Captured GinkgoWriter Output
------------------------------
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:650
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.303
STEP: creating mount and staging directories 01/13/23 02:02:20.304
I0113 02:02:20.304499   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:20.304519   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.304562   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0113 02:02:20.305073   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.305198   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.305354   11052 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}}}]}
I0113 02:02:20.309998   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.310591   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.310929   11052 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}}}]}
E0113 02:02:20.311888   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = NodeGetVolumeStats volume ID was empty
------------------------------
• [0.009 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:650

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.303
    STEP: creating mount and staging directories 01/13/23 02:02:20.304
  << End Captured GinkgoWriter Output
------------------------------
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:664
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.315
STEP: creating mount and staging directories 01/13/23 02:02:20.315
I0113 02:02:20.315798   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:20.315812   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.315953   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0113 02:02:20.316363   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.316382   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.316435   11052 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}}}]}
I0113 02:02:20.318123   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.318143   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.318183   11052 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}}}]}
E0113 02:02:20.320461   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = NodeGetVolumeStats volume path was empty
------------------------------
• [0.006 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    should fail when no volume path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:664

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.315
    STEP: creating mount and staging directories 01/13/23 02:02:20.315
  << End Captured GinkgoWriter Output
------------------------------
Node Service NodeGetVolumeStats
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:678
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.321
STEP: creating mount and staging directories 01/13/23 02:02:20.321
I0113 02:02:20.321841   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:20.321868   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.321914   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0113 02:02:20.323154   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.323178   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.323236   11052 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}}}]}
I0113 02:02:20.327331   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:20.327469   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.327547   11052 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}}}]}
E0113 02:02:20.328993   11052 utils.go:81] GRPC error: rpc error: code = NotFound desc = path some/path does not exist
------------------------------
• [0.008 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    should fail when volume is not found
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:678

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.321
    STEP: creating mount and staging directories 01/13/23 02:02:20.321
  << End Captured GinkgoWriter Output
------------------------------
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:693
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.33
STEP: creating mount and staging directories 01/13/23 02:02:20.33
I0113 02:02:20.331628   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:20.332131   11052 utils.go:77] GRPC request: {}
I0113 02:02:20.332242   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 34 lines ...
I0113 02:02:21.626519   11052 nodeserver.go:109] NodePublishVolume: mounting /tmp/csi-staging at /tmp/csi-mount/target with mountOptions: [bind]
I0113 02:02:21.626640   11052 mount_linux.go:220] Mounting cmd (mount) with arguments ( -o bind /tmp/csi-staging /tmp/csi-mount/target)
I0113 02:02:21.630631   11052 mount_linux.go:220] Mounting cmd (mount) with arguments ( -o bind,remount /tmp/csi-staging /tmp/csi-mount/target)
I0113 02:02:21.635009   11052 nodeserver.go:116] NodePublishVolume: mount /tmp/csi-staging at /tmp/csi-mount/target successfully
I0113 02:02:21.635060   11052 utils.go:83] GRPC response: {}
STEP: Get node volume stats 01/13/23 02:02:21.635
E0113 02:02:21.636078   11052 utils.go:81] GRPC error: rpc error: code = NotFound desc = path some/path does not exist
I0113 02:02:21.636549   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:02:21.636564   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-get-volume-stats-7329ecf1-728820b0###default"}
I0113 02:02:21.636631   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-get-volume-stats-7329ecf1-728820b0###default on /tmp/csi-mount/target
I0113 02:02:21.636640   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
I0113 02:02:21.636664   11052 mount_helper_common.go:56] unmounting "/tmp/csi-mount/target" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:21.636674   11052 mount_linux.go:779] Unmounting /tmp/csi-mount/target
... skipping 48 lines ...
------------------------------
• [2.091 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    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:693

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:20.33
    STEP: creating mount and staging directories 01/13/23 02:02:20.33
    STEP: creating a single node writer volume for expansion 01/13/23 02:02:20.341
... skipping 2 lines ...
    STEP: node staging volume 01/13/23 02:02:20.865
    STEP: publishing the volume on a node 01/13/23 02:02:21.618
    STEP: Get node volume stats 01/13/23 02:02:21.635
  << End Captured GinkgoWriter Output
------------------------------
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:740
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:22.422
STEP: creating mount and staging directories 01/13/23 02:02:22.422
I0113 02:02:22.424336   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:22.424884   11052 utils.go:77] GRPC request: {}
I0113 02:02:22.425342   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...
------------------------------
S [SKIPPED] [0.019 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeExpandVolume [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:733
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:740

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

  NodeExpandVolume not supported
  In [BeforeEach] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:735
------------------------------
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:755
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:22.441
STEP: creating mount and staging directories 01/13/23 02:02:22.441
I0113 02:02:22.441933   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:22.441948   11052 utils.go:77] GRPC request: {}
I0113 02:02:22.442013   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...
------------------------------
S [SKIPPED] [0.009 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeExpandVolume [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:733
    should fail when no volume path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:755

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

  NodeExpandVolume not supported
  In [BeforeEach] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:735
------------------------------
Node Service NodeExpandVolume
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:774
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:22.45
STEP: creating mount and staging directories 01/13/23 02:02:22.45
I0113 02:02:22.451586   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:02:22.451806   11052 utils.go:77] GRPC request: {}
I0113 02:02:22.451956   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...
------------------------------
S [SKIPPED] [0.014 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeExpandVolume [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:733
    should fail when volume is not found
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:774

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:22.45
    STEP: creating mount and staging directories 01/13/23 02:02:22.45
  << End Captured GinkgoWriter Output
... skipping 257 lines ...
    STEP: publishing the volume on a node 01/13/23 02:02:26.301
    STEP: publishing the volume on a node 01/13/23 02:02:26.469
    STEP: Get node volume stats 01/13/23 02:02:26.645
  << End Captured GinkgoWriter Output
------------------------------
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:1528
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.527
STEP: creating mount and staging directories 01/13/23 02:02:27.528
I0113 02:02:27.528542   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:27.528613   11052 utils.go:77] GRPC request: {}
I0113 02:02:27.528723   11052 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}}}]}
I0113 02:02:27.530843   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerExpandVolume
I0113 02:02:27.530903   11052 utils.go:77] GRPC request: {"capacity_range":{"required_bytes":11811160064}}
E0113 02:02:27.531226   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.004 seconds]
ExpandVolume [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail if no volume id is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1528

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.527
    STEP: creating mount and staging directories 01/13/23 02:02:27.528
  << End Captured GinkgoWriter Output
------------------------------
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:1545
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.532
STEP: creating mount and staging directories 01/13/23 02:02:27.532
I0113 02:02:27.532853   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:27.532874   11052 utils.go:77] GRPC request: {}
I0113 02:02:27.532922   11052 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}}}]}
I0113 02:02:27.533897   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerExpandVolume
I0113 02:02:27.533939   11052 utils.go:77] GRPC request: {}
E0113 02:02:27.534049   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.003 seconds]
ExpandVolume [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail if no capacity range is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1545

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.532
    STEP: creating mount and staging directories 01/13/23 02:02:27.532
  << End Captured GinkgoWriter Output
... skipping 228 lines ...
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.562
    STEP: creating mount and staging directories 01/13/23 02:02:27.562
    STEP: verifying name size and characters 01/13/23 02:02:27.563
  << End Captured GinkgoWriter Output
------------------------------
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:1366
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.564
STEP: creating mount and staging directories 01/13/23 02:02:27.564
I0113 02:02:27.566280   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:27.566335   11052 utils.go:77] GRPC request: {}
I0113 02:02:27.566455   11052 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}}}]}
I0113 02:02:27.567277   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0113 02:02:27.567290   11052 utils.go:77] GRPC request: {}
E0113 02:02:27.567355   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
------------------------------
• [0.003 seconds]
DeleteSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail when no snapshot id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1366

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:27.564
    STEP: creating mount and staging directories 01/13/23 02:02:27.564
  << End Captured GinkgoWriter Output
... skipping 5 lines ...
STEP: creating mount and staging directories 01/13/23 02:02:27.567
I0113 02:02:27.568231   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:27.568243   11052 utils.go:77] GRPC request: {}
I0113 02:02:27.568284   11052 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}}}]}
I0113 02:02:27.568803   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0113 02:02:27.568814   11052 utils.go:77] GRPC request: {"snapshot_id":"reallyfakesnapshotid"}
I0113 02:02:27.568877   11052 controllerserver.go:934] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I0113 02:02:27.568888   11052 utils.go:83] GRPC response: {}
------------------------------
• [0.002 seconds]
DeleteSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should succeed when an invalid snapshot id is used
... skipping 42 lines ...
I0113 02:02:29.418129   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:29.418164   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1###default"}
I0113 02:02:29.418311   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1###default on /tmp/csi-staging
I0113 02:02:29.418336   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:29.418381   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:29.418410   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:29.421798   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:29.421822   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:29.421921   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1###default on /tmp/proxy-mount
I0113 02:02:29.421931   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:29.421952   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:29.421959   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1###default on /tmp/csi-staging successfully
I0113 02:02:29.421972   11052 utils.go:83] GRPC response: {}
... skipping 89 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/13/23 02:02:29.785
STEP: creating mount and staging directories 01/13/23 02:02:29.785
I0113 02:02:29.790494   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:29.790557   11052 utils.go:77] GRPC request: {}
I0113 02:02:29.790720   11052 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.008 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/13/23 02:02:29.785
    STEP: creating mount and staging directories 01/13/23 02:02:29.785
  << 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/13/23 02:02:29.799
STEP: creating mount and staging directories 01/13/23 02:02:29.799
I0113 02:02:29.800761   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:29.800954   11052 utils.go:77] GRPC request: {}
I0113 02:02:29.801516   11052 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}}}]}
I0113 02:02:29.802927   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:02:29.802945   11052 utils.go:77] GRPC request: {}
E0113 02:02:29.803023   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided
------------------------------
• [0.004 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/13/23 02:02:29.799
    STEP: creating mount and staging directories 01/13/23 02:02:29.799
  << 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/13/23 02:02:29.803
STEP: creating mount and staging directories 01/13/23 02:02:29.803
I0113 02:02:29.804307   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:29.804351   11052 utils.go:77] GRPC request: {}
I0113 02:02:29.804509   11052 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}}}]}
I0113 02:02:29.805544   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:02:29.805559   11052 utils.go:77] GRPC request: {"name":"sanity-controller-create-no-volume-capabilities-7329ECF1-728820B0"}
E0113 02:02:29.805633   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities not valid: CreateVolume Volume capabilities 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 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/13/23 02:02:29.803
    STEP: creating mount and staging directories 01/13/23 02:02:29.803
  << End Captured GinkgoWriter Output
... skipping 24 lines ...
I0113 02:02:30.425481   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:30.425533   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-7329ecf1-728820b0###default"}
I0113 02:02:30.425749   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:30.425769   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:30.428040   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:30.428091   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:30.434061   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:30.434160   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:30.434491   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:30.434553   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:30.434638   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:30.434664   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:30.434682   11052 utils.go:83] GRPC response: {}
... skipping 44 lines ...
I0113 02:02:31.377581   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:31.377614   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-7329ecf1-728820b0###default"}
I0113 02:02:31.377778   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:31.377803   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:31.377861   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:31.377873   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:31.382805   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:31.382838   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:31.382936   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:31.382954   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:31.382978   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:31.382987   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:31.383002   11052 utils.go:83] GRPC response: {}
... skipping 16 lines ...
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:30.744
    STEP: creating mount and staging directories 01/13/23 02:02:30.744
    STEP: creating a volume 01/13/23 02:02:30.748
  << 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/13/23 02:02:31.712
STEP: creating mount and staging directories 01/13/23 02:02:31.713
I0113 02:02:31.713788   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:31.713814   11052 utils.go:77] GRPC request: {}
I0113 02:02:31.713883   11052 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 ...
I0113 02:02:33.138822   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:33.138877   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-7329ecf1-728820b0###default"}
I0113 02:02:33.139046   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:33.139069   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:33.139111   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:33.139134   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:33.143934   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:33.143982   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:33.144159   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:33.144173   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:33.144220   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:33.144235   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:33.144260   11052 utils.go:83] GRPC response: {}
... skipping 29 lines ...
------------------------------
• [2.204 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/13/23 02:02:31.712
    STEP: creating mount and staging directories 01/13/23 02:02:31.713
    STEP: creating a volume 01/13/23 02:02:31.714
  << 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/13/23 02:02:33.916
STEP: creating mount and staging directories 01/13/23 02:02:33.917
I0113 02:02:33.918045   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:33.918100   11052 utils.go:77] GRPC request: {}
I0113 02:02:33.918216   11052 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 ...
I0113 02:02:34.454993   11052 controllerserver.go:495] create file share sanity-controller-create-twice-different-7329ecf1-728820b0 on storage account f40d6e94b7ea84eb2a8bcd4 successfully
W0113 02:02:34.455062   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:02:34.455184   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.353704714 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default" result_code="succeeded"
I0113 02:02:34.455229   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default"}}
I0113 02:02:34.456694   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:02:34.456717   11052 utils.go:77] GRPC request: {"capacity_range":{"limit_bytes":21474836480,"required_bytes":21474836480},"name":"sanity-controller-create-twice-different-7329ECF1-728820B0","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
E0113 02:02:34.691220   11052 utils.go:81] GRPC error: rpc error: code = AlreadyExists desc = request file share(sanity-controller-create-twice-different-7329ecf1-728820b0) already exists, but its capacity 10 is smaller than 20
I0113 02:02:34.692402   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:02:34.692451   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default"}
I0113 02:02:34.692602   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default on /tmp/csi-mount/target
I0113 02:02:34.692615   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:02:34.692682   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:02:34.692697   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default on /tmp/csi-mount/target successfully
I0113 02:02:34.692707   11052 utils.go:83] GRPC response: {}
I0113 02:02:34.693323   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:34.693379   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default"}
I0113 02:02:34.693464   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:34.693491   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:34.693520   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:34.693571   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:34.697634   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:34.697743   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:34.697851   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:34.697868   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:34.697886   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:34.697892   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:34.697915   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [1.119 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/13/23 02:02:33.916
    STEP: creating mount and staging directories 01/13/23 02:02:33.917
    STEP: creating a volume 01/13/23 02:02:33.918
  << 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/13/23 02:02:35.036
STEP: creating mount and staging directories 01/13/23 02:02:35.037
I0113 02:02:35.039748   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:35.039777   11052 utils.go:77] GRPC request: {}
I0113 02:02:35.039844   11052 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 ...
I0113 02:02:35.627034   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:35.627173   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default"}
I0113 02:02:35.627290   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default on /tmp/csi-staging
I0113 02:02:35.627300   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:35.627322   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:35.627342   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:35.632573   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:35.632607   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:35.632835   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default on /tmp/proxy-mount
I0113 02:02:35.633018   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:35.633178   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:35.633228   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa###default on /tmp/csi-staging successfully
I0113 02:02:35.633263   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [1.005 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/13/23 02:02:35.036
    STEP: creating mount and staging directories 01/13/23 02:02:35.037
    STEP: creating a volume 01/13/23 02:02:35.04
... skipping 41 lines ...
I0113 02:02:37.863156   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:37.863182   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-7329ecf1-728820b0###default"}
I0113 02:02:37.863346   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:37.863356   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:37.863379   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:37.863389   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:37.869712   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:37.869780   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:37.869946   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:37.870002   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:37.870087   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:37.870119   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:37.870153   11052 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/13/23 02:02:38.838
STEP: creating mount and staging directories 01/13/23 02:02:38.839
I0113 02:02:38.840460   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:38.840489   11052 utils.go:77] GRPC request: {}
I0113 02:02:38.840572   11052 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.005 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/13/23 02:02:38.838
    STEP: creating mount and staging directories 01/13/23 02:02:38.839
  << 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/13/23 02:02:38.845
STEP: creating mount and staging directories 01/13/23 02:02:38.845
I0113 02:02:38.846582   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:38.846668   11052 utils.go:77] GRPC request: {}
I0113 02:02:38.846765   11052 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}}}]}
I0113 02:02:38.848833   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteVolume
I0113 02:02:38.849134   11052 utils.go:77] GRPC request: {}
E0113 02:02:38.849289   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.005 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/13/23 02:02:38.845
    STEP: creating mount and staging directories 01/13/23 02:02:38.845
  << End Captured GinkgoWriter Output
... skipping 5 lines ...
STEP: creating mount and staging directories 01/13/23 02:02:38.851
I0113 02:02:38.852778   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:38.852804   11052 utils.go:77] GRPC request: {}
I0113 02:02:38.852917   11052 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}}}]}
I0113 02:02:38.855232   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteVolume
I0113 02:02:38.855256   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id"}
E0113 02:02:38.855518   11052 controllerserver.go:594] GetFileShareInfo(fake-vol-id) in DeleteVolume failed with error: error parsing volume id: "fake-vol-id", should at least contain two #
I0113 02:02:38.855616   11052 utils.go:83] GRPC response: {}
------------------------------
• [0.005 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/13/23 02:02:38.857
    STEP: creating a volume 01/13/23 02:02:38.858
    STEP: deleting a volume 01/13/23 02:02:39.489
  << 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/13/23 02:02:39.81
STEP: creating mount and staging directories 01/13/23 02:02:39.81
I0113 02:02:39.812901   11052 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0113 02:02:39.812943   11052 utils.go:77] GRPC request: {}
E0113 02:02:39.813366   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
------------------------------
• [0.004 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/13/23 02:02:39.81
    STEP: creating mount and staging directories 01/13/23 02:02:39.81
  << 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/13/23 02:02:39.814
STEP: creating mount and staging directories 01/13/23 02:02:39.814
STEP: creating a single node writer volume 01/13/23 02:02:39.815
I0113 02:02:39.816022   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:02:39.816057   11052 utils.go:77] GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"sanity-controller-validate-nocaps-7329ECF1-728820B0","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0113 02:02:40.083429   11052 controllerserver.go:473] begin to create file share(sanity-controller-validate-nocaps-7329ecf1-728820b0) on account(f40d6e94b7ea84eb2a8bcd4) type() subID() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() size(10) protocol(SMB)
I0113 02:02:40.435305   11052 controllerserver.go:495] create file share sanity-controller-validate-nocaps-7329ecf1-728820b0 on storage account f40d6e94b7ea84eb2a8bcd4 successfully
W0113 02:02:40.435500   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:02:40.436095   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.352373896 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default" result_code="succeeded"
I0113 02:02:40.436304   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default"}}
I0113 02:02:40.437673   11052 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0113 02:02:40.437720   11052 utils.go:77] GRPC request: {"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default"}
E0113 02:02:40.437922   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities not provided
I0113 02:02:40.438991   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:02:40.439020   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default"}
I0113 02:02:40.439149   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default on /tmp/csi-mount/target
I0113 02:02:40.439157   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:02:40.439222   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:02:40.439237   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default on /tmp/csi-mount/target successfully
I0113 02:02:40.439245   11052 utils.go:83] GRPC response: {}
I0113 02:02:40.440318   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:40.440341   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default"}
I0113 02:02:40.440387   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:40.440396   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:40.440420   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:40.440441   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:40.446386   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:40.446421   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:40.446545   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:40.446564   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:40.446586   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:40.446592   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:40.446605   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [0.966 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/13/23 02:02:39.814
    STEP: creating mount and staging directories 01/13/23 02:02:39.814
    STEP: creating a single node writer volume 01/13/23 02:02:39.815
... skipping 26 lines ...
I0113 02:02:41.659647   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:41.659691   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-7329ecf1-728820b0###default"}
I0113 02:02:41.659782   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:41.659802   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:41.659829   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:41.659996   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:41.665207   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:41.665247   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:41.665384   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:41.665430   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:41.665477   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:41.665496   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:41.665519   11052 utils.go:83] GRPC response: {}
... skipping 17 lines ...
    STEP: creating mount and staging directories 01/13/23 02:02:40.781
    STEP: creating a single node writer volume 01/13/23 02:02:40.782
    STEP: validating volume capabilities 01/13/23 02:02:41.404
  << End Captured GinkgoWriter Output
------------------------------
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:825
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:41.992
STEP: creating mount and staging directories 01/13/23 02:02:41.993
I0113 02:02:41.994866   11052 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0113 02:02:41.994903   11052 utils.go:77] GRPC request: {"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}],"volume_id":"fake-vol-id-429f4256-9"}
W0113 02:02:41.995134   11052 azurefile.go:595] parsing volumeID(fake-vol-id-429f4256-9) return with error: error parsing volume id: "fake-vol-id-429f4256-9", should at least contain two #
E0113 02:02:41.995163   11052 utils.go:81] GRPC error: rpc error: code = NotFound desc = get account info from(fake-vol-id-429f4256-9) failed with error: <nil>
------------------------------
• [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 the requested volume does not exist
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:825

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:41.992
    STEP: creating mount and staging directories 01/13/23 02:02:41.993
  << End Captured GinkgoWriter Output
------------------------------
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:852
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:41.995
STEP: creating mount and staging directories 01/13/23 02:02:41.996
I0113 02:02:41.996617   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:41.996639   11052 utils.go:77] GRPC request: {}
I0113 02:02:41.996702   11052 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}}}]}
I0113 02:02:41.997457   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0113 02:02:41.997488   11052 utils.go:77] GRPC request: {}
E0113 02:02:41.997669   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
------------------------------
• [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:852

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:41.995
    STEP: creating mount and staging directories 01/13/23 02:02:41.996
  << End Captured GinkgoWriter Output
------------------------------
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:867
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:41.998
STEP: creating mount and staging directories 01/13/23 02:02:41.999
I0113 02:02:42.000353   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:42.000407   11052 utils.go:77] GRPC request: {}
I0113 02:02:42.000519   11052 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}}}]}
I0113 02:02:42.001560   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0113 02:02:42.001580   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-1207de76-2"}
E0113 02:02:42.001643   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
------------------------------
• [0.003 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    should fail when no node id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:867

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:41.998
    STEP: creating mount and staging directories 01/13/23 02:02:41.999
  << End Captured GinkgoWriter Output
------------------------------
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:883
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:42.002
STEP: creating mount and staging directories 01/13/23 02:02:42.004
I0113 02:02:42.006363   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:42.006674   11052 utils.go:77] GRPC request: {}
I0113 02:02:42.006767   11052 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}}}]}
I0113 02:02:42.008055   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0113 02:02:42.008103   11052 utils.go:77] GRPC request: {"node_id":"fake-node-id-1806f3d7-a","volume_id":"fake-vol-id-9eb1d28e-f"}
E0113 02:02:42.008260   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
------------------------------
• [0.007 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    should fail when no volume capability is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:883

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:42.002
    STEP: creating mount and staging directories 01/13/23 02:02:42.004
  << End Captured GinkgoWriter Output
------------------------------
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:900
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:42.009
STEP: creating mount and staging directories 01/13/23 02:02:42.009
I0113 02:02:42.009985   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:42.010000   11052 utils.go:77] GRPC request: {}
I0113 02:02:42.010035   11052 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
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    [It] 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:900

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

  testnodevolumeattachlimit not enabled
  In [It] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:902
------------------------------
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:1001
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:42.011
STEP: creating mount and staging directories 01/13/23 02:02:42.011
I0113 02:02:42.012003   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:42.012094   11052 utils.go:77] GRPC request: {}
I0113 02:02:42.012206   11052 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.004 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    [It] 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:1001

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:42.011
    STEP: creating mount and staging directories 01/13/23 02:02:42.011
  << End Captured GinkgoWriter Output
... skipping 84 lines ...
I0113 02:02:42.650695   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:42.650739   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-publish-7329ecf1-728820b0###default"}
I0113 02:02:42.650851   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-publish-7329ecf1-728820b0###default on /tmp/csi-staging
I0113 02:02:42.650870   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:42.650918   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:42.650948   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:42.662449   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:42.662502   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:42.662711   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-publish-7329ecf1-728820b0###default on /tmp/proxy-mount
I0113 02:02:42.662727   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:42.662759   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:42.662771   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-publish-7329ecf1-728820b0###default on /tmp/csi-staging successfully
I0113 02:02:42.662798   11052 utils.go:83] GRPC response: {}
... skipping 297 lines ...
    STEP: calling controllerpublish on that volume 01/13/23 02:02:42.645
    STEP: calling controllerpublish on that volume 01/13/23 02:02:42.646
    STEP: calling controllerpublish on that volume 01/13/23 02:02:42.647
  << End Captured GinkgoWriter Output
------------------------------
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:1079
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:46.454
STEP: creating mount and staging directories 01/13/23 02:02:46.455
I0113 02:02:46.455762   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:46.455793   11052 utils.go:77] GRPC request: {}
I0113 02:02:46.455851   11052 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}}}]}
I0113 02:02:46.456493   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerUnpublishVolume
I0113 02:02:46.456526   11052 utils.go:77] GRPC request: {}
E0113 02:02:46.456601   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
------------------------------
• [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1072
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1079

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:46.454
    STEP: creating mount and staging directories 01/13/23 02:02:46.455
  << End Captured GinkgoWriter Output
------------------------------
CreateSnapshot [Controller Server]
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1422
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:46.457
STEP: creating mount and staging directories 01/13/23 02:02:46.457
I0113 02:02:46.457748   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:46.457768   11052 utils.go:77] GRPC request: {}
I0113 02:02:46.457808   11052 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}}}]}
I0113 02:02:46.458383   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
I0113 02:02:46.458405   11052 utils.go:77] GRPC request: {"source_volume_id":"testId"}
E0113 02:02:46.458477   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
------------------------------
• [0.002 seconds]
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1422

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:46.457
    STEP: creating mount and staging directories 01/13/23 02:02:46.457
  << End Captured GinkgoWriter Output
------------------------------
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:1439
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:46.459
STEP: creating mount and staging directories 01/13/23 02:02:46.459
I0113 02:02:46.460858   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:46.460909   11052 utils.go:77] GRPC request: {}
I0113 02:02:46.461026   11052 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}}}]}
I0113 02:02:46.462002   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
I0113 02:02:46.462026   11052 utils.go:77] GRPC request: {"name":"name"}
E0113 02:02:46.462099   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
------------------------------
• [0.003 seconds]
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail when no source volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1439

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:46.459
    STEP: creating mount and staging directories 01/13/23 02:02:46.459
  << End Captured GinkgoWriter Output
... skipping 49 lines ...
I0113 02:02:48.859024   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:48.859039   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1###default"}
I0113 02:02:48.859085   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1###default on /tmp/csi-staging
I0113 02:02:48.859091   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:48.859108   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:48.859123   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:48.861595   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:48.861620   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:48.861726   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1###default on /tmp/proxy-mount
I0113 02:02:48.861781   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:48.861845   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:48.861941   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1###default on /tmp/csi-staging successfully
I0113 02:02:48.861980   11052 utils.go:83] GRPC response: {}
... skipping 16 lines ...
    STEP: creating a volume 01/13/23 02:02:46.463
    STEP: creating a snapshot 01/13/23 02:02:47.089
    STEP: creating a snapshot with the same name and source volume ID 01/13/23 02:02:47.722
  << End Captured GinkgoWriter Output
------------------------------
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:1470
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:49.2
STEP: creating mount and staging directories 01/13/23 02:02:49.2
I0113 02:02:49.200675   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:02:49.200700   11052 utils.go:77] GRPC request: {}
I0113 02:02:49.200798   11052 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 19 lines ...
I0113 02:02:51.037143   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.404972359 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default" result_code="succeeded"
I0113 02:02:51.037157   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default"}}
STEP: creating a snapshot with the same name but different source volume ID 01/13/23 02:02:51.037
I0113 02:02:51.038280   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
I0113 02:02:51.038318   11052 utils.go:77] GRPC request: {"name":"CreateSnapshot-snapshot-2","source_volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default"}
I0113 02:02:51.573038   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.534564757 request="azurefile_csi_driver_controller_create_snapshot" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e" source="file.csi.azure.com" source_resource_id="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default" snapshot_name="CreateSnapshot-snapshot-2" result_code="failed_csi_driver_controller_create_snapshot"
E0113 02:02:51.573237   11052 utils.go:81] 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-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default)
I0113 02:02:51.574192   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:02:51.574257   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default"}
I0113 02:02:51.574378   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/csi-mount/target
I0113 02:02:51.574388   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:02:51.574454   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:02:51.574474   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/csi-mount/target successfully
I0113 02:02:51.574495   11052 utils.go:83] GRPC response: {}
I0113 02:02:51.575656   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:02:51.575784   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default"}
I0113 02:02:51.576062   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/csi-staging
I0113 02:02:51.576073   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:02:51.576111   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:02:51.576124   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:02:51.579741   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:02:51.579789   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:02:51.579932   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/proxy-mount
I0113 02:02:51.579954   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:02:51.579980   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:02:51.579989   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/csi-staging successfully
I0113 02:02:51.580005   11052 utils.go:83] GRPC response: {}
... skipping 33 lines ...
I0113 02:02:52.463146   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.288391295 request="azurefile_csi_driver_controller_delete_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-2###default" result_code="succeeded"
I0113 02:02:52.463165   11052 utils.go:83] GRPC response: {}
------------------------------
• [3.264 seconds]
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  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:1470

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:02:49.2
    STEP: creating mount and staging directories 01/13/23 02:02:49.2
    STEP: creating a snapshot 01/13/23 02:02:49.201
... skipping 10 lines ...
I0113 02:02:52.466685   11052 utils.go:77] GRPC request: {}
I0113 02:02:52.466751   11052 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}}}]}
STEP: creating a volume 01/13/23 02:02:52.467
I0113 02:02:52.468082   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:02:52.468110   11052 utils.go:77] GRPC request: {"capacity_range":{"limit_bytes":10737418240,"required_bytes":10737418240},"name":"CreateSnapshot-volume-3","volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
I0113 02:02:52.711184   11052 controllerserver.go:473] begin to create file share(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) type() subID() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() size(10) protocol(SMB)
W0113 02:02:53.993043   11052 azurefile.go:770] CreateFileShare(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) 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:f24d4814-001a-0043-2ff3-26cdca000000\nTime:2023-01-13T02:02:54.1096943Z"), waiting for retrying
W0113 02:03:03.106623   11052 azurefile.go:770] CreateFileShare(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) 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:f24d4829-001a-0043-36f3-26cdca000000\nTime:2023-01-13T02:03:03.2464845Z"), waiting for retrying
W0113 02:03:18.637553   11052 azurefile.go:770] CreateFileShare(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) 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:f24d4860-001a-0043-3df3-26cdca000000\nTime:2023-01-13T02:03:18.7726317Z"), waiting for retrying
I0113 02:03:37.633442   11052 controllerserver.go:495] create file share createsnapshot-volume-3 on storage account f40d6e94b7ea84eb2a8bcd4 successfully
W0113 02:03:37.633500   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:03:37.633560   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=44.922379727 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default" result_code="succeeded"
I0113 02:03:37.633587   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default"}}
STEP: creating a snapshot 01/13/23 02:03:37.634
I0113 02:03:37.634855   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
... skipping 29 lines ...
I0113 02:03:39.211583   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:03:39.212168   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default"}
I0113 02:03:39.212368   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/csi-staging
I0113 02:03:39.212971   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:03:39.213009   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:03:39.213022   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:03:39.217327   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:03:39.217363   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:03:39.217485   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/proxy-mount
I0113 02:03:39.217504   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:03:39.217533   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:03:39.217542   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3###default on /tmp/csi-staging successfully
I0113 02:03:39.217558   11052 utils.go:83] GRPC response: {}
... skipping 16 lines ...
    STEP: creating a volume 01/13/23 02:02:52.467
    STEP: creating a snapshot 01/13/23 02:03:37.634
  << End Captured GinkgoWriter Output
------------------------------

Ran 50 of 78 Specs in 101.754 seconds
SUCCESS! -- 50 Passed | 0 Failed | 1 Pending | 27 Skipped
[38;5;228mYou're using deprecated Ginkgo functionality:[0m
[38;5;228m=============================================[0m
  [38;5;11m--ginkgo.noColor is deprecated, use --ginkgo.no-color instead[0m
  [1mLearn more at:[0m [38;5;14m[4mhttps://onsi.github.io/ginkgo/MIGRATING_TO_V2#changed-command-line-flags[0m

[38;5;243mTo silence deprecations that can be silenced set the following environment variable:[0m
... skipping 8 lines ...
------------------------------
Controller Service [Controller Server] ControllerGetCapabilities
  should return appropriate capabilities
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:124
STEP: connecting to CSI driver 01/13/23 02:03:39.6
------------------------------
• [FAILED] [60.003 seconds]
Controller Service [Controller Server] [BeforeEach]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:46
  ControllerGetCapabilities
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:123
    should return appropriate capabilities
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:124

  Begin Captured GinkgoWriter Output >>
    STEP: connecting to CSI driver 01/13/23 02:03:39.6
  << End Captured GinkgoWriter Output

  Unexpected error:
      <*errors.errorString | 0xc000472780>: {
          s: "Connection timed out",
      }
      Connection timed out
  occurred
  In [BeforeEach] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/sanity.go:265
... skipping 46 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/13/23 02:04:39.611
STEP: creating mount and staging directories 01/13/23 02:04:39.611
I0113 02:04:39.611866   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:39.611883   11052 utils.go:77] GRPC request: {}
I0113 02:04:39.611921   11052 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.001 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/13/23 02:04:39.611
    STEP: creating mount and staging directories 01/13/23 02:04:39.611
  << 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/13/23 02:04:39.614
STEP: creating mount and staging directories 01/13/23 02:04:39.614
I0113 02:04:39.615159   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:39.615176   11052 utils.go:77] GRPC request: {}
I0113 02:04:39.615212   11052 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}}}]}
I0113 02:04:39.615988   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:04:39.616010   11052 utils.go:77] GRPC request: {"parameters":{"fstype":"ext4"}}
E0113 02:04:39.616082   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name 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 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/13/23 02:04:39.614
    STEP: creating mount and staging directories 01/13/23 02:04:39.614
  << 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/13/23 02:04:39.616
STEP: creating mount and staging directories 01/13/23 02:04:39.616
I0113 02:04:39.617175   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:39.617191   11052 utils.go:77] GRPC request: {}
I0113 02:04:39.617235   11052 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}}}]}
I0113 02:04:39.617725   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:04:39.617745   11052 utils.go:77] GRPC request: {"name":"sanity-controller-create-no-volume-capabilities-C81B671A-D6A2D1E3","parameters":{"fstype":"ext4"}}
E0113 02:04:39.617828   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities not valid: CreateVolume Volume capabilities must be provided
------------------------------
• [0.001 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/13/23 02:04:39.616
    STEP: creating mount and staging directories 01/13/23 02:04:39.616
  << End Captured GinkgoWriter Output
... skipping 28 lines ...
I0113 02:04:40.847690   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:40.847775   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:40.847875   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:40.847886   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:40.847910   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:40.847947   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:40.850515   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:40.850639   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:40.850778   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:40.850802   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:40.850827   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:40.850838   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-no-capacity-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:40.850852   11052 utils.go:83] GRPC response: {}
... skipping 46 lines ...
I0113 02:04:41.985656   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:41.985722   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:41.985777   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:41.985797   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:41.985822   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:41.985832   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:41.988974   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:41.988997   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:41.989084   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:41.989147   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:41.989195   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:41.989205   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3#sanity-controller-create-single-with-capacity-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:41.989219   11052 utils.go:83] GRPC response: {}
... skipping 16 lines ...
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:41.217
    STEP: creating mount and staging directories 01/13/23 02:04:41.218
    STEP: creating a volume 01/13/23 02:04:41.219
  << 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/13/23 02:04:42.325
STEP: creating mount and staging directories 01/13/23 02:04:42.325
I0113 02:04:42.325716   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:42.325733   11052 utils.go:77] GRPC request: {}
I0113 02:04:42.325776   11052 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 26 lines ...
I0113 02:04:43.689527   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:43.689551   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-c81b671a-d6a2d1e3#sanity-controller-create-twice-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:43.689601   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-c81b671a-d6a2d1e3#sanity-controller-create-twice-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:43.689608   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:43.689625   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:43.689648   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:43.692157   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:43.692235   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:43.692342   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-c81b671a-d6a2d1e3#sanity-controller-create-twice-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:43.692449   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:43.692573   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:43.692599   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-c81b671a-d6a2d1e3#sanity-controller-create-twice-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:43.692615   11052 utils.go:83] GRPC response: {}
... skipping 29 lines ...
------------------------------
• [2.026 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/13/23 02:04:42.325
    STEP: creating mount and staging directories 01/13/23 02:04:42.325
    STEP: creating a volume 01/13/23 02:04:42.326
  << 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/13/23 02:04:44.351
STEP: creating mount and staging directories 01/13/23 02:04:44.351
I0113 02:04:44.353122   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:44.353151   11052 utils.go:77] GRPC request: {}
I0113 02:04:44.353209   11052 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 6 lines ...
I0113 02:04:45.115120   11052 controllerserver.go:516] create vhd file(sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd) size(10737418240) on share(sanity-controller-create-twice-different-c81b671a-d6a2d1e3) on account() type() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() successfully
W0113 02:04:45.115230   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:04:45.115319   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.495014133 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default" result_code="succeeded"
I0113 02:04:45.115365   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd","fstype":"ext4","secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default"}}
I0113 02:04:45.116259   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:04:45.116279   11052 utils.go:77] GRPC request: {"capacity_range":{"limit_bytes":21474836480,"required_bytes":21474836480},"name":"sanity-controller-create-twice-different-C81B671A-D6A2D1E3","parameters":{"fstype":"ext4"},"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
E0113 02:04:45.361938   11052 utils.go:81] GRPC error: rpc error: code = AlreadyExists desc = request file share(sanity-controller-create-twice-different-c81b671a-d6a2d1e3) already exists, but its capacity 10 is smaller than 20
I0113 02:04:45.362607   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:04:45.362625   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:45.362686   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-mount/target
I0113 02:04:45.362703   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:04:45.362913   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:04:45.362928   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-mount/target successfully
I0113 02:04:45.362936   11052 utils.go:83] GRPC response: {}
I0113 02:04:45.363741   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:45.363760   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:45.363803   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:45.363809   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:45.363825   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:45.363866   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:45.366354   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:45.366378   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:45.366458   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:45.366477   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:45.366496   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:45.366506   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-create-twice-different-c81b671a-d6a2d1e3#sanity-controller-create-twice-different-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:45.366517   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [1.368 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/13/23 02:04:44.351
    STEP: creating mount and staging directories 01/13/23 02:04:44.351
    STEP: creating a volume 01/13/23 02:04:44.353
  << 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/13/23 02:04:45.72
STEP: creating mount and staging directories 01/13/23 02:04:45.72
I0113 02:04:45.720694   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:45.720717   11052 utils.go:77] GRPC request: {}
I0113 02:04:45.720764   11052 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 17 lines ...
I0113 02:04:46.441603   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:46.441733   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd##default"}
I0113 02:04:46.441809   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd##default on /tmp/csi-staging
I0113 02:04:46.441816   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:46.441979   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:46.442048   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:46.444606   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:46.444635   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:46.444741   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd##default on /tmp/proxy-mount
I0113 02:04:46.444788   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:46.444839   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:46.444881   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa#aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.vhd##default on /tmp/csi-staging successfully
I0113 02:04:46.444918   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [1.073 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/13/23 02:04:45.72
    STEP: creating mount and staging directories 01/13/23 02:04:45.72
    STEP: creating a volume 01/13/23 02:04:45.721
... skipping 45 lines ...
I0113 02:04:48.802935   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:48.802974   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:48.803046   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:48.803073   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:48.803142   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:48.803555   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:48.805986   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:48.806033   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:48.806119   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:48.806179   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:48.806213   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:48.806264   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3#sanity-controller-vol-from-snap-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:48.806300   11052 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/13/23 02:04:49.825
STEP: creating mount and staging directories 01/13/23 02:04:49.825
I0113 02:04:49.825539   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:49.825553   11052 utils.go:77] GRPC request: {}
I0113 02:04:49.825599   11052 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/13/23 02:04:49.825
    STEP: creating mount and staging directories 01/13/23 02:04:49.825
  << 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/13/23 02:04:49.827
STEP: creating mount and staging directories 01/13/23 02:04:49.827
I0113 02:04:49.827580   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:49.827598   11052 utils.go:77] GRPC request: {}
I0113 02:04:49.827643   11052 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}}}]}
I0113 02:04:49.828152   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteVolume
I0113 02:04:49.828168   11052 utils.go:77] GRPC request: {}
E0113 02:04:49.828223   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.002 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/13/23 02:04:49.827
    STEP: creating mount and staging directories 01/13/23 02:04:49.827
  << End Captured GinkgoWriter Output
... skipping 5 lines ...
STEP: creating mount and staging directories 01/13/23 02:04:49.828
I0113 02:04:49.829285   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:49.829320   11052 utils.go:77] GRPC request: {}
I0113 02:04:49.829365   11052 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}}}]}
I0113 02:04:49.829918   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteVolume
I0113 02:04:49.829939   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id"}
E0113 02:04:49.830024   11052 controllerserver.go:594] GetFileShareInfo(fake-vol-id) in DeleteVolume failed with error: error parsing volume id: "fake-vol-id", should at least contain two #
I0113 02:04:49.830037   11052 utils.go:83] GRPC response: {}
------------------------------
• [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  DeleteVolume
... skipping 45 lines ...
    STEP: creating mount and staging directories 01/13/23 02:04:49.83
    STEP: creating a volume 01/13/23 02:04:49.831
    STEP: deleting a volume 01/13/23 02:04:50.488
  << 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/13/23 02:04:50.795
STEP: creating mount and staging directories 01/13/23 02:04:50.795
I0113 02:04:50.796279   11052 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0113 02:04:50.796300   11052 utils.go:77] GRPC request: {}
E0113 02:04:50.796379   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
------------------------------
• [0.001 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/13/23 02:04:50.795
    STEP: creating mount and staging directories 01/13/23 02:04:50.795
  << 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/13/23 02:04:50.796
STEP: creating mount and staging directories 01/13/23 02:04:50.796
STEP: creating a single node writer volume 01/13/23 02:04:50.797
I0113 02:04:50.797529   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:04:50.797653   11052 utils.go:77] GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"sanity-controller-validate-nocaps-C81B671A-D6A2D1E3","parameters":{"fstype":"ext4"},"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}]}
... skipping 3 lines ...
I0113 02:04:51.518604   11052 controllerserver.go:516] create vhd file(sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd) size(10737418240) on share(sanity-controller-validate-nocaps-c81b671a-d6a2d1e3) on account() type() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() successfully
W0113 02:04:51.518640   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:04:51.518708   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.494580087 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default" result_code="succeeded"
I0113 02:04:51.518722   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd","fstype":"ext4","secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default"}}
I0113 02:04:51.519387   11052 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0113 02:04:51.519404   11052 utils.go:77] GRPC request: {"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default"}
E0113 02:04:51.519485   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities not provided
I0113 02:04:51.519908   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:04:51.519928   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:51.519997   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-mount/target
I0113 02:04:51.520008   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:04:51.520057   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:04:51.520066   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-mount/target successfully
I0113 02:04:51.520081   11052 utils.go:83] GRPC response: {}
I0113 02:04:51.520934   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:51.520957   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:51.521032   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:51.521042   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:51.521063   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:51.521074   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:51.523475   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:51.523503   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:51.523603   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:51.523620   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:51.523645   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:51.523656   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3#sanity-controller-validate-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:51.523670   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [1.046 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/13/23 02:04:50.796
    STEP: creating mount and staging directories 01/13/23 02:04:50.796
    STEP: creating a single node writer volume 01/13/23 02:04:50.797
... skipping 28 lines ...
I0113 02:04:52.903796   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:52.903819   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-c81b671a-d6a2d1e3#sanity-controller-validate-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:04:52.903866   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-c81b671a-d6a2d1e3#sanity-controller-validate-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:04:52.903875   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:52.903909   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:52.903920   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:52.907170   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:52.907202   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:52.907346   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-c81b671a-d6a2d1e3#sanity-controller-validate-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:04:52.907366   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:52.907428   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:52.907447   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-controller-validate-c81b671a-d6a2d1e3#sanity-controller-validate-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:52.907463   11052 utils.go:83] GRPC response: {}
... skipping 17 lines ...
    STEP: creating mount and staging directories 01/13/23 02:04:51.844
    STEP: creating a single node writer volume 01/13/23 02:04:51.844
    STEP: validating volume capabilities 01/13/23 02:04:52.646
  << End Captured GinkgoWriter Output
------------------------------
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:825
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.2
STEP: creating mount and staging directories 01/13/23 02:04:53.2
I0113 02:04:53.200967   11052 utils.go:76] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities
I0113 02:04:53.201026   11052 utils.go:77] GRPC request: {"volume_capabilities":[{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}],"volume_id":"fake-vol-id-139b850f-4"}
W0113 02:04:53.201257   11052 azurefile.go:595] parsing volumeID(fake-vol-id-139b850f-4) return with error: error parsing volume id: "fake-vol-id-139b850f-4", should at least contain two #
E0113 02:04:53.201271   11052 utils.go:81] GRPC error: rpc error: code = NotFound desc = get account info from(fake-vol-id-139b850f-4) failed with error: <nil>
------------------------------
• [0.002 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 the requested volume does not exist
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:825

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.2
    STEP: creating mount and staging directories 01/13/23 02:04:53.2
  << End Captured GinkgoWriter Output
------------------------------
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:852
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.201
STEP: creating mount and staging directories 01/13/23 02:04:53.202
I0113 02:04:53.203147   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.203170   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.203222   11052 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}}}]}
I0113 02:04:53.204392   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0113 02:04:53.204415   11052 utils.go:77] GRPC request: {}
E0113 02:04:53.204485   11052 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
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:852

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.201
    STEP: creating mount and staging directories 01/13/23 02:04:53.202
  << End Captured GinkgoWriter Output
------------------------------
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:867
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.205
STEP: creating mount and staging directories 01/13/23 02:04:53.205
I0113 02:04:53.205750   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.205914   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.206006   11052 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}}}]}
I0113 02:04:53.207002   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0113 02:04:53.207111   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-238f317e-3"}
E0113 02:04:53.207208   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
------------------------------
• [0.003 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    should fail when no node id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:867

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.205
    STEP: creating mount and staging directories 01/13/23 02:04:53.205
  << End Captured GinkgoWriter Output
------------------------------
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:883
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.207
STEP: creating mount and staging directories 01/13/23 02:04:53.208
I0113 02:04:53.208481   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.208497   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.208537   11052 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}}}]}
I0113 02:04:53.209132   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerPublishVolume
I0113 02:04:53.209152   11052 utils.go:77] GRPC request: {"node_id":"fake-node-id-32906bc1-1","volume_id":"fake-vol-id-e43c3ade-5"}
E0113 02:04:53.209231   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
------------------------------
• [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    should fail when no volume capability is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:883

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.207
    STEP: creating mount and staging directories 01/13/23 02:04:53.208
  << End Captured GinkgoWriter Output
------------------------------
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:900
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.209
STEP: creating mount and staging directories 01/13/23 02:04:53.209
I0113 02:04:53.210311   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.210329   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.210367   11052 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.001 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    [It] 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:900

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

  testnodevolumeattachlimit not enabled
  In [It] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:902
------------------------------
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:1001
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.211
STEP: creating mount and staging directories 01/13/23 02:04:53.211
I0113 02:04:53.211661   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.211673   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.211704   11052 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
  ControllerPublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:845
    [It] 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:1001

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

  ControllerPublishVolume.readonly field not supported
  In [It] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1003
------------------------------
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:1079
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.213
STEP: creating mount and staging directories 01/13/23 02:04:53.213
I0113 02:04:53.213781   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.213805   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.213849   11052 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}}}]}
I0113 02:04:53.214430   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerUnpublishVolume
I0113 02:04:53.214455   11052 utils.go:77] GRPC request: {}
E0113 02:04:53.214526   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided
------------------------------
• [0.002 seconds]
Controller Service [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  ControllerUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1072
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1079

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.213
    STEP: creating mount and staging directories 01/13/23 02:04:53.213
  << End Captured GinkgoWriter Output
------------------------------
CreateSnapshot [Controller Server]
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1422
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.215
STEP: creating mount and staging directories 01/13/23 02:04:53.215
I0113 02:04:53.215685   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.215759   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.215812   11052 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}}}]}
I0113 02:04:53.216286   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
I0113 02:04:53.216305   11052 utils.go:77] GRPC request: {"source_volume_id":"testId"}
E0113 02:04:53.216355   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided
------------------------------
• [0.002 seconds]
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail when no name is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1422

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.215
    STEP: creating mount and staging directories 01/13/23 02:04:53.215
  << End Captured GinkgoWriter Output
------------------------------
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:1439
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.216
STEP: creating mount and staging directories 01/13/23 02:04:53.216
I0113 02:04:53.217204   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:53.217226   11052 utils.go:77] GRPC request: {}
I0113 02:04:53.217262   11052 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}}}]}
I0113 02:04:53.217772   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
I0113 02:04:53.217796   11052 utils.go:77] GRPC request: {"name":"name"}
E0113 02:04:53.217856   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided
------------------------------
• [0.001 seconds]
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail when no source volume id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1439

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:53.216
    STEP: creating mount and staging directories 01/13/23 02:04:53.216
  << End Captured GinkgoWriter Output
... skipping 51 lines ...
I0113 02:04:55.829774   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:55.829787   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1#createsnapshot-volume-1.vhd##default"}
I0113 02:04:55.829832   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1#createsnapshot-volume-1.vhd##default on /tmp/csi-staging
I0113 02:04:55.829838   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:55.829853   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:55.829861   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:55.832263   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:55.832280   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:55.832377   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1#createsnapshot-volume-1.vhd##default on /tmp/proxy-mount
I0113 02:04:55.832392   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:55.832408   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:55.832414   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-1#createsnapshot-volume-1.vhd##default on /tmp/csi-staging successfully
I0113 02:04:55.832426   11052 utils.go:83] GRPC response: {}
... skipping 16 lines ...
    STEP: creating a volume 01/13/23 02:04:53.219
    STEP: creating a snapshot 01/13/23 02:04:53.951
    STEP: creating a snapshot with the same name and source volume ID 01/13/23 02:04:54.571
  << End Captured GinkgoWriter Output
------------------------------
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:1470
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:56.145
STEP: creating mount and staging directories 01/13/23 02:04:56.145
I0113 02:04:56.146171   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:04:56.146187   11052 utils.go:77] GRPC request: {}
I0113 02:04:56.146258   11052 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 23 lines ...
I0113 02:04:58.239328   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.523272851 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default" result_code="succeeded"
I0113 02:04:58.239346   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"createsnapshot-volume-3.vhd","fstype":"ext4","secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default"}}
STEP: creating a snapshot with the same name but different source volume ID 01/13/23 02:04:58.239
I0113 02:04:58.240232   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateSnapshot
I0113 02:04:58.240256   11052 utils.go:77] GRPC request: {"name":"CreateSnapshot-snapshot-2","source_volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default"}
I0113 02:04:58.783572   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.543184175 request="azurefile_csi_driver_controller_create_snapshot" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e" source="file.csi.azure.com" source_resource_id="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default" snapshot_name="CreateSnapshot-snapshot-2" result_code="failed_csi_driver_controller_create_snapshot"
E0113 02:04:58.783612   11052 utils.go:81] 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-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default)
I0113 02:04:58.784174   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:04:58.784191   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default"}
I0113 02:04:58.784281   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/csi-mount/target
I0113 02:04:58.784289   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:04:58.784334   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:04:58.784345   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/csi-mount/target successfully
I0113 02:04:58.784363   11052 utils.go:83] GRPC response: {}
I0113 02:04:58.785352   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:04:58.785367   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default"}
I0113 02:04:58.785413   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/csi-staging
I0113 02:04:58.785420   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:04:58.785436   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:04:58.785447   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:04:58.787896   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:04:58.787919   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:04:58.788020   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/proxy-mount
I0113 02:04:58.788038   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:04:58.788058   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:04:58.788089   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/csi-staging successfully
I0113 02:04:58.788109   11052 utils.go:83] GRPC response: {}
... skipping 33 lines ...
I0113 02:04:59.756112   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.292710229 request="azurefile_csi_driver_controller_delete_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="0e46bd28-a80f-4d3a-8200-d9eb8d80cb2e" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-2#createsnapshot-volume-2.vhd##default" result_code="succeeded"
I0113 02:04:59.756131   11052 utils.go:83] GRPC response: {}
------------------------------
• [3.611 seconds]
CreateSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  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:1470

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:04:56.145
    STEP: creating mount and staging directories 01/13/23 02:04:56.145
    STEP: creating a snapshot 01/13/23 02:04:56.146
... skipping 10 lines ...
I0113 02:04:59.757847   11052 utils.go:77] GRPC request: {}
I0113 02:04:59.757974   11052 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}}}]}
STEP: creating a volume 01/13/23 02:04:59.758
I0113 02:04:59.758846   11052 utils.go:76] GRPC call: /csi.v1.Controller/CreateVolume
I0113 02:04:59.758867   11052 utils.go:77] 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}}]}
I0113 02:04:59.989117   11052 controllerserver.go:473] begin to create file share(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) type() subID() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() size(10) protocol(SMB)
W0113 02:05:01.161200   11052 azurefile.go:770] CreateFileShare(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) 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:f24d49e1-001a-0043-5ff3-26cdca000000\nTime:2023-01-13T02:05:01.2812550Z"), waiting for retrying
W0113 02:05:07.598771   11052 azurefile.go:770] CreateFileShare(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) 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:f24d49ef-001a-0043-64f3-26cdca000000\nTime:2023-01-13T02:05:07.7235903Z"), waiting for retrying
W0113 02:05:17.279640   11052 azurefile.go:770] CreateFileShare(createsnapshot-volume-3) on account(f40d6e94b7ea84eb2a8bcd4) 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:f24d4a25-001a-0043-6cf3-26cdca000000\nTime:2023-01-13T02:05:17.4070816Z"), waiting for retrying
I0113 02:05:35.823894   11052 controllerserver.go:495] create file share createsnapshot-volume-3 on storage account f40d6e94b7ea84eb2a8bcd4 successfully
I0113 02:05:35.824028   11052 controllerserver.go:511] begin to create vhd file(createsnapshot-volume-3.vhd) size(10737418240) on share(createsnapshot-volume-3) on account() type() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location()
I0113 02:05:35.934372   11052 controllerserver.go:516] create vhd file(createsnapshot-volume-3.vhd) size(10737418240) on share(createsnapshot-volume-3) on account() type() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() successfully
W0113 02:05:35.934574   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:05:35.935040   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=35.945951323 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default" result_code="succeeded"
I0113 02:05:35.935424   11052 utils.go:83] GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"diskname":"createsnapshot-volume-3.vhd","fstype":"ext4","secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default"}}
... skipping 31 lines ...
I0113 02:05:37.538424   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:05:37.538439   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default"}
I0113 02:05:37.538483   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/csi-staging
I0113 02:05:37.538490   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:05:37.538509   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:05:37.538530   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:05:37.540861   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:05:37.540883   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:05:37.540975   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/proxy-mount
I0113 02:05:37.540992   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:05:37.541014   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:05:37.541022   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#createsnapshot-volume-3#createsnapshot-volume-3.vhd##default on /tmp/csi-staging successfully
I0113 02:05:37.541034   11052 utils.go:83] GRPC response: {}
... skipping 53 lines ...
  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.918
    STEP: creating mount and staging directories 01/13/23 02:05:37.918
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:05:37.926
STEP: creating mount and staging directories 01/13/23 02:05:37.926
I0113 02:05:37.927416   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.927438   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.927478   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.928029   11052 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}}}]}
I0113 02:05:37.929341   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.929356   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.929389   11052 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}}}]}
I0113 02:05:37.929976   11052 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
I0113 02:05:37.929994   11052 utils.go:77] GRPC request: {}
E0113 02:05:37.930056   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodePublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:378
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:379

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.926
    STEP: creating mount and staging directories 01/13/23 02:05:37.926
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:05:37.931
STEP: creating mount and staging directories 01/13/23 02:05:37.931
I0113 02:05:37.931625   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.931795   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.931969   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.932763   11052 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}}}]}
I0113 02:05:37.934456   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.934478   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.934515   11052 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}}}]}
I0113 02:05:37.935126   11052 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
I0113 02:05:37.935144   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-c3f549d8-4"}
E0113 02:05:37.935214   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
------------------------------
• [0.005 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodePublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:378
    should fail when no target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:393

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.931
    STEP: creating mount and staging directories 01/13/23 02:05:37.931
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:05:37.936
STEP: creating mount and staging directories 01/13/23 02:05:37.936
I0113 02:05:37.936602   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.936658   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.936711   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.937173   11052 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}}}]}
I0113 02:05:37.938344   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.938599   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.938906   11052 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}}}]}
I0113 02:05:37.939588   11052 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
I0113 02:05:37.939696   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"fake-vol-id-4e588e47-8"}
E0113 02:05:37.939874   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodePublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:378
    should fail when no volume capability is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:408

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.936
    STEP: creating mount and staging directories 01/13/23 02:05:37.936
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:05:37.941
STEP: creating mount and staging directories 01/13/23 02:05:37.941
I0113 02:05:37.941555   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.941725   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.942013   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.942753   11052 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}}}]}
I0113 02:05:37.947028   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.947058   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.947103   11052 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}}}]}
I0113 02:05:37.947752   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:05:37.947773   11052 utils.go:77] GRPC request: {}
E0113 02:05:37.947823   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:426
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:427

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.941
    STEP: creating mount and staging directories 01/13/23 02:05:37.941
  << End Captured GinkgoWriter Output
------------------------------
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 01/13/23 02:05:37.948
STEP: creating mount and staging directories 01/13/23 02:05:37.948
I0113 02:05:37.949105   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.949126   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.949167   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.949684   11052 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}}}]}
I0113 02:05:37.951238   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.951258   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.951291   11052 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}}}]}
I0113 02:05:37.951703   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:05:37.951716   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-0c804523-2"}
E0113 02:05:37.951751   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnpublishVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:426
    should fail when no target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:439

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.948
    STEP: creating mount and staging directories 01/13/23 02:05:37.948
  << End Captured GinkgoWriter Output
------------------------------
S
------------------------------
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:525
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.952
STEP: creating mount and staging directories 01/13/23 02:05:37.952
I0113 02:05:37.952510   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.952520   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.952650   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.953026   11052 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}}}]}
I0113 02:05:37.953796   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.953806   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.953829   11052 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}}}]}
I0113 02:05:37.954371   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeStageVolume
I0113 02:05:37.954386   11052 utils.go:77] GRPC request: {"publish_context":{"device":"/dev/mock"},"staging_target_path":"/tmp/csi-staging","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}}}
E0113 02:05:37.954463   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.003 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeStageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:512
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:525

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.952
    STEP: creating mount and staging directories 01/13/23 02:05:37.952
  << End Captured GinkgoWriter Output
------------------------------
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:544
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.954
STEP: creating mount and staging directories 01/13/23 02:05:37.955
I0113 02:05:37.955393   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.955411   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.955455   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:37.955839   11052 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}}}]}
I0113 02:05:37.957127   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:37.957146   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.957180   11052 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}}}]}
I0113 02:05:37.957812   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeStageVolume
I0113 02:05:37.957826   11052 utils.go:77] GRPC request: {"publish_context":{"device":"/dev/mock"},"volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_id":"fake-vol-id-2fe6d9e8-f"}
E0113 02:05:37.957890   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeStageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:512
    should fail when no staging target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:544

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.954
    STEP: creating mount and staging directories 01/13/23 02:05:37.955
  << End Captured GinkgoWriter Output
------------------------------
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:563
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.958
STEP: creating mount and staging directories 01/13/23 02:05:37.958
I0113 02:05:37.959201   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:37.959217   11052 utils.go:77] GRPC request: {}
I0113 02:05:37.959256   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 13 lines ...
I0113 02:05:38.635451   11052 controllerserver.go:516] create vhd file(sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd) size(107374182400) on share(sanity-node-stage-nocaps-c81b671a-d6a2d1e3) on account() type() rg(azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6) location() successfully
W0113 02:05:38.635809   11052 azurefile.go:952] could not create secret: kubeClient is nil
I0113 02:05:38.636014   11052 azure_metrics.go:115] "Observed Request Latency" latency_seconds=0.445725792 request="azurefile_csi_driver_controller_create_volume" resource_group="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6" subscription_id="" source="file.csi.azure.com" volumeid="azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default" result_code="succeeded"
I0113 02:05:38.636130   11052 utils.go:83] GRPC response: {"volume":{"volume_context":{"diskname":"sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd","fstype":"ext4","secretnamespace":"default"},"volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default"}}
I0113 02:05:38.636934   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeStageVolume
I0113 02:05:38.637072   11052 utils.go:77] GRPC request: {"publish_context":{"device":"/dev/mock"},"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default"}
E0113 02:05:38.637288   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided
I0113 02:05:38.637914   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0113 02:05:38.637929   11052 utils.go:77] GRPC request: {"target_path":"/tmp/csi-mount/target","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:05:38.637960   11052 nodeserver.go:132] NodeUnpublishVolume: unmounting volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-mount/target
I0113 02:05:38.637967   11052 azure_common_linux.go:41] force unmount on /tmp/csi-mount/target
W0113 02:05:38.638007   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/csi-mount/target
I0113 02:05:38.638014   11052 nodeserver.go:136] NodeUnpublishVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-mount/target successfully
I0113 02:05:38.638022   11052 utils.go:83] GRPC response: {}
I0113 02:05:38.639000   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:05:38.639018   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default"}
I0113 02:05:38.639060   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging
I0113 02:05:38.639069   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:05:38.639090   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:05:38.639099   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:05:38.642180   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:05:38.642208   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:05:38.642336   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/proxy-mount
I0113 02:05:38.642367   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:05:38.642392   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:05:38.642408   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#sanity-node-stage-nocaps-c81b671a-d6a2d1e3#sanity-node-stage-nocaps-c81b671a-d6a2d1e3.vhd##default on /tmp/csi-staging successfully
I0113 02:05:38.642421   11052 utils.go:83] GRPC response: {}
... skipping 6 lines ...
------------------------------
• [0.965 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeStageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:512
    should fail when no volume capability is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:563

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:37.958
    STEP: creating mount and staging directories 01/13/23 02:05:37.958
    STEP: creating a single node writer volume 01/13/23 02:05:37.961
  << End Captured GinkgoWriter Output
------------------------------
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:614
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.923
STEP: creating mount and staging directories 01/13/23 02:05:38.923
I0113 02:05:38.924395   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.924420   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.924476   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:38.925109   11052 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}}}]}
I0113 02:05:38.926837   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.926852   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.926887   11052 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}}}]}
I0113 02:05:38.927253   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:05:38.927269   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging"}
E0113 02:05:38.927315   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnstageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:607
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:614

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.923
    STEP: creating mount and staging directories 01/13/23 02:05:38.923
  << End Captured GinkgoWriter Output
------------------------------
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:628
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.927
STEP: creating mount and staging directories 01/13/23 02:05:38.928
I0113 02:05:38.928455   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.928469   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.928498   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 2 lines ...
I0113 02:05:38.929181   11052 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}}}]}
I0113 02:05:38.930499   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.930521   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.930561   11052 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}}}]}
I0113 02:05:38.930962   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:05:38.930976   11052 utils.go:77] GRPC request: {"volume_id":"fake-vol-id-d2515af5-e"}
E0113 02:05:38.931012   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeUnstageVolume
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:607
    should fail when no staging target path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:628

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.927
    STEP: creating mount and staging directories 01/13/23 02:05:38.928
  << End Captured GinkgoWriter Output
------------------------------
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:650
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.932
STEP: creating mount and staging directories 01/13/23 02:05:38.933
I0113 02:05:38.935719   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.935777   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.935836   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0113 02:05:38.936345   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.936362   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.936427   11052 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}}}]}
I0113 02:05:38.937742   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.937764   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.937804   11052 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}}}]}
E0113 02:05:38.938389   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = NodeGetVolumeStats volume ID was empty
------------------------------
• [0.007 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:650

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.932
    STEP: creating mount and staging directories 01/13/23 02:05:38.933
  << End Captured GinkgoWriter Output
------------------------------
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:664
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.939
STEP: creating mount and staging directories 01/13/23 02:05:38.939
I0113 02:05:38.939618   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.939651   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.939692   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0113 02:05:38.940051   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.940070   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.940107   11052 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}}}]}
I0113 02:05:38.941172   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.941320   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.941412   11052 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}}}]}
E0113 02:05:38.941977   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = NodeGetVolumeStats volume path was empty
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    should fail when no volume path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:664

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.939
    STEP: creating mount and staging directories 01/13/23 02:05:38.939
  << End Captured GinkgoWriter Output
------------------------------
Node Service NodeGetVolumeStats
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:678
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.942
STEP: creating mount and staging directories 01/13/23 02:05:38.943
I0113 02:05:38.943565   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.943587   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.943629   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
I0113 02:05:38.944131   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.944199   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.944247   11052 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}}}]}
I0113 02:05:38.945736   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.945759   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.945805   11052 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}}}]}
E0113 02:05:38.946514   11052 utils.go:81] GRPC error: rpc error: code = NotFound desc = path some/path does not exist
------------------------------
• [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeGetVolumeStats
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:643
    should fail when volume is not found
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:678

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.942
    STEP: creating mount and staging directories 01/13/23 02:05:38.943
  << End Captured GinkgoWriter Output
------------------------------
S
------------------------------
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:740
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.947
STEP: creating mount and staging directories 01/13/23 02:05:38.947
I0113 02:05:38.947664   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.947857   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.947919   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...
------------------------------
S [SKIPPED] [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeExpandVolume [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:733
    should fail when no volume id is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:740

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

  NodeExpandVolume not supported
  In [BeforeEach] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:735
------------------------------
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:755
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.951
STEP: creating mount and staging directories 01/13/23 02:05:38.951
I0113 02:05:38.951761   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.951781   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.951823   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...
------------------------------
S [SKIPPED] [0.004 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeExpandVolume [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:733
    should fail when no volume path is provided
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:755

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

  NodeExpandVolume not supported
  In [BeforeEach] at: /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:735
------------------------------
Node Service NodeExpandVolume
  should fail when volume is not found
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:774
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.954
STEP: creating mount and staging directories 01/13/23 02:05:38.955
I0113 02:05:38.955521   11052 utils.go:76] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0113 02:05:38.955537   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.955577   11052 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}}]}
... skipping 6 lines ...
------------------------------
S [SKIPPED] [0.003 seconds]
Node Service
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  NodeExpandVolume [BeforeEach]
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:733
    should fail when volume is not found
    /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:774

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.954
    STEP: creating mount and staging directories 01/13/23 02:05:38.955
  << End Captured GinkgoWriter Output
... skipping 231 lines ...
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.975
    STEP: creating mount and staging directories 01/13/23 02:05:38.976
    STEP: verifying name size and characters 01/13/23 02:05:38.977
  << End Captured GinkgoWriter Output
------------------------------
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:1366
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.978
STEP: creating mount and staging directories 01/13/23 02:05:38.978
I0113 02:05:38.978727   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.978759   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.978817   11052 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}}}]}
I0113 02:05:38.979344   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0113 02:05:38.979368   11052 utils.go:77] GRPC request: {}
E0113 02:05:38.979433   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided
------------------------------
• [0.002 seconds]
DeleteSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail when no snapshot id is provided
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1366

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:38.978
    STEP: creating mount and staging directories 01/13/23 02:05:38.978
  << End Captured GinkgoWriter Output
... skipping 5 lines ...
STEP: creating mount and staging directories 01/13/23 02:05:38.98
I0113 02:05:38.980638   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:38.980709   11052 utils.go:77] GRPC request: {}
I0113 02:05:38.980808   11052 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}}}]}
I0113 02:05:38.981550   11052 utils.go:76] GRPC call: /csi.v1.Controller/DeleteSnapshot
I0113 02:05:38.981627   11052 utils.go:77] GRPC request: {"snapshot_id":"reallyfakesnapshotid"}
I0113 02:05:38.981916   11052 controllerserver.go:934] failed to get share url with (reallyfakesnapshotid): error parsing volume id: "reallyfakesnapshotid", should at least contain two #, returning with success
I0113 02:05:38.981938   11052 utils.go:83] GRPC response: {}
------------------------------
• [0.003 seconds]
DeleteSnapshot [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should succeed when an invalid snapshot id is used
... skipping 44 lines ...
I0113 02:05:40.479037   11052 utils.go:76] GRPC call: /csi.v1.Node/NodeUnstageVolume
I0113 02:05:40.479064   11052 utils.go:77] GRPC request: {"staging_target_path":"/tmp/csi-staging","volume_id":"azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1#deletesnapshot-volume-1.vhd##default"}
I0113 02:05:40.479124   11052 nodeserver.go:387] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1#deletesnapshot-volume-1.vhd##default on /tmp/csi-staging
I0113 02:05:40.479143   11052 azure_common_linux.go:41] force unmount on /tmp/csi-staging
I0113 02:05:40.479164   11052 mount_helper_common.go:56] unmounting "/tmp/csi-staging" (corruptedMount: false, mounterCanSkipMountPointChecks: true)
I0113 02:05:40.479181   11052 mount_linux.go:779] Unmounting /tmp/csi-staging
I0113 02:05:40.481930   11052 mount_linux.go:794] ignoring 'not mounted' error for /tmp/csi-staging
I0113 02:05:40.481960   11052 mount_helper_common.go:150] Warning: deleting path "/tmp/csi-staging"
I0113 02:05:40.482088   11052 nodeserver.go:393] NodeUnstageVolume: CleanupMountPoint volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1#deletesnapshot-volume-1.vhd##default on /tmp/proxy-mount
I0113 02:05:40.482111   11052 azure_common_linux.go:41] force unmount on /tmp/proxy-mount
W0113 02:05:40.482136   11052 mount_helper_common.go:47] Warning: mount cleanup skipped because path does not exist: /tmp/proxy-mount
I0113 02:05:40.482150   11052 nodeserver.go:397] NodeUnstageVolume: unmount volume azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6#f40d6e94b7ea84eb2a8bcd4#deletesnapshot-volume-1#deletesnapshot-volume-1.vhd##default on /tmp/csi-staging successfully
I0113 02:05:40.482166   11052 utils.go:83] GRPC response: {}
... skipping 15 lines ...
    STEP: creating mount and staging directories 01/13/23 02:05:38.982
    STEP: creating a volume 01/13/23 02:05:38.984
    STEP: creating a snapshot 01/13/23 02:05:39.629
  << End Captured GinkgoWriter Output
------------------------------
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:1528
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:40.826
STEP: creating mount and staging directories 01/13/23 02:05:40.826
I0113 02:05:40.826913   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:40.826940   11052 utils.go:77] GRPC request: {}
I0113 02:05:40.827005   11052 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}}}]}
I0113 02:05:40.827960   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerExpandVolume
I0113 02:05:40.828061   11052 utils.go:77] GRPC request: {"capacity_range":{"required_bytes":11811160064}}
E0113 02:05:40.828179   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.003 seconds]
ExpandVolume [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail if no volume id is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1528

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:40.826
    STEP: creating mount and staging directories 01/13/23 02:05:40.826
  << End Captured GinkgoWriter Output
------------------------------
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:1545
STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:40.828
STEP: creating mount and staging directories 01/13/23 02:05:40.829
I0113 02:05:40.830291   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0113 02:05:40.830451   11052 utils.go:77] GRPC request: {}
I0113 02:05:40.830642   11052 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}}}]}
I0113 02:05:40.831474   11052 utils.go:76] GRPC call: /csi.v1.Controller/ControllerExpandVolume
I0113 02:05:40.831492   11052 utils.go:77] GRPC request: {}
E0113 02:05:40.831926   11052 utils.go:81] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request
------------------------------
• [0.003 seconds]
ExpandVolume [Controller Server]
/home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:45
  should fail if no capacity range is given
  /home/prow/go/src/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1545

  Begin Captured GinkgoWriter Output >>
    STEP: reusing connection to CSI driver at unix:///tmp/csi.sock 01/13/23 02:05:40.828
    STEP: creating mount and staging directories 01/13/23 02:05:40.829
  << End Captured GinkgoWriter Output
------------------------------
S

Ran 47 of 78 Specs in 121.246 seconds
FAIL! -- 46 Passed | 1 Failed | 1 Pending | 30 Skipped
[38;5;228mYou're using deprecated Ginkgo functionality:[0m
[38;5;228m=============================================[0m
  [38;5;11m--ginkgo.noColor is deprecated, use --ginkgo.no-color instead[0m
  [1mLearn more at:[0m [38;5;14m[4mhttps://onsi.github.io/ginkgo/MIGRATING_TO_V2#changed-command-line-flags[0m

[38;5;243mTo silence deprecations that can be silenced set the following environment variable:[0m
  [38;5;243mACK_GINKGO_DEPRECATIONS=2.1.4[0m

pkill -f azurefileplugin
Deleting CSI sanity test binary
    sanity_test.go:88: Sanity test failed exit status 1
2023/01/13 02:05:40 Deleting resource group azurefile-csi-driver-test-1f91bf47-92e6-11ed-bbb2-f6b089654ee6
--- FAIL: TestSanity (283.28s)
FAIL
FAIL	sigs.k8s.io/azurefile-csi-driver/test/sanity	283.293s
FAIL
make: *** [Makefile:77: sanity-test] Error 1
+ EXIT_VALUE=2
+ set +o xtrace
PR	andyzhangx: doc: cut v1.25.0 release
Result	FAILURE
Tests	0 failed / 0 succeeded
Started	2023-01-13 01:56
Elapsed	8m44s
Revision	6574dfc37fae57a148279b940df9dedaac7a6567
Refs	1162
kubernetes-sigs/azurefile-csi-driver PR #1162 pull-azurefile-csi-driver-sanity #1613716618238496768

No Test Failures!

Error lines from build-log.txt
