This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: feat: enable fsGroup for nfs
ResultABORTED
Tests 0 failed / 31 succeeded
Started2021-09-15 12:56
Elapsed38m23s
Revision55967526d1fb964e83becbbbd46665369e3abd75
Refs 759

No Test Failures!


Show 31 Passed Tests

Show 3 Skipped Tests

Error lines from build-log.txt

... skipping 70 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11248  100 11248    0     0   196k      0 --:--:-- --:--:-- --:--:--  196k
Downloading https://get.helm.sh/helm-v3.6.3-linux-amd64.tar.gz
Verifying checksum... Done.
Preparing to install helm into /usr/local/bin
helm installed into /usr/local/bin/helm
docker pull k8sprow.azurecr.io/azurefile-csi:e2e-8dca11c54803a75627c0908c73283ddc856cfa65 || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/azurefile-csi:e2e-8dca11c54803a75627c0908c73283ddc856cfa65 not found: manifest unknown: manifest tagged by "e2e-8dca11c54803a75627c0908c73283ddc856cfa65" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/azurefile-csi-driver'
CGO_ENABLED=0 GOOS=windows go build -a -ldflags "-X sigs.k8s.io/azurefile-csi-driver/pkg/azurefile.driverVersion=e2e-8dca11c54803a75627c0908c73283ddc856cfa65 -X sigs.k8s.io/azurefile-csi-driver/pkg/azurefile.gitCommit=8dca11c54803a75627c0908c73283ddc856cfa65 -X sigs.k8s.io/azurefile-csi-driver/pkg/azurefile.buildDate=2021-09-15T13:00:13Z -s -w -extldflags '-static'" -mod vendor -o _output/amd64/azurefileplugin.exe ./pkg/azurefileplugin
docker buildx rm container-builder || true
error: no builder "container-builder" found
docker buildx create --use --name=container-builder
container-builder
# enable qemu for arm64 build
# https://github.com/docker/buildx/issues/464#issuecomment-741507760
docker run --privileged --rm tonistiigi/binfmt --uninstall qemu-aarch64
Unable to find image 'tonistiigi/binfmt:latest' locally
... skipping 1710 lines ...
                    type: string
                type: object
              volumeSnapshotClassName:
                description: 'volumeSnapshotClassName is the name of the VolumeSnapshotClass
                  requested by the VolumeSnapshot. If not specified, the default snapshot
                  class will be used if one exists. If not specified, and there is
                  no default snapshot class, dynamic snapshot creation will fail.
                  Empty string is not allowed for this field. TODO(xiangqian): a webhook
                  validation on empty string. More info: https://kubernetes.io/docs/concepts/storage/volume-snapshot-classes'
                type: string
            required:
            - source
            type: object
... skipping 22 lines ...
                  pre-existing snapshot, this field will be filled with the "creation_time"
                  value returned from the CSI "ListSnapshots" gRPC call if the driver
                  supports it. If not specified, it indicates that the creation time
                  of the snapshot is unknown.
                format: date-time
                type: string
              error:
                description: error is the last observed error during snapshot creation,
                  if any. This field could be helpful to upper level controllers(i.e.,
                  application controller) to decide whether they should continue on
                  waiting for the snapshot to be created based on the type of error
                  reported.
                properties:
                  message:
                    description: 'message is a string detailing the encountered error
                      during snapshot creation if specified. NOTE: message may be
                      logged, and it should not contain sensitive information.'
                    type: string
                  time:
                    description: time is the timestamp when the error was encountered.
                    format: date-time
                    type: string
                type: object
              readyToUse:
                description: readyToUse indicates if a snapshot is ready to be used
                  to restore a volume. In dynamic snapshot creation case, this field
... skipping 10 lines ...
                  in bytes. In dynamic snapshot creation case, this field will be
                  filled in with the "size_bytes" value returned from CSI "CreateSnapshotRequest"
                  gRPC call. For a pre-existing snapshot, this field will be filled
                  with the "size_bytes" value returned from the CSI "ListSnapshots"
                  gRPC call if the driver supports it. When restoring a volume from
                  this snapshot, the size of the volume MUST NOT be smaller than the
                  restoreSize if it is specified, otherwise the restoration will fail.
                  If not specified, it indicates that the size is unknown.
                pattern: ^(\+|-)?(([0-9]+(\.[0-9]*)?)|(\.[0-9]+))(([KMGTPE]i)|[numkMGTPE]|([eE](\+|-)?(([0-9]+(\.[0-9]*)?)|(\.[0-9]+))))?$
                x-kubernetes-int-or-string: true
            type: object
        required:
        - spec
... skipping 265 lines ...
                  supports it. If not specified, it indicates the creation time is
                  unknown. The format of this field is a Unix nanoseconds time encoded
                  as an int64. On Unix, the command `date +%s%N` returns the current
                  time in nanoseconds since 1970-01-01 00:00:00 UTC.
                format: int64
                type: integer
              error:
                description: error is the latest observed error during snapshot creation,
                  if any.
                properties:
                  message:
                    description: 'message is a string detailing the encountered error
                      during snapshot creation if specified. NOTE: message may be
                      logged, and it should not contain sensitive information.'
                    type: string
                  time:
                    description: time is the timestamp when the error was encountered.
                    format: date-time
                    type: string
                type: object
              readyToUse:
                description: readyToUse indicates if a snapshot is ready to be used
                  to restore a volume. In dynamic snapshot creation case, this field
... skipping 9 lines ...
                  in bytes. In dynamic snapshot creation case, this field will be
                  filled in with the "size_bytes" value returned from CSI "CreateSnapshotRequest"
                  gRPC call. For a pre-existing snapshot, this field will be filled
                  with the "size_bytes" value returned from the CSI "ListSnapshots"
                  gRPC call if the driver supports it. When restoring a volume from
                  this snapshot, the size of the volume MUST NOT be smaller than the
                  restoreSize if it is specified, otherwise the restoration will fail.
                  If not specified, it indicates that the size is unknown.
                format: int64
                minimum: 0
                type: integer
              snapshotHandle:
                description: snapshotHandle is the CSI "snapshot_id" of a snapshot
                  on the underlying storage system. If not specified, it indicates
                  that dynamic snapshot creation has either failed or it is still
                  in progress.
                type: string
            type: object
        required:
        - spec
        type: object
... skipping 830 lines ...
        - name: csi-resizer
          image: "mcr.microsoft.com/oss/kubernetes-csi/csi-resizer:v1.3.0"
          args:
            - "-csi-address=$(ADDRESS)"
            - "-v=2"
            - "-leader-election"
            - '-handle-volume-inuse-error=false'
          env:
            - name: ADDRESS
              value: /csi/csi.sock
          imagePullPolicy: IfNotPresent
          volumeMounts:
            - name: socket-dir
... skipping 193 lines ...
Git Commit: N/A
Go Version: go1.17.1
Platform: linux/amd64

Streaming logs below:
STEP: Building a namespace api object, basename azurefile
W0915 13:11:18.480670   12631 azure.go:77] InitializeCloudFromSecret: failed to get cloud config from secret /: failed to get secret /: resource name may not be empty
I0915 13:11:18.482750   12631 driver.go:93] Enabling controller service capability: CREATE_DELETE_VOLUME
I0915 13:11:18.482981   12631 driver.go:93] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0915 13:11:18.483154   12631 driver.go:93] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0915 13:11:18.483279   12631 driver.go:93] Enabling controller service capability: EXPAND_VOLUME
I0915 13:11:18.483434   12631 driver.go:112] Enabling volume access mode: SINGLE_NODE_WRITER
I0915 13:11:18.483606   12631 driver.go:112] Enabling volume access mode: SINGLE_NODE_READER_ONLY
... skipping 21 lines ...
Sep 15 13:11:43.465: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-kx8t7] to have phase Bound
Sep 15 13:11:43.526: INFO: PersistentVolumeClaim pvc-kx8t7 found and phase=Bound (61.570909ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Sep 15 13:11:43.702: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-2m7lc" in namespace "azurefile-8081" to be "Error status code"
Sep 15 13:11:43.762: INFO: Pod "azurefile-volume-tester-2m7lc": Phase="Pending", Reason="", readiness=false. Elapsed: 59.950921ms
Sep 15 13:11:45.821: INFO: Pod "azurefile-volume-tester-2m7lc": Phase="Pending", Reason="", readiness=false. Elapsed: 2.118240045s
Sep 15 13:11:47.879: INFO: Pod "azurefile-volume-tester-2m7lc": Phase="Failed", Reason="", readiness=false. Elapsed: 4.176293535s
STEP: Saw pod failure
Sep 15 13:11:47.879: INFO: Pod "azurefile-volume-tester-2m7lc" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep 15 13:11:47.986: INFO: deleting Pod "azurefile-8081"/"azurefile-volume-tester-2m7lc"
Sep 15 13:11:48.045: INFO: Pod azurefile-volume-tester-2m7lc has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-2m7lc in namespace azurefile-8081
Sep 15 13:11:48.112: INFO: deleting PVC "azurefile-8081"/"pvc-kx8t7"
... skipping 37 lines ...
Sep 15 13:11:50.629: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-rqxwr] to have phase Bound
Sep 15 13:11:50.687: INFO: PersistentVolumeClaim pvc-rqxwr found and phase=Bound (57.596742ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:11:50.860: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-pz49j" in namespace "azurefile-1318" to be "Succeeded or Failed"
Sep 15 13:11:50.918: INFO: Pod "azurefile-volume-tester-pz49j": Phase="Pending", Reason="", readiness=false. Elapsed: 57.723999ms
Sep 15 13:11:52.978: INFO: Pod "azurefile-volume-tester-pz49j": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.117776437s
STEP: Saw pod success
Sep 15 13:11:52.978: INFO: Pod "azurefile-volume-tester-pz49j" satisfied condition "Succeeded or Failed"
STEP: setting up the PV
STEP: creating a PV
STEP: setting up the PVC
STEP: creating a PVC
STEP: waiting for PVC to be in phase "Bound"
Sep 15 13:11:53.096: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-9vz8q] to have phase Bound
Sep 15 13:11:53.154: INFO: PersistentVolumeClaim pvc-9vz8q found and phase=Bound (57.780504ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:11:53.329: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-k7ls8" in namespace "azurefile-1318" to be "Succeeded or Failed"
Sep 15 13:11:53.386: INFO: Pod "azurefile-volume-tester-k7ls8": Phase="Pending", Reason="", readiness=false. Elapsed: 57.045451ms
Sep 15 13:11:55.444: INFO: Pod "azurefile-volume-tester-k7ls8": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.115373924s
STEP: Saw pod success
Sep 15 13:11:55.444: INFO: Pod "azurefile-volume-tester-k7ls8" satisfied condition "Succeeded or Failed"
STEP: setting up the PV
STEP: creating a PV
STEP: setting up the PVC
STEP: creating a PVC
STEP: waiting for PVC to be in phase "Bound"
Sep 15 13:11:55.562: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-7dsrl] to have phase Bound
Sep 15 13:11:55.619: INFO: PersistentVolumeClaim pvc-7dsrl found and phase=Bound (57.486546ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:11:55.799: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-bw7nj" in namespace "azurefile-1318" to be "Succeeded or Failed"
Sep 15 13:11:55.860: INFO: Pod "azurefile-volume-tester-bw7nj": Phase="Pending", Reason="", readiness=false. Elapsed: 61.162059ms
Sep 15 13:11:57.919: INFO: Pod "azurefile-volume-tester-bw7nj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.120461098s
STEP: Saw pod success
Sep 15 13:11:57.919: INFO: Pod "azurefile-volume-tester-bw7nj" satisfied condition "Succeeded or Failed"
STEP: setting up the PV
STEP: creating a PV
STEP: setting up the PVC
STEP: creating a PVC
STEP: waiting for PVC to be in phase "Bound"
Sep 15 13:11:58.041: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-x7llc] to have phase Bound
Sep 15 13:11:58.101: INFO: PersistentVolumeClaim pvc-x7llc found and phase=Bound (59.122002ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:11:58.276: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-8hlwc" in namespace "azurefile-1318" to be "Succeeded or Failed"
Sep 15 13:11:58.333: INFO: Pod "azurefile-volume-tester-8hlwc": Phase="Pending", Reason="", readiness=false. Elapsed: 57.127396ms
Sep 15 13:12:00.392: INFO: Pod "azurefile-volume-tester-8hlwc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.116022143s
STEP: Saw pod success
Sep 15 13:12:00.392: INFO: Pod "azurefile-volume-tester-8hlwc" satisfied condition "Succeeded or Failed"
STEP: setting up the PV
STEP: creating a PV
STEP: setting up the PVC
STEP: creating a PVC
STEP: waiting for PVC to be in phase "Bound"
Sep 15 13:12:00.509: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-7kbrw] to have phase Bound
Sep 15 13:12:00.567: INFO: PersistentVolumeClaim pvc-7kbrw found and phase=Bound (57.850139ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:12:00.744: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-krwgj" in namespace "azurefile-1318" to be "Succeeded or Failed"
Sep 15 13:12:00.802: INFO: Pod "azurefile-volume-tester-krwgj": Phase="Pending", Reason="", readiness=false. Elapsed: 57.760522ms
Sep 15 13:12:02.861: INFO: Pod "azurefile-volume-tester-krwgj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.116235682s
STEP: Saw pod success
Sep 15 13:12:02.861: INFO: Pod "azurefile-volume-tester-krwgj" satisfied condition "Succeeded or Failed"
STEP: setting up the PV
STEP: creating a PV
STEP: setting up the PVC
STEP: creating a PVC
STEP: waiting for PVC to be in phase "Bound"
Sep 15 13:12:02.978: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-clbfr] to have phase Bound
Sep 15 13:12:03.037: INFO: PersistentVolumeClaim pvc-clbfr found and phase=Bound (58.636411ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:12:03.211: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-lft9b" in namespace "azurefile-1318" to be "Succeeded or Failed"
Sep 15 13:12:03.268: INFO: Pod "azurefile-volume-tester-lft9b": Phase="Pending", Reason="", readiness=false. Elapsed: 57.20059ms
Sep 15 13:12:05.328: INFO: Pod "azurefile-volume-tester-lft9b": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.116908598s
STEP: Saw pod success
Sep 15 13:12:05.328: INFO: Pod "azurefile-volume-tester-lft9b" satisfied condition "Succeeded or Failed"
Sep 15 13:12:05.328: INFO: deleting Pod "azurefile-1318"/"azurefile-volume-tester-lft9b"
Sep 15 13:12:05.388: INFO: Pod azurefile-volume-tester-lft9b has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-lft9b in namespace azurefile-1318
Sep 15 13:12:05.458: INFO: deleting PVC "azurefile-1318"/"pvc-clbfr"
Sep 15 13:12:05.458: INFO: Deleting PersistentVolumeClaim "pvc-clbfr"
... skipping 143 lines ...
Sep 15 13:12:12.438: INFO: PersistentVolumeClaim pvc-q25pw found but phase is Pending instead of Bound.
Sep 15 13:12:14.497: INFO: PersistentVolumeClaim pvc-q25pw found and phase=Bound (2.120088818s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:12:14.673: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-lnz25" in namespace "azurefile-3274" to be "Succeeded or Failed"
Sep 15 13:12:14.735: INFO: Pod "azurefile-volume-tester-lnz25": Phase="Pending", Reason="", readiness=false. Elapsed: 61.313121ms
Sep 15 13:12:16.795: INFO: Pod "azurefile-volume-tester-lnz25": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.122286497s
STEP: Saw pod success
Sep 15 13:12:16.796: INFO: Pod "azurefile-volume-tester-lnz25" satisfied condition "Succeeded or Failed"
Sep 15 13:12:16.796: INFO: deleting Pod "azurefile-3274"/"azurefile-volume-tester-lnz25"
Sep 15 13:12:16.856: INFO: Pod azurefile-volume-tester-lnz25 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-lnz25 in namespace azurefile-3274
Sep 15 13:12:16.920: INFO: deleting PVC "azurefile-3274"/"pvc-q25pw"
Sep 15 13:12:16.920: INFO: Deleting PersistentVolumeClaim "pvc-q25pw"
... skipping 33 lines ...
Sep 15 13:12:19.202: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-4xjj6] to have phase Bound
Sep 15 13:12:19.262: INFO: PersistentVolumeClaim pvc-4xjj6 found and phase=Bound (60.051335ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:12:19.437: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-dstpj" in namespace "azurefile-495" to be "Succeeded or Failed"
Sep 15 13:12:19.495: INFO: Pod "azurefile-volume-tester-dstpj": Phase="Pending", Reason="", readiness=false. Elapsed: 57.483743ms
Sep 15 13:12:21.553: INFO: Pod "azurefile-volume-tester-dstpj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.11592075s
STEP: Saw pod success
Sep 15 13:12:21.553: INFO: Pod "azurefile-volume-tester-dstpj" satisfied condition "Succeeded or Failed"
Sep 15 13:12:21.553: INFO: deleting Pod "azurefile-495"/"azurefile-volume-tester-dstpj"
Sep 15 13:12:21.613: INFO: Pod azurefile-volume-tester-dstpj has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-dstpj in namespace azurefile-495
Sep 15 13:12:21.677: INFO: deleting PVC "azurefile-495"/"pvc-4xjj6"
Sep 15 13:12:21.677: INFO: Deleting PersistentVolumeClaim "pvc-4xjj6"
... skipping 131 lines ...
Sep 15 13:14:30.927: INFO: PersistentVolumeClaim pvc-bg26w found but phase is Pending instead of Bound.
Sep 15 13:14:32.988: INFO: PersistentVolumeClaim pvc-bg26w found and phase=Bound (1m40.927134074s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:14:33.166: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-lkjv6" in namespace "azurefile-5541" to be "Succeeded or Failed"
Sep 15 13:14:33.226: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 59.878324ms
Sep 15 13:14:35.285: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 2.118231079s
Sep 15 13:14:37.345: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 4.178986548s
Sep 15 13:14:39.404: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 6.237418218s
Sep 15 13:14:41.463: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 8.296440817s
Sep 15 13:14:43.522: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 10.355424165s
... skipping 7 lines ...
Sep 15 13:14:59.976: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 26.809517074s
Sep 15 13:15:02.031: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 28.864235185s
Sep 15 13:15:04.086: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 30.919310061s
Sep 15 13:15:06.141: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Pending", Reason="", readiness=false. Elapsed: 32.974963372s
Sep 15 13:15:08.196: INFO: Pod "azurefile-volume-tester-lkjv6": Phase="Succeeded", Reason="", readiness=false. Elapsed: 35.030025184s
STEP: Saw pod success
Sep 15 13:15:08.197: INFO: Pod "azurefile-volume-tester-lkjv6" satisfied condition "Succeeded or Failed"
Sep 15 13:15:08.197: INFO: deleting Pod "azurefile-5541"/"azurefile-volume-tester-lkjv6"
Sep 15 13:15:08.274: INFO: Pod azurefile-volume-tester-lkjv6 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-lkjv6 in namespace azurefile-5541
Sep 15 13:15:08.339: INFO: deleting PVC "azurefile-5541"/"pvc-bg26w"
Sep 15 13:15:08.339: INFO: Deleting PersistentVolumeClaim "pvc-bg26w"
... skipping 34 lines ...
Sep 15 13:15:15.618: INFO: PersistentVolumeClaim pvc-9r4gk found but phase is Pending instead of Bound.
Sep 15 13:15:17.673: INFO: PersistentVolumeClaim pvc-9r4gk found and phase=Bound (2.109620328s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:15:17.837: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-7xnq8" in namespace "azurefile-5356" to be "Succeeded or Failed"
Sep 15 13:15:17.890: INFO: Pod "azurefile-volume-tester-7xnq8": Phase="Pending", Reason="", readiness=false. Elapsed: 53.504143ms
Sep 15 13:15:19.947: INFO: Pod "azurefile-volume-tester-7xnq8": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.110283165s
STEP: Saw pod success
Sep 15 13:15:19.947: INFO: Pod "azurefile-volume-tester-7xnq8" satisfied condition "Succeeded or Failed"
Sep 15 13:15:19.947: INFO: deleting Pod "azurefile-5356"/"azurefile-volume-tester-7xnq8"
Sep 15 13:15:20.010: INFO: Pod azurefile-volume-tester-7xnq8 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-7xnq8 in namespace azurefile-5356
Sep 15 13:15:20.072: INFO: deleting PVC "azurefile-5356"/"pvc-9r4gk"
Sep 15 13:15:20.072: INFO: Deleting PersistentVolumeClaim "pvc-9r4gk"
... skipping 129 lines ...
Sep 15 13:17:19.533: INFO: PersistentVolumeClaim pvc-tf8hn found but phase is Pending instead of Bound.
Sep 15 13:17:21.589: INFO: PersistentVolumeClaim pvc-tf8hn found and phase=Bound (24.714350655s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Sep 15 13:17:21.753: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-rml6x" in namespace "azurefile-4147" to be "Error status code"
Sep 15 13:17:21.807: INFO: Pod "azurefile-volume-tester-rml6x": Phase="Pending", Reason="", readiness=false. Elapsed: 53.703733ms
Sep 15 13:17:23.863: INFO: Pod "azurefile-volume-tester-rml6x": Phase="Pending", Reason="", readiness=false. Elapsed: 2.11041047s
Sep 15 13:17:25.919: INFO: Pod "azurefile-volume-tester-rml6x": Phase="Failed", Reason="", readiness=false. Elapsed: 4.165952423s
STEP: Saw pod failure
Sep 15 13:17:25.919: INFO: Pod "azurefile-volume-tester-rml6x" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep 15 13:17:25.978: INFO: deleting Pod "azurefile-4147"/"azurefile-volume-tester-rml6x"
Sep 15 13:17:26.034: INFO: Pod azurefile-volume-tester-rml6x has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-rml6x in namespace azurefile-4147
Sep 15 13:17:26.098: INFO: deleting PVC "azurefile-4147"/"pvc-tf8hn"
... skipping 189 lines ...
Sep 15 13:19:34.297: INFO: PersistentVolumeClaim pvc-fxmbl found but phase is Pending instead of Bound.
Sep 15 13:19:36.351: INFO: PersistentVolumeClaim pvc-fxmbl found and phase=Bound (2.108741133s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:19:36.516: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-hj7mc" in namespace "azurefile-5561" to be "Succeeded or Failed"
Sep 15 13:19:36.570: INFO: Pod "azurefile-volume-tester-hj7mc": Phase="Pending", Reason="", readiness=false. Elapsed: 53.735248ms
Sep 15 13:19:38.625: INFO: Pod "azurefile-volume-tester-hj7mc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.108767942s
STEP: Saw pod success
Sep 15 13:19:38.625: INFO: Pod "azurefile-volume-tester-hj7mc" satisfied condition "Succeeded or Failed"
STEP: resizing the pvc
STEP: sleep 30s waiting for resize complete
STEP: checking the resizing result
STEP: checking the resizing PV result
STEP: checking the resizing azurefile result
2021/09/15 13:20:08 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
... skipping 40 lines ...
Sep 15 13:20:13.289: INFO: PersistentVolumeClaim pvc-ffwcm found but phase is Pending instead of Bound.
Sep 15 13:20:15.343: INFO: PersistentVolumeClaim pvc-ffwcm found and phase=Bound (2.107854685s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:20:15.507: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-fczfd" in namespace "azurefile-4376" to be "Succeeded or Failed"
Sep 15 13:20:15.561: INFO: Pod "azurefile-volume-tester-fczfd": Phase="Pending", Reason="", readiness=false. Elapsed: 53.699273ms
Sep 15 13:20:17.616: INFO: Pod "azurefile-volume-tester-fczfd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109294769s
Sep 15 13:20:19.671: INFO: Pod "azurefile-volume-tester-fczfd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.163972967s
Sep 15 13:20:21.729: INFO: Pod "azurefile-volume-tester-fczfd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.221564626s
Sep 15 13:20:23.785: INFO: Pod "azurefile-volume-tester-fczfd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.277887043s
Sep 15 13:20:25.840: INFO: Pod "azurefile-volume-tester-fczfd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 10.332855321s
STEP: Saw pod success
Sep 15 13:20:25.840: INFO: Pod "azurefile-volume-tester-fczfd" satisfied condition "Succeeded or Failed"
Sep 15 13:20:25.840: INFO: deleting Pod "azurefile-4376"/"azurefile-volume-tester-fczfd"
Sep 15 13:20:25.896: INFO: Pod azurefile-volume-tester-fczfd has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-fczfd in namespace azurefile-4376
Sep 15 13:20:25.959: INFO: deleting PVC "azurefile-4376"/"pvc-ffwcm"
Sep 15 13:20:25.959: INFO: Deleting PersistentVolumeClaim "pvc-ffwcm"
... skipping 35 lines ...
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pod has 'FailedMount' event
Sep 15 13:20:32.192: INFO: deleting Pod "azurefile-1577"/"azurefile-volume-tester-ktqjj"
Sep 15 13:20:32.249: INFO: Error getting logs for pod azurefile-volume-tester-ktqjj: the server rejected our request for an unknown reason (get pods azurefile-volume-tester-ktqjj)
STEP: Deleting pod azurefile-volume-tester-ktqjj in namespace azurefile-1577
Sep 15 13:20:32.306: INFO: deleting PVC "azurefile-1577"/"pvc-fdgmc"
Sep 15 13:20:32.306: INFO: Deleting PersistentVolumeClaim "pvc-fdgmc"
STEP: waiting for claim's PV "pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff" to be deleted
Sep 15 13:20:32.478: INFO: Waiting up to 10m0s for PersistentVolume pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff to get deleted
Sep 15 13:20:32.532: INFO: PersistentVolume pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff found and phase=Bound (53.69154ms)
... skipping 34 lines ...
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pod has 'FailedMount' event
Sep 15 13:20:48.706: INFO: deleting Pod "azurefile-953"/"azurefile-volume-tester-wjsfx"
Sep 15 13:20:48.762: INFO: Error getting logs for pod azurefile-volume-tester-wjsfx: the server rejected our request for an unknown reason (get pods azurefile-volume-tester-wjsfx)
STEP: Deleting pod azurefile-volume-tester-wjsfx in namespace azurefile-953
Sep 15 13:20:48.828: INFO: deleting PVC "azurefile-953"/"pvc-h4qvk"
Sep 15 13:20:48.828: INFO: Deleting PersistentVolumeClaim "pvc-h4qvk"
STEP: waiting for claim's PV "pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742" to be deleted
Sep 15 13:20:48.994: INFO: Waiting up to 10m0s for PersistentVolume pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 to get deleted
Sep 15 13:20:49.049: INFO: PersistentVolume pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 found and phase=Bound (55.037735ms)
... skipping 118 lines ...
Sep 15 13:22:42.425: INFO: PersistentVolumeClaim pvc-8plp6 found but phase is Pending instead of Bound.
Sep 15 13:22:44.481: INFO: PersistentVolumeClaim pvc-8plp6 found and phase=Bound (2.109882186s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with an error
Sep 15 13:22:44.645: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-lz66r" in namespace "azurefile-9336" to be "Error status code"
Sep 15 13:22:44.699: INFO: Pod "azurefile-volume-tester-lz66r": Phase="Pending", Reason="", readiness=false. Elapsed: 53.540368ms
Sep 15 13:22:46.755: INFO: Pod "azurefile-volume-tester-lz66r": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109270292s
Sep 15 13:22:48.811: INFO: Pod "azurefile-volume-tester-lz66r": Phase="Failed", Reason="", readiness=false. Elapsed: 4.165168169s
STEP: Saw pod failure
Sep 15 13:22:48.811: INFO: Pod "azurefile-volume-tester-lz66r" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Sep 15 13:22:48.868: INFO: deleting Pod "azurefile-9336"/"azurefile-volume-tester-lz66r"
Sep 15 13:22:48.945: INFO: Pod azurefile-volume-tester-lz66r has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-lz66r in namespace azurefile-9336
Sep 15 13:22:49.007: INFO: deleting PVC "azurefile-9336"/"pvc-8plp6"
... skipping 199 lines ...
Sep 15 13:23:18.477: INFO: PersistentVolumeClaim pvc-pnml8 found but phase is Pending instead of Bound.
Sep 15 13:23:20.531: INFO: PersistentVolumeClaim pvc-pnml8 found and phase=Bound (2.108874553s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:23:20.694: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-n5g9j" in namespace "azurefile-8553" to be "Succeeded or Failed"
Sep 15 13:23:20.749: INFO: Pod "azurefile-volume-tester-n5g9j": Phase="Pending", Reason="", readiness=false. Elapsed: 54.433096ms
Sep 15 13:23:22.805: INFO: Pod "azurefile-volume-tester-n5g9j": Phase="Pending", Reason="", readiness=false. Elapsed: 2.110483s
Sep 15 13:23:24.860: INFO: Pod "azurefile-volume-tester-n5g9j": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.165760628s
STEP: Saw pod success
Sep 15 13:23:24.860: INFO: Pod "azurefile-volume-tester-n5g9j" satisfied condition "Succeeded or Failed"
Sep 15 13:23:24.860: INFO: deleting Pod "azurefile-8553"/"azurefile-volume-tester-n5g9j"
Sep 15 13:23:24.920: INFO: Pod azurefile-volume-tester-n5g9j has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-n5g9j in namespace azurefile-8553
Sep 15 13:23:24.989: INFO: deleting PVC "azurefile-8553"/"pvc-pnml8"
Sep 15 13:23:24.989: INFO: Deleting PersistentVolumeClaim "pvc-pnml8"
... skipping 74 lines ...
Sep 15 13:23:33.547: INFO: PersistentVolumeClaim pvc-ltvqf found but phase is Pending instead of Bound.
Sep 15 13:23:35.602: INFO: PersistentVolumeClaim pvc-ltvqf found and phase=Bound (2.109348134s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Sep 15 13:23:35.767: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-f5mkf" in namespace "azurefile-9267" to be "Succeeded or Failed"
Sep 15 13:23:35.821: INFO: Pod "azurefile-volume-tester-f5mkf": Phase="Pending", Reason="", readiness=false. Elapsed: 54.00513ms
Sep 15 13:23:37.877: INFO: Pod "azurefile-volume-tester-f5mkf": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.109685841s
STEP: Saw pod success
Sep 15 13:23:37.877: INFO: Pod "azurefile-volume-tester-f5mkf" satisfied condition "Succeeded or Failed"
STEP: creating volume snapshot class
STEP: setting up the VolumeSnapshotClass
STEP: creating a VolumeSnapshotClass
STEP: taking snapshots
STEP: creating a VolumeSnapshot for pvc-ltvqf
STEP: waiting for VolumeSnapshot to be ready to use - volume-snapshot-t5wfl
... skipping 31 lines ...
check the driver pods if restarts ...
======================================================================================
2021/09/15 13:23:54 Check successfully
Sep 15 13:23:54.966: INFO: >>> kubeConfig: /root/tmp1316207620/kubeconfig/kubeconfig.westus2.json
2021/09/15 13:23:54 run script: test/utils/get_storage_account_secret_name.sh
2021/09/15 13:23:55 got output: azure-storage-account-f42c264898221426bb69624-secret
, error: <nil>
2021/09/15 13:23:55 got storage account secret name: azure-storage-account-f42c264898221426bb69624-secret
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: waiting for PVC to be in phase "Bound"
Sep 15 13:23:55.397: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-gln78] to have phase Bound
Sep 15 13:23:55.450: INFO: PersistentVolumeClaim pvc-gln78 found but phase is Pending instead of Bound.
Sep 15 13:23:57.506: INFO: PersistentVolumeClaim pvc-gln78 found and phase=Bound (2.10871833s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:23:57.672: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-5sv5j" in namespace "azurefile-5802" to be "Succeeded or Failed"
Sep 15 13:23:57.731: INFO: Pod "azurefile-volume-tester-5sv5j": Phase="Pending", Reason="", readiness=false. Elapsed: 58.143235ms
Sep 15 13:23:59.786: INFO: Pod "azurefile-volume-tester-5sv5j": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.113838706s
STEP: Saw pod success
Sep 15 13:23:59.786: INFO: Pod "azurefile-volume-tester-5sv5j" satisfied condition "Succeeded or Failed"
Sep 15 13:23:59.786: INFO: deleting Pod "azurefile-5802"/"azurefile-volume-tester-5sv5j"
Sep 15 13:23:59.843: INFO: Pod azurefile-volume-tester-5sv5j has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-5sv5j in namespace azurefile-5802
Sep 15 13:23:59.902: INFO: deleting PVC "azurefile-5802"/"pvc-gln78"
Sep 15 13:23:59.902: INFO: Deleting PersistentVolumeClaim "pvc-gln78"
... skipping 32 lines ...
Sep 15 13:24:01.685: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-kfdrm] to have phase Bound
Sep 15 13:24:01.739: INFO: PersistentVolumeClaim pvc-kfdrm found and phase=Bound (54.346632ms)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:24:01.903: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-wfld5" in namespace "azurefile-493" to be "Succeeded or Failed"
Sep 15 13:24:01.957: INFO: Pod "azurefile-volume-tester-wfld5": Phase="Pending", Reason="", readiness=false. Elapsed: 54.028574ms
Sep 15 13:24:04.013: INFO: Pod "azurefile-volume-tester-wfld5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.109697608s
STEP: Saw pod success
Sep 15 13:24:04.013: INFO: Pod "azurefile-volume-tester-wfld5" satisfied condition "Succeeded or Failed"
Sep 15 13:24:04.013: INFO: deleting Pod "azurefile-493"/"azurefile-volume-tester-wfld5"
Sep 15 13:24:04.069: INFO: Pod azurefile-volume-tester-wfld5 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-wfld5 in namespace azurefile-493
Sep 15 13:24:04.141: INFO: deleting PVC "azurefile-493"/"pvc-kfdrm"
Sep 15 13:24:04.141: INFO: Deleting PersistentVolumeClaim "pvc-kfdrm"
... skipping 62 lines ...
check the driver pods if restarts ...
======================================================================================
2021/09/15 13:25:19 Check successfully
Sep 15 13:25:19.842: INFO: >>> kubeConfig: /root/tmp1316207620/kubeconfig/kubeconfig.westus2.json
2021/09/15 13:25:19 run script: test/utils/get_storage_account_secret_name.sh
2021/09/15 13:25:20 got output: azure-storage-account-f42c264898221426bb69624-secret
, error: <nil>
2021/09/15 13:25:20 got storage account secret name: azure-storage-account-f42c264898221426bb69624-secret
STEP: Successfully provisioned AzureFile volume: "kubetest-nzmen618#f42c264898221426bb69624#csi-inline-smb-volume##csi-inline-smb-volume"

STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:25:20.741: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-vkjmc" in namespace "azurefile-2818" to be "Succeeded or Failed"
Sep 15 13:25:20.795: INFO: Pod "azurefile-volume-tester-vkjmc": Phase="Pending", Reason="", readiness=false. Elapsed: 54.124494ms
Sep 15 13:25:22.851: INFO: Pod "azurefile-volume-tester-vkjmc": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.109678752s
STEP: Saw pod success
Sep 15 13:25:22.851: INFO: Pod "azurefile-volume-tester-vkjmc" satisfied condition "Succeeded or Failed"
Sep 15 13:25:22.851: INFO: deleting Pod "azurefile-2818"/"azurefile-volume-tester-vkjmc"
Sep 15 13:25:22.907: INFO: Pod azurefile-volume-tester-vkjmc has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-vkjmc in namespace azurefile-2818
Sep 15 13:25:22.966: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "azurefile-2818" for this suite.
... skipping 35 lines ...
check the driver pods if restarts ...
======================================================================================
2021/09/15 13:25:26 Check successfully
Sep 15 13:25:26.215: INFO: >>> kubeConfig: /root/tmp1316207620/kubeconfig/kubeconfig.westus2.json
STEP: creating secret smbcreds in namespace azurefile-5786
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:25:26.335: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-62fbg" in namespace "azurefile-5786" to be "Succeeded or Failed"
Sep 15 13:25:26.390: INFO: Pod "azurefile-volume-tester-62fbg": Phase="Pending", Reason="", readiness=false. Elapsed: 54.540786ms
Sep 15 13:25:28.447: INFO: Pod "azurefile-volume-tester-62fbg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.111202739s
Sep 15 13:25:30.502: INFO: Pod "azurefile-volume-tester-62fbg": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.166344835s
STEP: Saw pod success
Sep 15 13:25:30.502: INFO: Pod "azurefile-volume-tester-62fbg" satisfied condition "Succeeded or Failed"
Sep 15 13:25:30.502: INFO: deleting Pod "azurefile-5786"/"azurefile-volume-tester-62fbg"
Sep 15 13:25:30.582: INFO: Pod azurefile-volume-tester-62fbg has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-62fbg in namespace azurefile-5786
Sep 15 13:25:30.644: INFO: deleting Secret smbcreds
Sep 15 13:25:30.701: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 51 lines ...
Sep 15 13:26:23.701: INFO: PersistentVolumeClaim pvc-c77ld found but phase is Pending instead of Bound.
Sep 15 13:26:25.756: INFO: PersistentVolumeClaim pvc-c77ld found and phase=Bound (53.489232636s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:26:25.920: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-bf22g" in namespace "azurefile-8844" to be "Succeeded or Failed"
Sep 15 13:26:25.974: INFO: Pod "azurefile-volume-tester-bf22g": Phase="Pending", Reason="", readiness=false. Elapsed: 54.098423ms
Sep 15 13:26:28.031: INFO: Pod "azurefile-volume-tester-bf22g": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.110727215s
STEP: Saw pod success
Sep 15 13:26:28.031: INFO: Pod "azurefile-volume-tester-bf22g" satisfied condition "Succeeded or Failed"
Sep 15 13:26:28.031: INFO: deleting Pod "azurefile-8844"/"azurefile-volume-tester-bf22g"
Sep 15 13:26:28.088: INFO: Pod azurefile-volume-tester-bf22g has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-bf22g in namespace azurefile-8844
Sep 15 13:26:28.149: INFO: deleting PVC "azurefile-8844"/"pvc-c77ld"
Sep 15 13:26:28.149: INFO: Deleting PersistentVolumeClaim "pvc-c77ld"
... skipping 74 lines ...
Sep 15 13:27:57.338: INFO: PersistentVolumeClaim pvc-jv7jb found but phase is Pending instead of Bound.
Sep 15 13:27:59.393: INFO: PersistentVolumeClaim pvc-jv7jb found and phase=Bound (1m24.331154366s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:27:59.558: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-bw48l" in namespace "azurefile-90" to be "Succeeded or Failed"
Sep 15 13:27:59.612: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 54.184216ms
Sep 15 13:28:01.668: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 2.109472314s
Sep 15 13:28:03.722: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 4.164106324s
Sep 15 13:28:05.778: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 6.21941042s
Sep 15 13:28:07.833: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 8.274255453s
Sep 15 13:28:09.888: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 10.329457316s
Sep 15 13:28:11.943: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 12.384601033s
Sep 15 13:28:13.998: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 14.439553585s
Sep 15 13:28:16.053: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Pending", Reason="", readiness=false. Elapsed: 16.494871867s
Sep 15 13:28:18.108: INFO: Pod "azurefile-volume-tester-bw48l": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.549786348s
STEP: Saw pod success
Sep 15 13:28:18.108: INFO: Pod "azurefile-volume-tester-bw48l" satisfied condition "Succeeded or Failed"
Sep 15 13:28:18.108: INFO: deleting Pod "azurefile-90"/"azurefile-volume-tester-bw48l"
Sep 15 13:28:18.187: INFO: Pod azurefile-volume-tester-bw48l has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-bw48l in namespace azurefile-90
Sep 15 13:28:18.248: INFO: deleting PVC "azurefile-90"/"pvc-jv7jb"
Sep 15 13:28:18.248: INFO: Deleting PersistentVolumeClaim "pvc-jv7jb"
... skipping 89 lines ...
Sep 15 13:28:36.963: INFO: PersistentVolumeClaim pvc-gt6ll found but phase is Pending instead of Bound.
Sep 15 13:28:39.019: INFO: PersistentVolumeClaim pvc-gt6ll found and phase=Bound (2.110594422s)
STEP: checking the PVC
STEP: validating provisioned PV
STEP: checking the PV
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Sep 15 13:28:39.186: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-dhnz4" in namespace "azurefile-7578" to be "Succeeded or Failed"
Sep 15 13:28:39.241: INFO: Pod "azurefile-volume-tester-dhnz4": Phase="Pending", Reason="", readiness=false. Elapsed: 54.886144ms
Sep 15 13:28:41.297: INFO: Pod "azurefile-volume-tester-dhnz4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.111342295s
Sep 15 13:28:43.359: INFO: Pod "azurefile-volume-tester-dhnz4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.173118564s
STEP: Saw pod success
Sep 15 13:28:43.359: INFO: Pod "azurefile-volume-tester-dhnz4" satisfied condition "Succeeded or Failed"
Sep 15 13:28:43.359: INFO: deleting Pod "azurefile-7578"/"azurefile-volume-tester-dhnz4"
Sep 15 13:28:43.416: INFO: Pod azurefile-volume-tester-dhnz4 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-dhnz4 in namespace azurefile-7578
Sep 15 13:28:43.481: INFO: deleting PVC "azurefile-7578"/"pvc-gt6ll"
Sep 15 13:28:43.481: INFO: Deleting PersistentVolumeClaim "pvc-gt6ll"
... skipping 160 lines ...
Go Version: go1.17.1
Platform: linux/amd64

Streaming logs below:
I0915 13:11:11.007788       1 azurefile.go:233] driver userAgent: file.csi.azure.com/e2e-8dca11c54803a75627c0908c73283ddc856cfa65 gc/go1.17.1 (amd64-linux) e2e-test
I0915 13:11:11.009922       1 azure.go:70] reading cloud config from secret kube-system/azure-cloud-provider
W0915 13:11:11.029854       1 azure.go:77] InitializeCloudFromSecret: failed to get cloud config from secret kube-system/azure-cloud-provider: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0915 13:11:11.029872       1 azure.go:82] could not read cloud config from secret kube-system/azure-cloud-provider
I0915 13:11:11.029886       1 azure.go:92] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0915 13:11:11.029903       1 azure.go:100] read cloud config from file: /etc/kubernetes/azure.json successfully
I0915 13:11:11.030382       1 azure_auth.go:234] Using AzurePublicCloud environment
I0915 13:11:11.030412       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0915 13:11:11.030432       1 azure.go:685] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 479 lines ...
I0915 13:26:28.369281       1 utils.go:125] GRPC response: {}
I0915 13:26:35.041860       1 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0915 13:26:35.041898       1 utils.go:119] GRPC request: {"capacity_range":{"required_bytes":107374182400},"name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","parameters":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","skuName":"Premium_LRS"},"volume_capabilities":[{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}}]}
I0915 13:26:35.110121       1 azure_storageaccount.go:205] azure - no matching account found, begin to create a new account ff1926a3b7fa94b4e95e9b5 in resource group kubetest-nzmen618, location: westus2, accountType: Premium_LRS, accountKind: FileStorage, tags: map[k8s-azure-created-by:azure]
I0915 13:26:54.306550       1 azure_storageaccount.go:298] Creating private endpoint(ff1926a3b7fa94b4e95e9b5-pvtendpoint) for account (ff1926a3b7fa94b4e95e9b5)
I0915 13:27:25.445724       1 azure_storageaccount.go:328] Creating private dns zone(privatelink.file.core.windows.net) in resourceGroup (kubetest-nzmen618)
I0915 13:27:56.552264       1 azure_privatednsclient.go:56] Received error while waiting for completion for privatedns.put.request, resourceGroup: kubetest-nzmen618, error: Code="PreconditionFailed" Message="The Zone privatelink.file.core.windows.net exists already and hence cannot be created again."
I0915 13:27:56.552297       1 azure_storageaccount.go:333] private dns zone(privatelink.file.core.windows.net) in resourceGroup (kubetest-nzmen618) already exists
I0915 13:27:56.552304       1 azure_storageaccount.go:342] Creating virtual link for vnet(ff1926a3b7fa94b4e95e9b5-vnetlink) and DNS Zone(privatelink.file.core.windows.net) in resourceGroup(kubetest-nzmen618)
I0915 13:27:57.841513       1 azure_storageaccount.go:355] Creating private DNS zone group(ff1926a3b7fa94b4e95e9b5-dnszonegroup) with privateEndpoint(ff1926a3b7fa94b4e95e9b5-pvtendpoint), vNetName(k8s-vnet-18691370), resourceGroup(kubetest-nzmen618)
I0915 13:27:58.823398       1 controllerserver.go:361] begin to create file share(pvcn-c422dac0-527c-44ef-bd8b-b47743adce41) on account(ff1926a3b7fa94b4e95e9b5) type(Premium_LRS) rg(kubetest-nzmen618) location() size(100) protocol(NFS)
I0915 13:27:58.975989       1 controllerserver.go:383] create file share pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 on storage account ff1926a3b7fa94b4e95e9b5 successfully
I0915 13:27:58.976049       1 utils.go:125] GRPC response: {"volume":{"capacity_bytes":107374182400,"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}}
... skipping 123 lines ...
Go Version: go1.17.1
Platform: linux/amd64

Streaming logs below:
I0915 13:11:12.710381       1 azurefile.go:233] driver userAgent: file.csi.azure.com/e2e-8dca11c54803a75627c0908c73283ddc856cfa65 gc/go1.17.1 (amd64-linux) e2e-test
I0915 13:11:12.772963       1 azure.go:70] reading cloud config from secret kube-system/azure-cloud-provider
W0915 13:11:12.791173       1 azure.go:77] InitializeCloudFromSecret: failed to get cloud config from secret kube-system/azure-cloud-provider: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0915 13:11:12.791194       1 azure.go:82] could not read cloud config from secret kube-system/azure-cloud-provider
I0915 13:11:12.791200       1 azure.go:92] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0915 13:11:12.791219       1 azure.go:100] read cloud config from file: /etc/kubernetes/azure.json successfully
I0915 13:11:12.791825       1 azure_auth.go:234] Using AzurePublicCloud environment
I0915 13:11:12.791862       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0915 13:11:12.791882       1 azure.go:685] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 101 lines ...
Go Version: go1.17.1
Platform: linux/amd64

Streaming logs below:
I0915 13:11:02.605635       1 azurefile.go:233] driver userAgent: file.csi.azure.com/e2e-8dca11c54803a75627c0908c73283ddc856cfa65 gc/go1.17.1 (amd64-linux) e2e-test
I0915 13:11:02.606668       1 azure.go:70] reading cloud config from secret kube-system/azure-cloud-provider
W0915 13:11:02.630776       1 azure.go:77] InitializeCloudFromSecret: failed to get cloud config from secret kube-system/azure-cloud-provider: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0915 13:11:02.630795       1 azure.go:82] could not read cloud config from secret kube-system/azure-cloud-provider
I0915 13:11:02.630802       1 azure.go:92] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0915 13:11:02.630831       1 azure.go:100] read cloud config from file: /etc/kubernetes/azure.json successfully
I0915 13:11:02.631423       1 azure_auth.go:234] Using AzurePublicCloud environment
I0915 13:11:02.631448       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0915 13:11:02.631471       1 azure.go:685] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 68 lines ...
Go Version: go1.17.1
Platform: linux/amd64

Streaming logs below:
I0915 13:11:05.677187       1 azurefile.go:233] driver userAgent: file.csi.azure.com/e2e-8dca11c54803a75627c0908c73283ddc856cfa65 gc/go1.17.1 (amd64-linux) e2e-test
I0915 13:11:05.678865       1 azure.go:70] reading cloud config from secret kube-system/azure-cloud-provider
W0915 13:11:05.699032       1 azure.go:77] InitializeCloudFromSecret: failed to get cloud config from secret kube-system/azure-cloud-provider: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0915 13:11:05.699058       1 azure.go:82] could not read cloud config from secret kube-system/azure-cloud-provider
I0915 13:11:05.699063       1 azure.go:92] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0915 13:11:05.699078       1 azure.go:100] read cloud config from file: /etc/kubernetes/azure.json successfully
I0915 13:11:05.700443       1 azure_auth.go:234] Using AzurePublicCloud environment
I0915 13:11:05.700505       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0915 13:11:05.700537       1 azure.go:685] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 54 lines ...
I0915 13:11:06.289107       1 utils.go:125] GRPC response: {"name":"file.csi.azure.com","vendor_version":"e2e-8dca11c54803a75627c0908c73283ddc856cfa65"}
I0915 13:11:06.661193       1 utils.go:118] GRPC call: /csi.v1.Node/NodeGetInfo
I0915 13:11:06.661209       1 utils.go:119] GRPC request: {}
I0915 13:11:06.661294       1 utils.go:125] GRPC response: {"node_id":"k8s-agentpool1-18691370-0"}
I0915 13:11:43.890091       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:11:43.890109       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-n7rsb/globalmount","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_id":"kubetest-nzmen618#f42c264898221426bb69624#pre-provisioned-readonly##pre-provisioned-readonly"}
I0915 13:11:43.893410       1 azurefile.go:570] could not get account(f42c264898221426bb69624) key from secret(azure-storage-account-f42c264898221426bb69624-secret), error: could not get secret(azure-storage-account-f42c264898221426bb69624-secret): secrets "azure-storage-account-f42c264898221426bb69624-secret" not found, use cluster identity to get account key instead
I0915 13:11:44.417974       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-n7rsb/globalmount) fstype() volumeID(kubetest-nzmen618#f42c264898221426bb69624#pre-provisioned-readonly##pre-provisioned-readonly) context(map[]) mountflags([]) mountOptions([dir_mode=0777 actimeo=30 mfsymlinks file_mode=0777]) volumeMountGroup()
I0915 13:11:44.418077       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,actimeo=30,mfsymlinks,file_mode=0777,<masked> //f42c264898221426bb69624.file.core.windows.net/pre-provisioned-readonly /var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-n7rsb/globalmount)
I0915 13:11:44.658883       1 nodeserver.go:304] volume(kubetest-nzmen618#f42c264898221426bb69624#pre-provisioned-readonly##pre-provisioned-readonly) mount "//f42c264898221426bb69624.file.core.windows.net/pre-provisioned-readonly" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-n7rsb/globalmount" succeeded
I0915 13:11:44.658930       1 utils.go:125] GRPC response: {}
I0915 13:11:44.664932       1 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0915 13:11:44.664953       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-n7rsb/globalmount","target_path":"/var/lib/kubelet/pods/3f93bb39-81a5-49d0-8a0c-15f86a69c402/volumes/kubernetes.io~csi/azurefile-8081-file.csi.azure.com-preprovsioned-pv-n7rsb/mount","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/ephemeral":"false","csi.storage.k8s.io/pod.name":"azurefile-volume-tester-2m7lc","csi.storage.k8s.io/pod.namespace":"azurefile-8081","csi.storage.k8s.io/pod.uid":"3f93bb39-81a5-49d0-8a0c-15f86a69c402","csi.storage.k8s.io/serviceAccount.name":"default"},"volume_id":"kubetest-nzmen618#f42c264898221426bb69624#pre-provisioned-readonly##pre-provisioned-readonly"}
... skipping 252 lines ...
I0915 13:12:21.711131       1 nodeserver.go:360] NodeUnstageVolume: unmount volume kubetest-nzmen618#f42c264898221426bb69624#pre-provisioned-provided-credentials##pre-provisioned-provided-credentials on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-495-file.csi.azure.com-preprovsioned-pv-7sq4b/globalmount successfully
I0915 13:12:21.711144       1 utils.go:125] GRPC response: {}
I0915 13:14:33.334100       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:14:33.334124       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:14:33.354237       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:14:33.354292       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
E0915 13:14:33.444605       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)

E0915 13:14:33.444685       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)
I0915 13:14:34.016887       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:14:34.016912       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:14:34.017053       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:14:34.017097       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
E0915 13:14:34.040618       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)

E0915 13:14:34.040646       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)
I0915 13:14:35.120155       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:14:35.120176       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:14:35.120306       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:14:35.120355       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
E0915 13:14:35.146208       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)

E0915 13:14:35.146239       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)
I0915 13:14:37.225450       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:14:37.225532       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:14:37.225737       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:14:37.225871       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
E0915 13:14:37.252554       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)

E0915 13:14:37.252601       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)
I0915 13:14:41.336152       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:14:41.336191       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:14:41.336435       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:14:41.336525       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
E0915 13:14:41.363430       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)

E0915 13:14:41.363464       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)
I0915 13:14:49.459266       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:14:49.459302       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:14:49.459686       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:14:49.459771       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
E0915 13:14:49.484963       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)

E0915 13:14:49.485011       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg)
I0915 13:15:05.502354       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:15:05.502372       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["dir_mode=0777","file_mode=0777","uid=0","gid=0","mfsymlinks","cache=strict","nosharesock","vers=3.1.1"]}},"access_mode":{"mode":1}},"volume_context":{"accessTier":"Hot","csi.storage.k8s.io/pv/name":"pvc-ea3b07b9-f554-48cb-9642-3181e4b65318","csi.storage.k8s.io/pvc/name":"pvc-bg26w","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"secret-1631711571","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"f6644d065f21548bf95a0c5.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#"}
I0915 13:15:05.502539       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount) fstype() volumeID(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) context(map[accessTier:Hot csi.storage.k8s.io/pv/name:pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 csi.storage.k8s.io/pvc/name:pvc-bg26w csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:secret-1631711571 secretNamespace:kube-system secretnamespace:kube-system server:f6644d065f21548bf95a0c5.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1]) mountOptions([dir_mode=0777 file_mode=0777 uid=0 gid=0 mfsymlinks cache=strict nosharesock vers=3.1.1 actimeo=30]) volumeMountGroup()
I0915 13:15:05.502615       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,uid=0,gid=0,mfsymlinks,cache=strict,nosharesock,vers=3.1.1,actimeo=30,<masked> //f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount)
I0915 13:15:05.547332       1 nodeserver.go:304] volume(kubetest-nzmen618#f6644d065f21548bf95a0c5#pvc-ea3b07b9-f554-48cb-9642-3181e4b65318#) mount "//f6644d065f21548bf95a0c5.privatelink.file.core.windows.net/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-ea3b07b9-f554-48cb-9642-3181e4b65318/globalmount" succeeded
... skipping 214 lines ...
I0915 13:20:30.449708       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd])
I0915 13:20:30.522505       1 mount_linux.go:449] Output: ""
I0915 13:20:30.522526       1 mount_linux.go:408] Disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd" appears to be unformatted, attempting to format as type: "ext4" with options: [-F -m0 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd]
I0915 13:20:31.041197       1 mount_linux.go:418] Disk successfully formatted (mkfs): ext4 - /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:31.041224       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:31.041241       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount)
E0915 13:20:31.081959       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:31.082009       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"
I0915 13:20:31.616179       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:31.616198       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff","csi.storage.k8s.io/pvc/name":"pvc-fdgmc","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"}
I0915 13:20:31.616376       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff csi.storage.k8s.io/pvc/name:pvc-fdgmc csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync actimeo=30 mfsymlinks file_mode=0777]) volumeMountGroup()
I0915 13:20:31.623902       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount
I0915 13:20:31.623925       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:31.623955       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:31.623967       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd])
I0915 13:20:31.678703       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd\nTYPE=ext4\n"
I0915 13:20:31.678725       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd
I0915 13:20:31.778762       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:31.778805       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount)
E0915 13:20:31.817921       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:31.817981       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"
I0915 13:20:32.918047       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:32.918071       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff","csi.storage.k8s.io/pvc/name":"pvc-fdgmc","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"}
I0915 13:20:32.918329       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff csi.storage.k8s.io/pvc/name:pvc-fdgmc csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync file_mode=0777 actimeo=30 mfsymlinks]) volumeMountGroup()
I0915 13:20:32.926890       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount
I0915 13:20:32.926909       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:32.926937       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:32.926953       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd])
I0915 13:20:32.980497       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd\nTYPE=ext4\n"
I0915 13:20:32.980522       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd
I0915 13:20:33.068044       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:33.068077       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount)
E0915 13:20:33.101103       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:33.101266       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"
I0915 13:20:35.123200       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:35.123218       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff","csi.storage.k8s.io/pvc/name":"pvc-fdgmc","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"}
I0915 13:20:35.123383       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff csi.storage.k8s.io/pvc/name:pvc-fdgmc csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync actimeo=30 mfsymlinks file_mode=0777]) volumeMountGroup()
I0915 13:20:35.134256       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount
I0915 13:20:35.134284       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:35.134312       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:35.134323       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd])
I0915 13:20:35.187690       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd\nTYPE=ext4\n"
I0915 13:20:35.187709       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd
I0915 13:20:35.268469       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:35.268506       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount)
E0915 13:20:35.300367       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:35.300401       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"
I0915 13:20:39.337826       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:39.338097       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff","csi.storage.k8s.io/pvc/name":"pvc-fdgmc","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"}
I0915 13:20:39.338351       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff csi.storage.k8s.io/pvc/name:pvc-fdgmc csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync actimeo=30 mfsymlinks file_mode=0777]) volumeMountGroup()
I0915 13:20:39.346411       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount
I0915 13:20:39.346439       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:39.346800       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff#pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:39.346821       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd])
I0915 13:20:39.408255       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd\nTYPE=ext4\n"
I0915 13:20:39.408285       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd
I0915 13:20:39.490461       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
I0915 13:20:39.490511       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount)
E0915 13:20:39.521002       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:39.521075       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-45ff5d3d-1583-4c50-85c4-1ccddd5467ff/proxy-mount/pvcd-45ff5d3d-1583-4c50-85c4-1ccddd5467ff.vhd"
I0915 13:20:46.870396       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:46.870417       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742","csi.storage.k8s.io/pvc/name":"pvc-h4qvk","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"}
I0915 13:20:46.870557       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 csi.storage.k8s.io/pvc/name:pvc-h4qvk csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync file_mode=0777 actimeo=30 mfsymlinks]) volumeMountGroup()
I0915 13:20:46.870623       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,cache=strict,actimeo=30,nostrictsync,file_mode=0777,actimeo=30,mfsymlinks,<masked> //f5ed4ae513e624373a53793.file.core.windows.net/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount)
I0915 13:20:46.913358       1 nodeserver.go:304] volume(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd) mount "//f5ed4ae513e624373a53793.file.core.windows.net/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount" succeeded
I0915 13:20:46.913409       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:46.913425       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd])
I0915 13:20:46.972673       1 mount_linux.go:449] Output: ""
I0915 13:20:46.972697       1 mount_linux.go:408] Disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd" appears to be unformatted, attempting to format as type: "ext4" with options: [-F -m0 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd]
I0915 13:20:47.489441       1 mount_linux.go:418] Disk successfully formatted (mkfs): ext4 - /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:47.489526       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:47.489574       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount)
E0915 13:20:47.524892       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:47.525050       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"
I0915 13:20:48.073213       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:48.073237       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742","csi.storage.k8s.io/pvc/name":"pvc-h4qvk","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"}
I0915 13:20:48.073485       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 csi.storage.k8s.io/pvc/name:pvc-h4qvk csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync actimeo=30 mfsymlinks file_mode=0777]) volumeMountGroup()
I0915 13:20:48.081267       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount
I0915 13:20:48.081298       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:48.081329       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:48.081342       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd])
I0915 13:20:48.137618       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd\nTYPE=ext4\n"
I0915 13:20:48.137647       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd
I0915 13:20:48.221674       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:48.221727       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount)
E0915 13:20:48.251974       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:48.252030       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"
I0915 13:20:49.276171       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:49.276190       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742","csi.storage.k8s.io/pvc/name":"pvc-h4qvk","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"}
I0915 13:20:49.276308       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 csi.storage.k8s.io/pvc/name:pvc-h4qvk csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync mfsymlinks file_mode=0777 actimeo=30]) volumeMountGroup()
I0915 13:20:49.284244       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount
I0915 13:20:49.284274       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:49.284307       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:49.284327       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd])
I0915 13:20:49.340332       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd\nTYPE=ext4\n"
I0915 13:20:49.340355       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd
I0915 13:20:49.417453       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:49.417505       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount)
E0915 13:20:49.452000       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:49.452033       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"
I0915 13:20:51.482572       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:51.482594       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742","csi.storage.k8s.io/pvc/name":"pvc-h4qvk","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"}
I0915 13:20:51.482843       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 csi.storage.k8s.io/pvc/name:pvc-h4qvk csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync file_mode=0777 actimeo=30 mfsymlinks]) volumeMountGroup()
I0915 13:20:51.490705       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount
I0915 13:20:51.490728       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:51.490880       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:51.490901       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd])
I0915 13:20:51.547787       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd\nTYPE=ext4\n"
I0915 13:20:51.547807       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd
I0915 13:20:51.628736       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:51.628774       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount)
E0915 13:20:51.671064       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:51.671100       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"
I0915 13:20:55.697704       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:20:55.697863       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["invalid","mount","options"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742","csi.storage.k8s.io/pvc/name":"pvc-h4qvk","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"}
I0915 13:20:55.698077       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount) fstype(ext4) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742 csi.storage.k8s.io/pvc/name:pvc-h4qvk csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([invalid mount options]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync file_mode=0777 actimeo=30 mfsymlinks]) volumeMountGroup()
I0915 13:20:55.710318       1 nodeserver.go:468] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount
I0915 13:20:55.710346       1 nodeserver.go:277] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:55.710534       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742#pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0915 13:20:55.710561       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd])
I0915 13:20:55.766641       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd\nTYPE=ext4\n"
I0915 13:20:55.766676       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd
I0915 13:20:55.850465       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
I0915 13:20:55.850505       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount)
E0915 13:20:55.889009       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t ext4 -o barrier=1,errors=remount-ro,invalid,loop,mount,noatime,options,defaults /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0915 13:20:55.889065       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742/proxy-mount/pvcd-d5427dbb-10d8-4a4b-b88e-0bc02f8d4742.vhd"
I0915 13:21:08.731803       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:21:08.731820       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-622ada94-f790-4f8d-9e5b-630b50edd266/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"xfs"}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-622ada94-f790-4f8d-9e5b-630b50edd266","csi.storage.k8s.io/pvc/name":"pvc-xghcf","csi.storage.k8s.io/pvc/namespace":"azurefile-3033","diskname":"pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd","fsType":"xfs","secretnamespace":"azurefile-3033","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd"}
I0915 13:21:08.731932       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-622ada94-f790-4f8d-9e5b-630b50edd266/proxy-mount) fstype(xfs) volumeID(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-622ada94-f790-4f8d-9e5b-630b50edd266 csi.storage.k8s.io/pvc/name:pvc-xghcf csi.storage.k8s.io/pvc/namespace:azurefile-3033 diskname:pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd fsType:xfs secretnamespace:azurefile-3033 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync file_mode=0777 actimeo=30 mfsymlinks]) volumeMountGroup()
I0915 13:21:08.731998       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,cache=strict,actimeo=30,nostrictsync,file_mode=0777,actimeo=30,mfsymlinks,<masked> //f5ed4ae513e624373a53793.file.core.windows.net/pvcd-622ada94-f790-4f8d-9e5b-630b50edd266 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-622ada94-f790-4f8d-9e5b-630b50edd266/proxy-mount)
I0915 13:21:08.811299       1 nodeserver.go:304] volume(kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd) mount "//f5ed4ae513e624373a53793.file.core.windows.net/pvcd-622ada94-f790-4f8d-9e5b-630b50edd266" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-622ada94-f790-4f8d-9e5b-630b50edd266/proxy-mount" succeeded
I0915 13:21:08.811349       1 nodeserver.go:324] NodeStageVolume: volume kubetest-nzmen618#f5ed4ae513e624373a53793#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266#pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-622ada94-f790-4f8d-9e5b-630b50edd266/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-622ada94-f790-4f8d-9e5b-630b50edd266/proxy-mount/pvcd-622ada94-f790-4f8d-9e5b-630b50edd266.vhd with mount options([loop])
... skipping 412 lines ...
I0915 13:24:50.762035       1 mount_linux.go:175] Mounting cmd (mount) with arguments ( -o bind,remount /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-b5fac202-55c1-46d3-af38-9de59278c21f/globalmount /var/lib/kubelet/pods/79fa53ad-b69a-4c10-b0ed-db98359c6ba8/volumes/kubernetes.io~csi/pvc-b5fac202-55c1-46d3-af38-9de59278c21f/mount)
I0915 13:24:50.763191       1 nodeserver.go:131] NodePublishVolume: mount /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-b5fac202-55c1-46d3-af38-9de59278c21f/globalmount at /var/lib/kubelet/pods/79fa53ad-b69a-4c10-b0ed-db98359c6ba8/volumes/kubernetes.io~csi/pvc-b5fac202-55c1-46d3-af38-9de59278c21f/mount successfully
I0915 13:24:50.763204       1 utils.go:125] GRPC response: {}
I0915 13:25:20.960067       1 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0915 13:25:20.960104       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/58c0705e-45f4-4608-8eb5-6fff324d9a48/volumes/kubernetes.io~csi/test-volume-1/mount","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/ephemeral":"true","csi.storage.k8s.io/pod.name":"azurefile-volume-tester-vkjmc","csi.storage.k8s.io/pod.namespace":"azurefile-2818","csi.storage.k8s.io/pod.uid":"58c0705e-45f4-4608-8eb5-6fff324d9a48","csi.storage.k8s.io/serviceAccount.name":"default","mountOptions":"cache=singleclient","secretName":"azure-storage-account-f42c264898221426bb69624-secret","server":"","shareName":"csi-inline-smb-volume"},"volume_id":"csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7"}
I0915 13:25:20.960245       1 nodeserver.go:66] NodePublishVolume: ephemeral volume(csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7) mount on /var/lib/kubelet/pods/58c0705e-45f4-4608-8eb5-6fff324d9a48/volumes/kubernetes.io~csi/test-volume-1/mount, VolumeContext: map[csi.storage.k8s.io/ephemeral:true csi.storage.k8s.io/pod.name:azurefile-volume-tester-vkjmc csi.storage.k8s.io/pod.namespace:azurefile-2818 csi.storage.k8s.io/pod.uid:58c0705e-45f4-4608-8eb5-6fff324d9a48 csi.storage.k8s.io/serviceAccount.name:default getaccountkeyfromsecret:true mountOptions:cache=singleclient secretName:azure-storage-account-f42c264898221426bb69624-secret secretnamespace:azurefile-2818 server: shareName:csi-inline-smb-volume storageaccount:]
W0915 13:25:20.960273       1 azurefile.go:506] parsing volumeID(csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7) return with error: error parsing volume id: "csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7", should at least contain two #
I0915 13:25:20.984580       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/pods/58c0705e-45f4-4608-8eb5-6fff324d9a48/volumes/kubernetes.io~csi/test-volume-1/mount) fstype() volumeID(csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7) context(map[csi.storage.k8s.io/ephemeral:true csi.storage.k8s.io/pod.name:azurefile-volume-tester-vkjmc csi.storage.k8s.io/pod.namespace:azurefile-2818 csi.storage.k8s.io/pod.uid:58c0705e-45f4-4608-8eb5-6fff324d9a48 csi.storage.k8s.io/serviceAccount.name:default getaccountkeyfromsecret:true mountOptions:cache=singleclient secretName:azure-storage-account-f42c264898221426bb69624-secret secretnamespace:azurefile-2818 server: shareName:csi-inline-smb-volume storageaccount:]) mountflags([]) mountOptions([actimeo=30 cache=singleclient dir_mode=0777 file_mode=0777 mfsymlinks]) volumeMountGroup()
I0915 13:25:20.984650       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o actimeo=30,cache=singleclient,dir_mode=0777,file_mode=0777,mfsymlinks,<masked> //f42c264898221426bb69624.file.core.windows.net/csi-inline-smb-volume /var/lib/kubelet/pods/58c0705e-45f4-4608-8eb5-6fff324d9a48/volumes/kubernetes.io~csi/test-volume-1/mount)
I0915 13:25:21.136028       1 nodeserver.go:304] volume(csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7) mount "//f42c264898221426bb69624.file.core.windows.net/csi-inline-smb-volume" on "/var/lib/kubelet/pods/58c0705e-45f4-4608-8eb5-6fff324d9a48/volumes/kubernetes.io~csi/test-volume-1/mount" succeeded
I0915 13:25:21.136061       1 utils.go:125] GRPC response: {}
I0915 13:25:22.763027       1 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0915 13:25:22.763046       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/58c0705e-45f4-4608-8eb5-6fff324d9a48/volumes/kubernetes.io~csi/test-volume-1/mount","volume_id":"csi-439a3abeff641ccaadab171a1dd71e0df7bdcc60474beef73103d4db20f36ec7"}
... skipping 48 lines ...
I0915 13:26:27.989769       1 nodeserver.go:360] NodeUnstageVolume: unmount volume kubetest-nzmen618#fd6446cc3c8374b5e817fc9#pvcn-9f1fe545-488e-4a62-a124-ceaa83e30441# on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-9f1fe545-488e-4a62-a124-ceaa83e30441/globalmount successfully
I0915 13:26:27.989777       1 utils.go:125] GRPC response: {}
I0915 13:27:59.741746       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:27:59.741764       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}
I0915 13:27:59.741902       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount) fstype() volumeID(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) context(map[csi.storage.k8s.io/pv/name:pvc-c422dac0-527c-44ef-bd8b-b47743adce41 csi.storage.k8s.io/pvc/name:pvc-jv7jb csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576]) volumeMountGroup()
I0915 13:27:59.741958       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount)
E0915 13:27:59.803914       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known

E0915 13:27:59.803951       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) mount "ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known
I0915 13:28:00.343273       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:28:00.343317       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}
I0915 13:28:00.343587       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount) fstype() volumeID(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) context(map[csi.storage.k8s.io/pv/name:pvc-c422dac0-527c-44ef-bd8b-b47743adce41 csi.storage.k8s.io/pvc/name:pvc-jv7jb csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576]) volumeMountGroup()
I0915 13:28:00.343684       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount)
E0915 13:28:00.354545       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known

E0915 13:28:00.354590       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) mount "ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known
I0915 13:28:01.445494       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:28:01.445642       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}
I0915 13:28:01.445876       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount) fstype() volumeID(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) context(map[csi.storage.k8s.io/pv/name:pvc-c422dac0-527c-44ef-bd8b-b47743adce41 csi.storage.k8s.io/pvc/name:pvc-jv7jb csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576]) volumeMountGroup()
I0915 13:28:01.445969       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount)
E0915 13:28:01.456391       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known

E0915 13:28:01.456422       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) mount "ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known
I0915 13:28:03.552640       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:28:03.552664       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}
I0915 13:28:03.552792       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount) fstype() volumeID(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) context(map[csi.storage.k8s.io/pv/name:pvc-c422dac0-527c-44ef-bd8b-b47743adce41 csi.storage.k8s.io/pvc/name:pvc-jv7jb csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576]) volumeMountGroup()
I0915 13:28:03.552842       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount)
E0915 13:28:03.563549       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known

E0915 13:28:03.563581       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) mount "ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known
I0915 13:28:07.578642       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:28:07.578665       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}
I0915 13:28:07.578820       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount) fstype() volumeID(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) context(map[csi.storage.k8s.io/pv/name:pvc-c422dac0-527c-44ef-bd8b-b47743adce41 csi.storage.k8s.io/pvc/name:pvc-jv7jb csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576]) volumeMountGroup()
I0915 13:28:07.578882       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount)
E0915 13:28:07.588952       1 mount_linux.go:179] Mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known

E0915 13:28:07.588981       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) mount "ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount" failed with mount failed: exit status 32
Mounting command: mount
Mounting arguments: -t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount
Output: mount.nfs: Failed to resolve server ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net: Name or service not known
I0915 13:28:15.612083       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0915 13:28:15.612105       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-c422dac0-527c-44ef-bd8b-b47743adce41","csi.storage.k8s.io/pvc/name":"pvc-jv7jb","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1631711471824-8081-file.csi.azure.com"},"volume_id":"kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#"}
I0915 13:28:15.612358       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount) fstype() volumeID(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) context(map[csi.storage.k8s.io/pv/name:pvc-c422dac0-527c-44ef-bd8b-b47743adce41 csi.storage.k8s.io/pvc/name:pvc-jv7jb csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1631711471824-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576]) volumeMountGroup()
I0915 13:28:15.612456       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount)
I0915 13:28:15.719140       1 nodeserver.go:304] volume(kubetest-nzmen618#ff1926a3b7fa94b4e95e9b5#pvcn-c422dac0-527c-44ef-bd8b-b47743adce41#) mount "ff1926a3b7fa94b4e95e9b5.privatelink.file.core.windows.net:/ff1926a3b7fa94b4e95e9b5/pvcn-c422dac0-527c-44ef-bd8b-b47743adce41" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c422dac0-527c-44ef-bd8b-b47743adce41/globalmount" succeeded
I0915 13:28:15.719179       1 utils.go:125] GRPC response: {}
... skipping 246 lines ...
Go Version: go1.17.1
Platform: linux/amd64

Streaming logs below:
I0915 13:11:05.684173       1 azurefile.go:233] driver userAgent: file.csi.azure.com/e2e-8dca11c54803a75627c0908c73283ddc856cfa65 gc/go1.17.1 (amd64-linux) e2e-test
I0915 13:11:05.685628       1 azure.go:70] reading cloud config from secret kube-system/azure-cloud-provider
W0915 13:11:05.704555       1 azure.go:77] InitializeCloudFromSecret: failed to get cloud config from secret kube-system/azure-cloud-provider: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0915 13:11:05.704576       1 azure.go:82] could not read cloud config from secret kube-system/azure-cloud-provider
I0915 13:11:05.704582       1 azure.go:92] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0915 13:11:05.704600       1 azure.go:100] read cloud config from file: /etc/kubernetes/azure.json successfully
I0915 13:11:05.705226       1 azure_auth.go:234] Using AzurePublicCloud environment
I0915 13:11:05.705252       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0915 13:11:05.705273       1 azure.go:685] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 113 lines ...
W0915 13:18:55.603898       1 mount_helper_common.go:34] Warning: Unmount skipped because path does not exist: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-1ea7aee5-8eb0-42c0-aa3b-493326c681ed/proxy-mount
I0915 13:18:55.603905       1 nodeserver.go:360] NodeUnstageVolume: unmount volume kubetest-nzmen618#f42c264898221426bb69624#pvc-1ea7aee5-8eb0-42c0-aa3b-493326c681ed# on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-1ea7aee5-8eb0-42c0-aa3b-493326c681ed/globalmount successfully
I0915 13:18:55.603912       1 utils.go:125] GRPC response: {}
I0915 13:25:26.516368       1 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0915 13:25:26.516391       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/460775cf-9636-4eba-b717-dff2809a61c1/volumes/kubernetes.io~csi/test-volume-1/mount","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/ephemeral":"true","csi.storage.k8s.io/pod.name":"azurefile-volume-tester-62fbg","csi.storage.k8s.io/pod.namespace":"azurefile-5786","csi.storage.k8s.io/pod.uid":"460775cf-9636-4eba-b717-dff2809a61c1","csi.storage.k8s.io/serviceAccount.name":"default","mountOptions":"cache=singleclient","secretName":"smbcreds","server":"smb-server.default.svc.cluster.local","shareName":"share"},"volume_id":"csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb"}
I0915 13:25:26.516505       1 nodeserver.go:66] NodePublishVolume: ephemeral volume(csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb) mount on /var/lib/kubelet/pods/460775cf-9636-4eba-b717-dff2809a61c1/volumes/kubernetes.io~csi/test-volume-1/mount, VolumeContext: map[csi.storage.k8s.io/ephemeral:true csi.storage.k8s.io/pod.name:azurefile-volume-tester-62fbg csi.storage.k8s.io/pod.namespace:azurefile-5786 csi.storage.k8s.io/pod.uid:460775cf-9636-4eba-b717-dff2809a61c1 csi.storage.k8s.io/serviceAccount.name:default getaccountkeyfromsecret:true mountOptions:cache=singleclient secretName:smbcreds secretnamespace:azurefile-5786 server:smb-server.default.svc.cluster.local shareName:share storageaccount:]
W0915 13:25:26.516530       1 azurefile.go:506] parsing volumeID(csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb) return with error: error parsing volume id: "csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb", should at least contain two #
I0915 13:25:26.534727       1 nodeserver.go:270] cifsMountPath(/var/lib/kubelet/pods/460775cf-9636-4eba-b717-dff2809a61c1/volumes/kubernetes.io~csi/test-volume-1/mount) fstype() volumeID(csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb) context(map[csi.storage.k8s.io/ephemeral:true csi.storage.k8s.io/pod.name:azurefile-volume-tester-62fbg csi.storage.k8s.io/pod.namespace:azurefile-5786 csi.storage.k8s.io/pod.uid:460775cf-9636-4eba-b717-dff2809a61c1 csi.storage.k8s.io/serviceAccount.name:default getaccountkeyfromsecret:true mountOptions:cache=singleclient secretName:smbcreds secretnamespace:azurefile-5786 server:smb-server.default.svc.cluster.local shareName:share storageaccount:]) mountflags([]) mountOptions([actimeo=30 cache=singleclient dir_mode=0777 file_mode=0777 mfsymlinks]) volumeMountGroup()
I0915 13:25:26.534795       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o actimeo=30,cache=singleclient,dir_mode=0777,file_mode=0777,mfsymlinks,<masked> //smb-server.default.svc.cluster.local/share /var/lib/kubelet/pods/460775cf-9636-4eba-b717-dff2809a61c1/volumes/kubernetes.io~csi/test-volume-1/mount)
I0915 13:25:26.599586       1 nodeserver.go:304] volume(csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb) mount "//smb-server.default.svc.cluster.local/share" on "/var/lib/kubelet/pods/460775cf-9636-4eba-b717-dff2809a61c1/volumes/kubernetes.io~csi/test-volume-1/mount" succeeded
I0915 13:25:26.599621       1 utils.go:125] GRPC response: {}
I0915 13:25:30.126431       1 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0915 13:25:30.126452       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/460775cf-9636-4eba-b717-dff2809a61c1/volumes/kubernetes.io~csi/test-volume-1/mount","volume_id":"csi-a7a9c962658d07189ad8a37acd4cf228c1e85a323794ca2182e743b1f8e887fb"}
... skipping 215 lines ...
Uninstalled Azure File CSI driver successfully.
2021/09/15 13:29:52 ===================================================

JUnit report was created: /logs/artifacts/junit_01.xml

Ran 31 of 34 Specs in 1687.941 seconds
SUCCESS! -- 31 Passed | 0 Failed | 0 Pending | 3 Skipped
You're using deprecated Ginkgo functionality:
=============================================
Ginkgo 2.0 is under active development and will introduce (a small number of) breaking changes.
To learn more, view the migration guide at https://github.com/onsi/ginkgo/blob/v2/docs/MIGRATING_TO_V2.md
To comment, chime in at https://github.com/onsi/ginkgo/issues/711

... skipping 67 lines ...
2021/09/15 13:30:33 process.go:153: Running: chmod +x /root/tmp1316207620/win-ci-logs-collector.sh
2021/09/15 13:30:33 process.go:155: Step 'chmod +x /root/tmp1316207620/win-ci-logs-collector.sh' finished in 1.224525ms
2021/09/15 13:30:33 process.go:153: Running: bash -c /root/tmp1316207620/win-ci-logs-collector.sh kubetest-nzmen618.westus2.cloudapp.azure.com /root/tmp1316207620 /root/.ssh/id_rsa
ssh key file /root/.ssh/id_rsa does not exist. Exiting.
2021/09/15 13:30:33 process.go:155: Step 'bash -c /root/tmp1316207620/win-ci-logs-collector.sh kubetest-nzmen618.westus2.cloudapp.azure.com /root/tmp1316207620 /root/.ssh/id_rsa' finished in 3.967714ms
2021/09/15 13:30:33 aksengine.go:1141: Deleting resource group: kubetest-nzmen618.
{"component":"entrypoint","file":"prow/entrypoint/run.go:169","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2021-09-15T13:34:15Z"}
++ early_exit_handler
++ '[' -n 175 ']'
++ kill -TERM 175
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...