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

No Test Failures!


Show 31 Passed Tests

Show 3 Skipped Tests

Error lines from build-log.txt

... skipping 363 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 793 lines ...
        - name: csi-resizer
          image: "mcr.microsoft.com/oss/kubernetes-csi/csi-resizer:v1.1.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 188 lines ...
Git Commit: N/A
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
STEP: Building a namespace api object, basename azurefile
E0722 11:50:26.561009   14044 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret /: resource name may not be empty
I0722 11:50:26.562539   14044 driver.go:93] Enabling controller service capability: CREATE_DELETE_VOLUME
I0722 11:50:26.562569   14044 driver.go:93] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0722 11:50:26.562577   14044 driver.go:93] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0722 11:50:26.562582   14044 driver.go:93] Enabling controller service capability: EXPAND_VOLUME
I0722 11:50:26.562589   14044 driver.go:112] Enabling volume access mode: SINGLE_NODE_WRITER
I0722 11:50:26.562596   14044 driver.go:112] Enabling volume access mode: SINGLE_NODE_READER_ONLY
... skipping 19 lines ...
Jul 22 11:50:47.478: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-8njzk] to have phase Bound
Jul 22 11:50:47.506: INFO: PersistentVolumeClaim pvc-8njzk found and phase=Bound (27.858412ms)
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
Jul 22 11:50:47.592: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-jl8k5" in namespace "azurefile-8081" to be "Error status code"
Jul 22 11:50:47.620: INFO: Pod "azurefile-volume-tester-jl8k5": Phase="Pending", Reason="", readiness=false. Elapsed: 27.911595ms
Jul 22 11:50:49.650: INFO: Pod "azurefile-volume-tester-jl8k5": Phase="Pending", Reason="", readiness=false. Elapsed: 2.057787697s
Jul 22 11:50:51.679: INFO: Pod "azurefile-volume-tester-jl8k5": Phase="Failed", Reason="", readiness=false. Elapsed: 4.08692569s
STEP: Saw pod failure
Jul 22 11:50:51.679: INFO: Pod "azurefile-volume-tester-jl8k5" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Jul 22 11:50:51.798: INFO: deleting Pod "azurefile-8081"/"azurefile-volume-tester-jl8k5"
Jul 22 11:50:51.829: INFO: Pod azurefile-volume-tester-jl8k5 has the following logs: /bin/sh: can't create /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-jl8k5 in namespace azurefile-8081
Jul 22 11:50:51.863: INFO: deleting PVC "azurefile-8081"/"pvc-8njzk"
... skipping 37 lines ...
Jul 22 11:50:53.539: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-wxc5w] to have phase Bound
Jul 22 11:50:53.566: INFO: PersistentVolumeClaim pvc-wxc5w found and phase=Bound (27.589039ms)
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
Jul 22 11:50:53.652: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-d4tlp" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 22 11:50:53.683: INFO: Pod "azurefile-volume-tester-d4tlp": Phase="Pending", Reason="", readiness=false. Elapsed: 31.365791ms
Jul 22 11:50:55.713: INFO: Pod "azurefile-volume-tester-d4tlp": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.061365507s
STEP: Saw pod success
Jul 22 11:50:55.714: INFO: Pod "azurefile-volume-tester-d4tlp" 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"
Jul 22 11:50:55.771: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-2mq7c] to have phase Bound
Jul 22 11:50:55.800: INFO: PersistentVolumeClaim pvc-2mq7c found and phase=Bound (29.094523ms)
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
Jul 22 11:50:55.892: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-gvbx4" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 22 11:50:55.920: INFO: Pod "azurefile-volume-tester-gvbx4": Phase="Pending", Reason="", readiness=false. Elapsed: 27.847076ms
Jul 22 11:50:57.951: INFO: Pod "azurefile-volume-tester-gvbx4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.058791157s
STEP: Saw pod success
Jul 22 11:50:57.951: INFO: Pod "azurefile-volume-tester-gvbx4" 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"
Jul 22 11:50:58.010: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-g4t7b] to have phase Bound
Jul 22 11:50:58.038: INFO: PersistentVolumeClaim pvc-g4t7b found and phase=Bound (28.048577ms)
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
Jul 22 11:50:58.126: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-jcbp5" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 22 11:50:58.154: INFO: Pod "azurefile-volume-tester-jcbp5": Phase="Pending", Reason="", readiness=false. Elapsed: 28.275554ms
Jul 22 11:51:00.184: INFO: Pod "azurefile-volume-tester-jcbp5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.05799184s
STEP: Saw pod success
Jul 22 11:51:00.184: INFO: Pod "azurefile-volume-tester-jcbp5" 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"
Jul 22 11:51:00.242: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-z9d29] to have phase Bound
Jul 22 11:51:00.271: INFO: PersistentVolumeClaim pvc-z9d29 found and phase=Bound (29.112497ms)
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
Jul 22 11:51:00.360: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-vsqbj" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 22 11:51:00.388: INFO: Pod "azurefile-volume-tester-vsqbj": Phase="Pending", Reason="", readiness=false. Elapsed: 27.88373ms
Jul 22 11:51:02.418: INFO: Pod "azurefile-volume-tester-vsqbj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.0577541s
STEP: Saw pod success
Jul 22 11:51:02.418: INFO: Pod "azurefile-volume-tester-vsqbj" 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"
Jul 22 11:51:02.477: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-tfp9f] to have phase Bound
Jul 22 11:51:02.505: INFO: PersistentVolumeClaim pvc-tfp9f found and phase=Bound (28.145224ms)
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
Jul 22 11:51:02.592: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-djdpx" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 22 11:51:02.621: INFO: Pod "azurefile-volume-tester-djdpx": Phase="Pending", Reason="", readiness=false. Elapsed: 28.65884ms
Jul 22 11:51:04.650: INFO: Pod "azurefile-volume-tester-djdpx": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.058599691s
STEP: Saw pod success
Jul 22 11:51:04.651: INFO: Pod "azurefile-volume-tester-djdpx" 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"
Jul 22 11:51:04.711: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-jwmbf] to have phase Bound
Jul 22 11:51:04.740: INFO: PersistentVolumeClaim pvc-jwmbf found and phase=Bound (28.881884ms)
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
Jul 22 11:51:04.829: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-x28f5" in namespace "azurefile-1318" to be "Succeeded or Failed"
Jul 22 11:51:04.856: INFO: Pod "azurefile-volume-tester-x28f5": Phase="Pending", Reason="", readiness=false. Elapsed: 27.742755ms
Jul 22 11:51:06.887: INFO: Pod "azurefile-volume-tester-x28f5": Phase="Running", Reason="", readiness=true. Elapsed: 2.057911615s
Jul 22 11:51:08.916: INFO: Pod "azurefile-volume-tester-x28f5": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.087028581s
STEP: Saw pod success
Jul 22 11:51:08.916: INFO: Pod "azurefile-volume-tester-x28f5" satisfied condition "Succeeded or Failed"
Jul 22 11:51:08.916: INFO: deleting Pod "azurefile-1318"/"azurefile-volume-tester-x28f5"
Jul 22 11:51:08.954: INFO: Pod azurefile-volume-tester-x28f5 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-x28f5 in namespace azurefile-1318
Jul 22 11:51:08.987: INFO: deleting PVC "azurefile-1318"/"pvc-jwmbf"
Jul 22 11:51:08.987: INFO: Deleting PersistentVolumeClaim "pvc-jwmbf"
... skipping 143 lines ...
Jul 22 11:51:13.230: INFO: PersistentVolumeClaim pvc-rv546 found but phase is Pending instead of Bound.
Jul 22 11:51:15.260: INFO: PersistentVolumeClaim pvc-rv546 found and phase=Bound (2.057356515s)
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
Jul 22 11:51:15.353: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-kx6rd" in namespace "azurefile-3274" to be "Succeeded or Failed"
Jul 22 11:51:15.381: INFO: Pod "azurefile-volume-tester-kx6rd": Phase="Pending", Reason="", readiness=false. Elapsed: 27.803824ms
Jul 22 11:51:17.411: INFO: Pod "azurefile-volume-tester-kx6rd": Phase="Running", Reason="", readiness=true. Elapsed: 2.057901161s
Jul 22 11:51:19.441: INFO: Pod "azurefile-volume-tester-kx6rd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.087635637s
STEP: Saw pod success
Jul 22 11:51:19.441: INFO: Pod "azurefile-volume-tester-kx6rd" satisfied condition "Succeeded or Failed"
Jul 22 11:51:19.441: INFO: deleting Pod "azurefile-3274"/"azurefile-volume-tester-kx6rd"
Jul 22 11:51:19.472: INFO: Pod azurefile-volume-tester-kx6rd has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-kx6rd in namespace azurefile-3274
Jul 22 11:51:19.508: INFO: deleting PVC "azurefile-3274"/"pvc-rv546"
Jul 22 11:51:19.508: INFO: Deleting PersistentVolumeClaim "pvc-rv546"
... skipping 33 lines ...
Jul 22 11:51:21.023: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-szj72] to have phase Bound
Jul 22 11:51:21.051: INFO: PersistentVolumeClaim pvc-szj72 found and phase=Bound (27.978662ms)
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
Jul 22 11:51:21.138: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-wlmvr" in namespace "azurefile-495" to be "Succeeded or Failed"
Jul 22 11:51:21.166: INFO: Pod "azurefile-volume-tester-wlmvr": Phase="Pending", Reason="", readiness=false. Elapsed: 28.109893ms
Jul 22 11:51:23.196: INFO: Pod "azurefile-volume-tester-wlmvr": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.05753551s
STEP: Saw pod success
Jul 22 11:51:23.196: INFO: Pod "azurefile-volume-tester-wlmvr" satisfied condition "Succeeded or Failed"
Jul 22 11:51:23.196: INFO: deleting Pod "azurefile-495"/"azurefile-volume-tester-wlmvr"
Jul 22 11:51:23.235: INFO: Pod azurefile-volume-tester-wlmvr has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-wlmvr in namespace azurefile-495
Jul 22 11:51:23.271: INFO: deleting PVC "azurefile-495"/"pvc-szj72"
Jul 22 11:51:23.271: INFO: Deleting PersistentVolumeClaim "pvc-szj72"
... skipping 130 lines ...
Jul 22 11:53:26.846: INFO: PersistentVolumeClaim pvc-fsb44 found but phase is Pending instead of Bound.
Jul 22 11:53:28.876: INFO: PersistentVolumeClaim pvc-fsb44 found and phase=Bound (1m37.451228501s)
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
Jul 22 11:53:28.963: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-75bcd" in namespace "azurefile-5541" to be "Succeeded or Failed"
Jul 22 11:53:28.994: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 31.819057ms
Jul 22 11:53:31.030: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.067373868s
Jul 22 11:53:33.060: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.097136633s
Jul 22 11:53:35.090: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.127134907s
Jul 22 11:53:37.120: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.157271539s
Jul 22 11:53:39.149: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.186395876s
... skipping 7 lines ...
Jul 22 11:53:55.386: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 26.423774472s
Jul 22 11:53:57.419: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 28.456331682s
Jul 22 11:53:59.450: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 30.487741167s
Jul 22 11:54:01.480: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Pending", Reason="", readiness=false. Elapsed: 32.517126197s
Jul 22 11:54:03.509: INFO: Pod "azurefile-volume-tester-75bcd": Phase="Succeeded", Reason="", readiness=false. Elapsed: 34.546364185s
STEP: Saw pod success
Jul 22 11:54:03.509: INFO: Pod "azurefile-volume-tester-75bcd" satisfied condition "Succeeded or Failed"
Jul 22 11:54:03.509: INFO: deleting Pod "azurefile-5541"/"azurefile-volume-tester-75bcd"
Jul 22 11:54:03.622: INFO: Pod azurefile-volume-tester-75bcd has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-75bcd in namespace azurefile-5541
Jul 22 11:54:03.657: INFO: deleting PVC "azurefile-5541"/"pvc-fsb44"
Jul 22 11:54:03.657: INFO: Deleting PersistentVolumeClaim "pvc-fsb44"
... skipping 34 lines ...
Jul 22 11:54:09.906: INFO: PersistentVolumeClaim pvc-5fwk2 found but phase is Pending instead of Bound.
Jul 22 11:54:11.937: INFO: PersistentVolumeClaim pvc-5fwk2 found and phase=Bound (2.058384635s)
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
Jul 22 11:54:12.024: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-g8mc2" in namespace "azurefile-5356" to be "Succeeded or Failed"
Jul 22 11:54:12.051: INFO: Pod "azurefile-volume-tester-g8mc2": Phase="Pending", Reason="", readiness=false. Elapsed: 27.64155ms
Jul 22 11:54:14.080: INFO: Pod "azurefile-volume-tester-g8mc2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.055978779s
Jul 22 11:54:16.110: INFO: Pod "azurefile-volume-tester-g8mc2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.08642247s
STEP: Saw pod success
Jul 22 11:54:16.110: INFO: Pod "azurefile-volume-tester-g8mc2" satisfied condition "Succeeded or Failed"
Jul 22 11:54:16.110: INFO: deleting Pod "azurefile-5356"/"azurefile-volume-tester-g8mc2"
Jul 22 11:54:16.141: INFO: Pod azurefile-volume-tester-g8mc2 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-g8mc2 in namespace azurefile-5356
Jul 22 11:54:16.178: INFO: deleting PVC "azurefile-5356"/"pvc-5fwk2"
Jul 22 11:54:16.178: INFO: Deleting PersistentVolumeClaim "pvc-5fwk2"
... skipping 129 lines ...
Jul 22 11:56:18.180: INFO: PersistentVolumeClaim pvc-jxmnv found but phase is Pending instead of Bound.
Jul 22 11:56:20.210: INFO: PersistentVolumeClaim pvc-jxmnv found and phase=Bound (20.32244363s)
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
Jul 22 11:56:20.297: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-jq2qg" in namespace "azurefile-4147" to be "Error status code"
Jul 22 11:56:20.325: INFO: Pod "azurefile-volume-tester-jq2qg": Phase="Pending", Reason="", readiness=false. Elapsed: 28.076402ms
Jul 22 11:56:22.358: INFO: Pod "azurefile-volume-tester-jq2qg": Phase="Pending", Reason="", readiness=false. Elapsed: 2.060800322s
Jul 22 11:56:24.388: INFO: Pod "azurefile-volume-tester-jq2qg": Phase="Failed", Reason="", readiness=false. Elapsed: 4.091130578s
STEP: Saw pod failure
Jul 22 11:56:24.388: INFO: Pod "azurefile-volume-tester-jq2qg" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Jul 22 11:56:24.422: INFO: deleting Pod "azurefile-4147"/"azurefile-volume-tester-jq2qg"
Jul 22 11:56:24.454: INFO: Pod azurefile-volume-tester-jq2qg has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-jq2qg in namespace azurefile-4147
Jul 22 11:56:24.494: INFO: deleting PVC "azurefile-4147"/"pvc-jxmnv"
... skipping 190 lines ...
Jul 22 11:58:22.741: INFO: PersistentVolumeClaim pvc-8rtb6 found but phase is Pending instead of Bound.
Jul 22 11:58:24.770: INFO: PersistentVolumeClaim pvc-8rtb6 found and phase=Bound (2.057802521s)
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
Jul 22 11:58:24.859: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-7m9mk" in namespace "azurefile-5561" to be "Succeeded or Failed"
Jul 22 11:58:24.887: INFO: Pod "azurefile-volume-tester-7m9mk": Phase="Pending", Reason="", readiness=false. Elapsed: 27.913147ms
Jul 22 11:58:26.916: INFO: Pod "azurefile-volume-tester-7m9mk": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.0577625s
STEP: Saw pod success
Jul 22 11:58:26.916: INFO: Pod "azurefile-volume-tester-7m9mk" 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/07/22 11:58:57 Running in Prow, converting AZURE_CREDENTIALS to AZURE_CREDENTIAL_FILE
... skipping 41 lines ...
Jul 22 11:59:03.907: INFO: PersistentVolumeClaim pvc-wvqzk found but phase is Pending instead of Bound.
Jul 22 11:59:05.936: INFO: PersistentVolumeClaim pvc-wvqzk found and phase=Bound (2.057197277s)
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
Jul 22 11:59:06.024: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-vdj9k" in namespace "azurefile-4376" to be "Succeeded or Failed"
Jul 22 11:59:06.052: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Pending", Reason="", readiness=false. Elapsed: 28.225939ms
Jul 22 11:59:08.081: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Pending", Reason="", readiness=false. Elapsed: 2.057116369s
Jul 22 11:59:10.111: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Pending", Reason="", readiness=false. Elapsed: 4.087313282s
Jul 22 11:59:12.141: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Pending", Reason="", readiness=false. Elapsed: 6.117382623s
Jul 22 11:59:14.171: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Pending", Reason="", readiness=false. Elapsed: 8.146750328s
Jul 22 11:59:16.200: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Pending", Reason="", readiness=false. Elapsed: 10.176615188s
Jul 22 11:59:18.230: INFO: Pod "azurefile-volume-tester-vdj9k": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.205894505s
STEP: Saw pod success
Jul 22 11:59:18.230: INFO: Pod "azurefile-volume-tester-vdj9k" satisfied condition "Succeeded or Failed"
Jul 22 11:59:18.230: INFO: deleting Pod "azurefile-4376"/"azurefile-volume-tester-vdj9k"
Jul 22 11:59:18.261: INFO: Pod azurefile-volume-tester-vdj9k has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-vdj9k in namespace azurefile-4376
Jul 22 11:59:18.299: INFO: deleting PVC "azurefile-4376"/"pvc-wvqzk"
Jul 22 11:59:18.299: INFO: Deleting PersistentVolumeClaim "pvc-wvqzk"
... skipping 36 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
Jul 22 11:59:28.683: INFO: deleting Pod "azurefile-1577"/"azurefile-volume-tester-qxsbt"
Jul 22 11:59:28.717: INFO: Error getting logs for pod azurefile-volume-tester-qxsbt: the server rejected our request for an unknown reason (get pods azurefile-volume-tester-qxsbt)
STEP: Deleting pod azurefile-volume-tester-qxsbt in namespace azurefile-1577
Jul 22 11:59:28.746: INFO: deleting PVC "azurefile-1577"/"pvc-8kz4s"
Jul 22 11:59:28.747: INFO: Deleting PersistentVolumeClaim "pvc-8kz4s"
STEP: waiting for claim's PV "pvc-df75b5df-e90b-4465-8361-0b0076b94410" to be deleted
Jul 22 11:59:28.838: INFO: Waiting up to 10m0s for PersistentVolume pvc-df75b5df-e90b-4465-8361-0b0076b94410 to get deleted
Jul 22 11:59:28.866: INFO: PersistentVolume pvc-df75b5df-e90b-4465-8361-0b0076b94410 found and phase=Bound (28.067101ms)
... 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
Jul 22 11:59:44.195: INFO: deleting Pod "azurefile-953"/"azurefile-volume-tester-sgpcp"
Jul 22 11:59:44.226: INFO: Error getting logs for pod azurefile-volume-tester-sgpcp: the server rejected our request for an unknown reason (get pods azurefile-volume-tester-sgpcp)
STEP: Deleting pod azurefile-volume-tester-sgpcp in namespace azurefile-953
Jul 22 11:59:44.259: INFO: deleting PVC "azurefile-953"/"pvc-pkpdc"
Jul 22 11:59:44.259: INFO: Deleting PersistentVolumeClaim "pvc-pkpdc"
STEP: waiting for claim's PV "pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53" to be deleted
Jul 22 11:59:44.350: INFO: Waiting up to 10m0s for PersistentVolume pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 to get deleted
Jul 22 11:59:44.378: INFO: PersistentVolume pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 found and phase=Bound (28.605156ms)
... skipping 119 lines ...
Jul 22 12:01:40.348: INFO: PersistentVolumeClaim pvc-bqgxn found but phase is Pending instead of Bound.
Jul 22 12:01:42.377: INFO: PersistentVolumeClaim pvc-bqgxn found and phase=Bound (2.056721897s)
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
Jul 22 12:01:42.466: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-nk7zw" in namespace "azurefile-9336" to be "Error status code"
Jul 22 12:01:42.494: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 28.012392ms
Jul 22 12:01:44.523: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.056949179s
Jul 22 12:01:46.553: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.086848576s
Jul 22 12:01:48.582: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.115580045s
Jul 22 12:01:50.611: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.144733754s
Jul 22 12:01:52.641: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.174336425s
Jul 22 12:01:54.671: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 12.204805956s
Jul 22 12:01:56.700: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 14.233589074s
Jul 22 12:01:58.730: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 16.263844814s
Jul 22 12:02:00.760: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Pending", Reason="", readiness=false. Elapsed: 18.293563448s
Jul 22 12:02:02.790: INFO: Pod "azurefile-volume-tester-nk7zw": Phase="Failed", Reason="", readiness=false. Elapsed: 20.32340602s
STEP: Saw pod failure
Jul 22 12:02:02.790: INFO: Pod "azurefile-volume-tester-nk7zw" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Jul 22 12:02:02.824: INFO: deleting Pod "azurefile-9336"/"azurefile-volume-tester-nk7zw"
Jul 22 12:02:02.854: INFO: Pod azurefile-volume-tester-nk7zw has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azurefile-volume-tester-nk7zw in namespace azurefile-9336
Jul 22 12:02:02.889: INFO: deleting PVC "azurefile-9336"/"pvc-bqgxn"
... skipping 195 lines ...
Jul 22 12:02:33.660: INFO: PersistentVolumeClaim pvc-fwl69 found but phase is Pending instead of Bound.
Jul 22 12:02:35.690: INFO: PersistentVolumeClaim pvc-fwl69 found and phase=Bound (2.0579556s)
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
Jul 22 12:02:35.778: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-2wpjl" in namespace "azurefile-8553" to be "Succeeded or Failed"
Jul 22 12:02:35.806: INFO: Pod "azurefile-volume-tester-2wpjl": Phase="Pending", Reason="", readiness=false. Elapsed: 28.059842ms
Jul 22 12:02:37.836: INFO: Pod "azurefile-volume-tester-2wpjl": Phase="Pending", Reason="", readiness=false. Elapsed: 2.058001888s
Jul 22 12:02:39.867: INFO: Pod "azurefile-volume-tester-2wpjl": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.088177836s
STEP: Saw pod success
Jul 22 12:02:39.867: INFO: Pod "azurefile-volume-tester-2wpjl" satisfied condition "Succeeded or Failed"
Jul 22 12:02:39.867: INFO: deleting Pod "azurefile-8553"/"azurefile-volume-tester-2wpjl"
Jul 22 12:02:39.897: INFO: Pod azurefile-volume-tester-2wpjl has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-2wpjl in namespace azurefile-8553
Jul 22 12:02:39.933: INFO: deleting PVC "azurefile-8553"/"pvc-fwl69"
Jul 22 12:02:39.933: INFO: Deleting PersistentVolumeClaim "pvc-fwl69"
... skipping 79 lines ...
Jul 22 12:03:12.286: INFO: PersistentVolumeClaim pvc-w7gz2 found but phase is Pending instead of Bound.
Jul 22 12:03:14.315: INFO: PersistentVolumeClaim pvc-w7gz2 found and phase=Bound (2.056683673s)
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
Jul 22 12:03:14.403: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-vc78z" in namespace "azurefile-9267" to be "Succeeded or Failed"
Jul 22 12:03:14.431: INFO: Pod "azurefile-volume-tester-vc78z": Phase="Pending", Reason="", readiness=false. Elapsed: 28.133976ms
Jul 22 12:03:16.461: INFO: Pod "azurefile-volume-tester-vc78z": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.057933134s
STEP: Saw pod success
Jul 22 12:03:16.461: INFO: Pod "azurefile-volume-tester-vc78z" 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-w7gz2
STEP: waiting for VolumeSnapshot to be ready to use - volume-snapshot-pnnt8
... skipping 32 lines ...
check the driver pods if restarts ...
======================================================================================
2021/07/22 12:03:37 Check successfully
Jul 22 12:03:37.806: INFO: >>> kubeConfig: /root/tmp086438365/kubeconfig/kubeconfig.eastus.json
2021/07/22 12:03:37 run script: test/utils/get_storage_account_secret_name.sh
2021/07/22 12:03:38 got output: azure-storage-account-fd75972c40eda4aa8a9a776-secret
, error: <nil>
2021/07/22 12:03:38 got storage account secret name: azure-storage-account-fd75972c40eda4aa8a9a776-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"
Jul 22 12:03:38.111: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-skdvx] to have phase Bound
Jul 22 12:03:38.139: INFO: PersistentVolumeClaim pvc-skdvx found but phase is Pending instead of Bound.
Jul 22 12:03:40.169: INFO: PersistentVolumeClaim pvc-skdvx found and phase=Bound (2.057501198s)
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
Jul 22 12:03:40.258: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-k225d" in namespace "azurefile-5802" to be "Succeeded or Failed"
Jul 22 12:03:40.286: INFO: Pod "azurefile-volume-tester-k225d": Phase="Pending", Reason="", readiness=false. Elapsed: 28.273498ms
Jul 22 12:03:42.316: INFO: Pod "azurefile-volume-tester-k225d": Phase="Running", Reason="", readiness=true. Elapsed: 2.058020815s
Jul 22 12:03:44.347: INFO: Pod "azurefile-volume-tester-k225d": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.088533408s
STEP: Saw pod success
Jul 22 12:03:44.347: INFO: Pod "azurefile-volume-tester-k225d" satisfied condition "Succeeded or Failed"
Jul 22 12:03:44.347: INFO: deleting Pod "azurefile-5802"/"azurefile-volume-tester-k225d"
Jul 22 12:03:44.378: INFO: Pod azurefile-volume-tester-k225d has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-k225d in namespace azurefile-5802
Jul 22 12:03:44.411: INFO: deleting PVC "azurefile-5802"/"pvc-skdvx"
Jul 22 12:03:44.411: INFO: Deleting PersistentVolumeClaim "pvc-skdvx"
... skipping 34 lines ...
Jul 22 12:03:50.639: INFO: PersistentVolumeClaim pvc-n44n2 found but phase is Pending instead of Bound.
Jul 22 12:03:52.668: INFO: PersistentVolumeClaim pvc-n44n2 found and phase=Bound (2.057255229s)
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
Jul 22 12:03:52.755: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-pwjmq" in namespace "azurefile-493" to be "Succeeded or Failed"
Jul 22 12:03:52.783: INFO: Pod "azurefile-volume-tester-pwjmq": Phase="Pending", Reason="", readiness=false. Elapsed: 28.148013ms
Jul 22 12:03:54.815: INFO: Pod "azurefile-volume-tester-pwjmq": Phase="Succeeded", Reason="", readiness=false. Elapsed: 2.059525466s
STEP: Saw pod success
Jul 22 12:03:54.815: INFO: Pod "azurefile-volume-tester-pwjmq" satisfied condition "Succeeded or Failed"
Jul 22 12:03:54.815: INFO: deleting Pod "azurefile-493"/"azurefile-volume-tester-pwjmq"
Jul 22 12:03:54.846: INFO: Pod azurefile-volume-tester-pwjmq has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-pwjmq in namespace azurefile-493
Jul 22 12:03:54.881: INFO: deleting PVC "azurefile-493"/"pvc-n44n2"
Jul 22 12:03:54.881: INFO: Deleting PersistentVolumeClaim "pvc-n44n2"
... skipping 67 lines ...
check the driver pods if restarts ...
======================================================================================
2021/07/22 12:05:08 Check successfully
Jul 22 12:05:08.804: INFO: >>> kubeConfig: /root/tmp086438365/kubeconfig/kubeconfig.eastus.json
2021/07/22 12:05:08 run script: test/utils/get_storage_account_secret_name.sh
2021/07/22 12:05:09 got output: azure-storage-account-fd75972c40eda4aa8a9a776-secret
, error: <nil>
2021/07/22 12:05:09 got storage account secret name: azure-storage-account-fd75972c40eda4aa8a9a776-secret
STEP: Successfully provisioned AzureFile volume: "kubetest-l6rouidd#fd75972c40eda4aa8a9a776#csi-inline-smb-volume##csi-inline-smb-volume"

STEP: deploying the pod
STEP: checking that the pods command exits with no error
Jul 22 12:05:09.547: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-j2c2b" in namespace "azurefile-2818" to be "Succeeded or Failed"
Jul 22 12:05:09.575: INFO: Pod "azurefile-volume-tester-j2c2b": Phase="Pending", Reason="", readiness=false. Elapsed: 28.148397ms
Jul 22 12:05:11.605: INFO: Pod "azurefile-volume-tester-j2c2b": Phase="Running", Reason="", readiness=true. Elapsed: 2.058553287s
Jul 22 12:05:13.635: INFO: Pod "azurefile-volume-tester-j2c2b": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.088611435s
STEP: Saw pod success
Jul 22 12:05:13.635: INFO: Pod "azurefile-volume-tester-j2c2b" satisfied condition "Succeeded or Failed"
Jul 22 12:05:13.635: INFO: deleting Pod "azurefile-2818"/"azurefile-volume-tester-j2c2b"
Jul 22 12:05:13.694: INFO: Pod azurefile-volume-tester-j2c2b has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-j2c2b in namespace azurefile-2818
Jul 22 12:05:13.728: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "azurefile-2818" for this suite.
... skipping 40 lines ...
check the driver pods if restarts ...
======================================================================================
2021/07/22 12:05:15 Check successfully
Jul 22 12:05:15.640: INFO: >>> kubeConfig: /root/tmp086438365/kubeconfig/kubeconfig.eastus.json
STEP: creating secret smbcreds in namespace azurefile-5786
STEP: deploying the pod
STEP: checking that the pods command exits with no error
Jul 22 12:05:15.702: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-ndvtj" in namespace "azurefile-5786" to be "Succeeded or Failed"
Jul 22 12:05:15.730: INFO: Pod "azurefile-volume-tester-ndvtj": Phase="Pending", Reason="", readiness=false. Elapsed: 28.12739ms
Jul 22 12:05:17.760: INFO: Pod "azurefile-volume-tester-ndvtj": Phase="Pending", Reason="", readiness=false. Elapsed: 2.05783729s
Jul 22 12:05:19.790: INFO: Pod "azurefile-volume-tester-ndvtj": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.088363358s
STEP: Saw pod success
Jul 22 12:05:19.790: INFO: Pod "azurefile-volume-tester-ndvtj" satisfied condition "Succeeded or Failed"
Jul 22 12:05:19.790: INFO: deleting Pod "azurefile-5786"/"azurefile-volume-tester-ndvtj"
Jul 22 12:05:19.821: INFO: Pod azurefile-volume-tester-ndvtj has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-ndvtj in namespace azurefile-5786
Jul 22 12:05:19.856: INFO: deleting Secret smbcreds
Jul 22 12:05:19.890: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
... skipping 71 lines ...
Jul 22 12:06:52.258: INFO: PersistentVolumeClaim pvc-w8zt2 found but phase is Pending instead of Bound.
Jul 22 12:06:54.288: INFO: PersistentVolumeClaim pvc-w8zt2 found and phase=Bound (1m33.395803588s)
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
Jul 22 12:06:54.374: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-5c2j4" in namespace "azurefile-8844" to be "Succeeded or Failed"
Jul 22 12:06:54.404: INFO: Pod "azurefile-volume-tester-5c2j4": Phase="Pending", Reason="", readiness=false. Elapsed: 30.145695ms
Jul 22 12:06:56.435: INFO: Pod "azurefile-volume-tester-5c2j4": Phase="Pending", Reason="", readiness=false. Elapsed: 2.060341623s
Jul 22 12:06:58.464: INFO: Pod "azurefile-volume-tester-5c2j4": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.089824755s
STEP: Saw pod success
Jul 22 12:06:58.464: INFO: Pod "azurefile-volume-tester-5c2j4" satisfied condition "Succeeded or Failed"
Jul 22 12:06:58.464: INFO: deleting Pod "azurefile-8844"/"azurefile-volume-tester-5c2j4"
Jul 22 12:06:58.583: INFO: Pod azurefile-volume-tester-5c2j4 has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-5c2j4 in namespace azurefile-8844
Jul 22 12:06:58.619: INFO: deleting PVC "azurefile-8844"/"pvc-w8zt2"
Jul 22 12:06:58.619: INFO: Deleting PersistentVolumeClaim "pvc-w8zt2"
... skipping 74 lines ...
Jul 22 12:08:26.094: INFO: PersistentVolumeClaim pvc-h4jkm found but phase is Pending instead of Bound.
Jul 22 12:08:28.123: INFO: PersistentVolumeClaim pvc-h4jkm found and phase=Bound (1m23.239252254s)
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
Jul 22 12:08:28.210: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-jgbdw" in namespace "azurefile-90" to be "Succeeded or Failed"
Jul 22 12:08:28.238: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 28.012931ms
Jul 22 12:08:30.268: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 2.05798929s
Jul 22 12:08:32.298: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 4.087492023s
Jul 22 12:08:34.326: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 6.11597975s
Jul 22 12:08:36.361: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 8.150410589s
Jul 22 12:08:38.390: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 10.179378223s
Jul 22 12:08:40.420: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 12.209925763s
Jul 22 12:08:42.450: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 14.240089049s
Jul 22 12:08:44.480: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Pending", Reason="", readiness=false. Elapsed: 16.26997953s
Jul 22 12:08:46.510: INFO: Pod "azurefile-volume-tester-jgbdw": Phase="Succeeded", Reason="", readiness=false. Elapsed: 18.299597709s
STEP: Saw pod success
Jul 22 12:08:46.510: INFO: Pod "azurefile-volume-tester-jgbdw" satisfied condition "Succeeded or Failed"
Jul 22 12:08:46.510: INFO: deleting Pod "azurefile-90"/"azurefile-volume-tester-jgbdw"
Jul 22 12:08:46.629: INFO: Pod azurefile-volume-tester-jgbdw has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-jgbdw in namespace azurefile-90
Jul 22 12:08:46.672: INFO: deleting PVC "azurefile-90"/"pvc-h4jkm"
Jul 22 12:08:46.672: INFO: Deleting PersistentVolumeClaim "pvc-h4jkm"
... skipping 89 lines ...
Jul 22 12:09:03.863: INFO: PersistentVolumeClaim pvc-dj9fq found but phase is Pending instead of Bound.
Jul 22 12:09:05.892: INFO: PersistentVolumeClaim pvc-dj9fq found and phase=Bound (2.057464584s)
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
Jul 22 12:09:05.981: INFO: Waiting up to 15m0s for pod "azurefile-volume-tester-ngstb" in namespace "azurefile-7578" to be "Succeeded or Failed"
Jul 22 12:09:06.010: INFO: Pod "azurefile-volume-tester-ngstb": Phase="Pending", Reason="", readiness=false. Elapsed: 28.322292ms
Jul 22 12:09:08.040: INFO: Pod "azurefile-volume-tester-ngstb": Phase="Pending", Reason="", readiness=false. Elapsed: 2.058256564s
Jul 22 12:09:10.070: INFO: Pod "azurefile-volume-tester-ngstb": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.088921557s
STEP: Saw pod success
Jul 22 12:09:10.070: INFO: Pod "azurefile-volume-tester-ngstb" satisfied condition "Succeeded or Failed"
Jul 22 12:09:10.070: INFO: deleting Pod "azurefile-7578"/"azurefile-volume-tester-ngstb"
Jul 22 12:09:10.143: INFO: Pod azurefile-volume-tester-ngstb has the following logs: hello world

STEP: Deleting pod azurefile-volume-tester-ngstb in namespace azurefile-7578
Jul 22 12:09:10.178: INFO: deleting PVC "azurefile-7578"/"pvc-dj9fq"
Jul 22 12:09:10.178: INFO: Deleting PersistentVolumeClaim "pvc-dj9fq"
... skipping 162 lines ...
Git Commit: efc95e5065cdc603c26b1aed87923d2a7e710ede
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
I0722 11:50:21.915624       1 azure.go:60] reading cloud config from secret
E0722 11:50:21.947058       1 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0722 11:50:21.947085       1 azure.go:64] GetCloudProvider: failed to initialize cloud from secret kube-system/azure-cloud-provider: 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
I0722 11:50:21.947091       1 azure.go:69] could not read cloud config from secret
I0722 11:50:21.947101       1 azure.go:79] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0722 11:50:21.947124       1 azure.go:87] read cloud config from file: /etc/kubernetes/azure.json successfully
I0722 11:50:21.947759       1 azure_auth.go:234] Using AzurePublicCloud environment
I0722 11:50:21.947796       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0722 11:50:21.947812       1 azure.go:678] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 95 lines ...
Git Commit: efc95e5065cdc603c26b1aed87923d2a7e710ede
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
I0722 11:50:19.693778       1 azure.go:60] reading cloud config from secret
E0722 11:50:19.715648       1 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0722 11:50:19.715685       1 azure.go:64] GetCloudProvider: failed to initialize cloud from secret kube-system/azure-cloud-provider: 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
I0722 11:50:19.715691       1 azure.go:69] could not read cloud config from secret
I0722 11:50:19.715708       1 azure.go:79] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0722 11:50:19.715752       1 azure.go:87] read cloud config from file: /etc/kubernetes/azure.json successfully
I0722 11:50:19.717142       1 azure_auth.go:234] Using AzurePublicCloud environment
I0722 11:50:19.717208       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0722 11:50:19.717224       1 azure.go:678] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 473 lines ...
I0722 12:06:58.788024       1 utils.go:125] GRPC response: {}
I0722 12:07:04.881530       1 utils.go:118] GRPC call: /csi.v1.Controller/CreateVolume
I0722 12:07:04.881576       1 utils.go:119] GRPC request: {"capacity_range":{"required_bytes":107374182400},"name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","parameters":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","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}}]}
I0722 12:07:04.950123       1 azure_storageaccount.go:196] azure - no matching account found, begin to create a new account f4fd9b451d20d446fa0753b in resource group kubetest-l6rouidd, location: eastus, accountType: Premium_LRS, accountKind: FileStorage, tags: map[created-by:azure]
I0722 12:07:23.680724       1 azure_storageaccount.go:282] Creating private endpoint(f4fd9b451d20d446fa0753b-pvtendpoint) for account (f4fd9b451d20d446fa0753b)
I0722 12:07:54.925750       1 azure_storageaccount.go:316] Creating private dns zone(privatelink.file.core.windows.net) in resourceGroup (kubetest-l6rouidd)
I0722 12:08:25.635173       1 azure_privatednsclient.go:56] Received error while waiting for completion for privatedns.put.request, resourceGroup: kubetest-l6rouidd, error: Code="PreconditionFailed" Message="The Zone privatelink.file.core.windows.net exists already and hence cannot be created again."
I0722 12:08:25.635227       1 azure_storageaccount.go:321] private dns zone(privatelink.file.core.windows.net) in resourceGroup (kubetest-l6rouidd) already exists
I0722 12:08:25.635235       1 azure_storageaccount.go:329] Creating virtual link for vnet(f4fd9b451d20d446fa0753b-vnetlink) and DNS Zone(privatelink.file.core.windows.net) in resourceGroup(kubetest-l6rouidd)
I0722 12:08:26.496404       1 azure_storageaccount.go:342] Creating private DNS zone group(f4fd9b451d20d446fa0753b-dnszonegroup) with privateEndpoint(f4fd9b451d20d446fa0753b-pvtendpoint), vNetName(k8s-vnet-32360603), resourceGroup(kubetest-l6rouidd)
I0722 12:08:26.813778       1 controllerserver.go:342] begin to create file share(pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543) on account(f4fd9b451d20d446fa0753b) type(Premium_LRS) rg(kubetest-l6rouidd) location() size(100) protocol(NFS)
I0722 12:08:27.007471       1 controllerserver.go:364] create file share pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 on storage account f4fd9b451d20d446fa0753b successfully
I0722 12:08:27.007509       1 utils.go:125] GRPC response: {"volume":{"capacity_bytes":107374182400,"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}}
... skipping 123 lines ...
Git Commit: efc95e5065cdc603c26b1aed87923d2a7e710ede
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
I0722 11:50:16.894870       1 azure.go:60] reading cloud config from secret
E0722 11:50:16.916152       1 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0722 11:50:16.916179       1 azure.go:64] GetCloudProvider: failed to initialize cloud from secret kube-system/azure-cloud-provider: 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
I0722 11:50:16.916185       1 azure.go:69] could not read cloud config from secret
I0722 11:50:16.916193       1 azure.go:79] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0722 11:50:16.916215       1 azure.go:87] read cloud config from file: /etc/kubernetes/azure.json successfully
I0722 11:50:16.916820       1 azure_auth.go:234] Using AzurePublicCloud environment
I0722 11:50:16.916865       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0722 11:50:16.916883       1 azure.go:678] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 148 lines ...
W0722 12:00:45.877050       1 mount_helper_common.go:133] Warning: "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2bd33a16-f174-44e1-aee3-2314a22cf138/proxy-mount" is not a mountpoint, deleting
I0722 12:00:45.877117       1 nodeserver.go:311] NodeUnstageVolume: unmount volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-2bd33a16-f174-44e1-aee3-2314a22cf138#pvcd-2bd33a16-f174-44e1-aee3-2314a22cf138.vhd on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2bd33a16-f174-44e1-aee3-2314a22cf138/globalmount successfully
I0722 12:00:45.877132       1 utils.go:125] GRPC response: {}
I0722 12:05:09.756939       1 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0722 12:05:09.756959       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/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-j2c2b","csi.storage.k8s.io/pod.namespace":"azurefile-2818","csi.storage.k8s.io/pod.uid":"283efcc8-f333-4f7f-adef-b5dcd928f6cd","csi.storage.k8s.io/serviceAccount.name":"default","mountOptions":"cache=singleclient","secretName":"azure-storage-account-fd75972c40eda4aa8a9a776-secret","server":"","shareName":"csi-inline-smb-volume"},"volume_id":"csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2"}
I0722 12:05:09.757095       1 nodeserver.go:63] NodePublishVolume: ephemeral volume(csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2) mount on /var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/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-j2c2b csi.storage.k8s.io/pod.namespace:azurefile-2818 csi.storage.k8s.io/pod.uid:283efcc8-f333-4f7f-adef-b5dcd928f6cd csi.storage.k8s.io/serviceAccount.name:default getaccountkeyfromsecret:true mountOptions:cache=singleclient secretName:azure-storage-account-fd75972c40eda4aa8a9a776-secret secretnamespace:azurefile-2818 server: shareName:csi-inline-smb-volume storageaccount:]
W0722 12:05:09.757118       1 azurefile.go:492] parsing volumeID(csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2) return with error: error parsing volume id: "csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2", should at least contain two #
I0722 12:05:09.777286       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/volumes/kubernetes.io~csi/test-volume-1/mount) fstype() volumeID(csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2) context(map[csi.storage.k8s.io/ephemeral:true csi.storage.k8s.io/pod.name:azurefile-volume-tester-j2c2b csi.storage.k8s.io/pod.namespace:azurefile-2818 csi.storage.k8s.io/pod.uid:283efcc8-f333-4f7f-adef-b5dcd928f6cd csi.storage.k8s.io/serviceAccount.name:default getaccountkeyfromsecret:true mountOptions:cache=singleclient secretName:azure-storage-account-fd75972c40eda4aa8a9a776-secret secretnamespace:azurefile-2818 server: shareName:csi-inline-smb-volume storageaccount:]) mountflags([]) mountOptions([actimeo=30 cache=singleclient dir_mode=0777 file_mode=0777 mfsymlinks])
I0722 12:05:09.777393       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> //fd75972c40eda4aa8a9a776.file.core.windows.net/csi-inline-smb-volume /var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/volumes/kubernetes.io~csi/test-volume-1/mount)
I0722 12:05:09.916712       1 nodeserver.go:255] volume(csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2) mount "//fd75972c40eda4aa8a9a776.file.core.windows.net/csi-inline-smb-volume" on "/var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/volumes/kubernetes.io~csi/test-volume-1/mount" succeeded
I0722 12:05:09.916751       1 utils.go:125] GRPC response: {}
I0722 12:05:12.162831       1 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:05:12.162847       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/volumes/kubernetes.io~csi/test-volume-1/mount","volume_id":"csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2"}
... skipping 2 lines ...
W0722 12:05:12.183321       1 mount_helper_common.go:133] Warning: "/var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/volumes/kubernetes.io~csi/test-volume-1/mount" is not a mountpoint, deleting
I0722 12:05:12.183409       1 nodeserver.go:123] NodeUnpublishVolume: unmount volume csi-b882fa18ff440eb1a77e1128d79cb33440445a1934c651485f4ac9776170c0c2 on /var/lib/kubelet/pods/283efcc8-f333-4f7f-adef-b5dcd928f6cd/volumes/kubernetes.io~csi/test-volume-1/mount successfully
I0722 12:05:12.183434       1 utils.go:125] GRPC response: {}
I0722 12:05:15.973116       1 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0722 12:05:15.973173       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/88a3821b-acfa-4102-a64c-1f5e8c1ae03f/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-ndvtj","csi.storage.k8s.io/pod.namespace":"azurefile-5786","csi.storage.k8s.io/pod.uid":"88a3821b-acfa-4102-a64c-1f5e8c1ae03f","csi.storage.k8s.io/serviceAccount.name":"default","mountOptions":"cache=singleclient","secretName":"smbcreds","server":"smb-server.default.svc.cluster.local","shareName":"share"},"volume_id":"csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86"}
I0722 12:05:15.973297       1 nodeserver.go:63] NodePublishVolume: ephemeral volume(csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86) mount on /var/lib/kubelet/pods/88a3821b-acfa-4102-a64c-1f5e8c1ae03f/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-ndvtj csi.storage.k8s.io/pod.namespace:azurefile-5786 csi.storage.k8s.io/pod.uid:88a3821b-acfa-4102-a64c-1f5e8c1ae03f 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:]
W0722 12:05:15.973326       1 azurefile.go:492] parsing volumeID(csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86) return with error: error parsing volume id: "csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86", should at least contain two #
I0722 12:05:15.975794       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/pods/88a3821b-acfa-4102-a64c-1f5e8c1ae03f/volumes/kubernetes.io~csi/test-volume-1/mount) fstype() volumeID(csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86) context(map[csi.storage.k8s.io/ephemeral:true csi.storage.k8s.io/pod.name:azurefile-volume-tester-ndvtj csi.storage.k8s.io/pod.namespace:azurefile-5786 csi.storage.k8s.io/pod.uid:88a3821b-acfa-4102-a64c-1f5e8c1ae03f 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])
I0722 12:05:15.975863       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/88a3821b-acfa-4102-a64c-1f5e8c1ae03f/volumes/kubernetes.io~csi/test-volume-1/mount)
I0722 12:05:16.100046       1 nodeserver.go:255] volume(csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86) mount "//smb-server.default.svc.cluster.local/share" on "/var/lib/kubelet/pods/88a3821b-acfa-4102-a64c-1f5e8c1ae03f/volumes/kubernetes.io~csi/test-volume-1/mount" succeeded
I0722 12:05:16.100081       1 utils.go:125] GRPC response: {}
I0722 12:05:18.185220       1 utils.go:118] GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0722 12:05:18.185254       1 utils.go:119] GRPC request: {"target_path":"/var/lib/kubelet/pods/88a3821b-acfa-4102-a64c-1f5e8c1ae03f/volumes/kubernetes.io~csi/test-volume-1/mount","volume_id":"csi-6b2e3ede43ffe4815b3867d19014cf1ab7ec69d21c25c6e4be278050cffd3f86"}
... skipping 40 lines ...
Git Commit: efc95e5065cdc603c26b1aed87923d2a7e710ede
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
I0722 11:50:13.563956       1 azure.go:60] reading cloud config from secret
E0722 11:50:13.584111       1 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0722 11:50:13.584184       1 azure.go:64] GetCloudProvider: failed to initialize cloud from secret kube-system/azure-cloud-provider: 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
I0722 11:50:13.584203       1 azure.go:69] could not read cloud config from secret
I0722 11:50:13.584233       1 azure.go:79] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0722 11:50:13.584277       1 azure.go:87] read cloud config from file: /etc/kubernetes/azure.json successfully
I0722 11:50:13.585715       1 azure_auth.go:234] Using AzurePublicCloud environment
I0722 11:50:13.585784       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0722 11:50:13.585815       1 azure.go:678] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 63 lines ...
Git Commit: efc95e5065cdc603c26b1aed87923d2a7e710ede
Go Version: go1.16.6
Platform: linux/amd64

Streaming logs below:
I0722 11:50:17.627984       1 azure.go:60] reading cloud config from secret
E0722 11:50:17.646522       1 azure_config.go:45] Failed to get cloud-config from secret: failed to get secret kube-system/azure-cloud-provider: secrets "azure-cloud-provider" not found
I0722 11:50:17.646549       1 azure.go:64] GetCloudProvider: failed to initialize cloud from secret kube-system/azure-cloud-provider: 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
I0722 11:50:17.646555       1 azure.go:69] could not read cloud config from secret
I0722 11:50:17.646563       1 azure.go:79] use default AZURE_CREDENTIAL_FILE env var: /etc/kubernetes/azure.json
I0722 11:50:17.646585       1 azure.go:87] read cloud config from file: /etc/kubernetes/azure.json successfully
I0722 11:50:17.647190       1 azure_auth.go:234] Using AzurePublicCloud environment
I0722 11:50:17.647233       1 azure_auth.go:119] azure: using client_id+client_secret to retrieve access token
I0722 11:50:17.647251       1 azure.go:678] Azure cloudprovider using try backoff: retries=6, exponent=1.500000, duration=5, jitter=1.000000
... skipping 50 lines ...
I0722 11:50:18.734167       1 utils.go:125] GRPC response: {"name":"file.csi.azure.com","vendor_version":"e2e-efc95e5065cdc603c26b1aed87923d2a7e710ede"}
I0722 11:50:18.941977       1 utils.go:118] GRPC call: /csi.v1.Node/NodeGetInfo
I0722 11:50:18.941995       1 utils.go:119] GRPC request: {}
I0722 11:50:18.942035       1 utils.go:125] GRPC response: {"node_id":"k8s-agentpool1-32360603-0"}
I0722 11:50:47.788899       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:50:47.788921       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-ctw8r/globalmount","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":1}},"volume_id":"kubetest-l6rouidd#fd75972c40eda4aa8a9a776#pre-provisioned-readonly##pre-provisioned-readonly"}
I0722 11:50:47.792555       1 azurefile.go:556] could not get account(fd75972c40eda4aa8a9a776) key from secret(azure-storage-account-fd75972c40eda4aa8a9a776-secret), error: could not get secret(azure-storage-account-fd75972c40eda4aa8a9a776-secret): secrets "azure-storage-account-fd75972c40eda4aa8a9a776-secret" not found, use cluster identity to get account key instead
I0722 11:50:48.106235       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-ctw8r/globalmount) fstype() volumeID(kubetest-l6rouidd#fd75972c40eda4aa8a9a776#pre-provisioned-readonly##pre-provisioned-readonly) context(map[]) mountflags([]) mountOptions([file_mode=0777 dir_mode=0777 actimeo=30 mfsymlinks])
I0722 11:50:48.106510       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o file_mode=0777,dir_mode=0777,actimeo=30,mfsymlinks,<masked> //fd75972c40eda4aa8a9a776.file.core.windows.net/pre-provisioned-readonly /var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-ctw8r/globalmount)
I0722 11:50:48.657592       1 nodeserver.go:255] volume(kubetest-l6rouidd#fd75972c40eda4aa8a9a776#pre-provisioned-readonly##pre-provisioned-readonly) mount "//fd75972c40eda4aa8a9a776.file.core.windows.net/pre-provisioned-readonly" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/azurefile-8081-file.csi.azure.com-preprovsioned-pv-ctw8r/globalmount" succeeded
I0722 11:50:48.657651       1 utils.go:125] GRPC response: {}
I0722 11:50:48.664355       1 utils.go:118] GRPC call: /csi.v1.Node/NodePublishVolume
I0722 11:50:48.664373       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-ctw8r/globalmount","target_path":"/var/lib/kubelet/pods/7bcb629c-f643-4873-8be0-a7c8f9c8c737/volumes/kubernetes.io~csi/azurefile-8081-file.csi.azure.com-preprovsioned-pv-ctw8r/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-jl8k5","csi.storage.k8s.io/pod.namespace":"azurefile-8081","csi.storage.k8s.io/pod.uid":"7bcb629c-f643-4873-8be0-a7c8f9c8c737","csi.storage.k8s.io/serviceAccount.name":"default"},"volume_id":"kubetest-l6rouidd#fd75972c40eda4aa8a9a776#pre-provisioned-readonly##pre-provisioned-readonly"}
... skipping 252 lines ...
I0722 11:51:23.237800       1 nodeserver.go:311] NodeUnstageVolume: unmount volume kubetest-l6rouidd#fd75972c40eda4aa8a9a776#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-n5sg7/globalmount successfully
I0722 11:51:23.237809       1 utils.go:125] GRPC response: {}
I0722 11:53:29.202127       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:53:29.202157       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:53:29.223906       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:53:29.223971       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
E0722 11:53:29.281159       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)

E0722 11:53:29.281205       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I0722 11:53:29.802036       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:53:29.802055       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:53:29.802405       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:53:29.802479       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
E0722 11:53:29.831178       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)

E0722 11:53:29.831217       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I0722 11:53:30.905347       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:53:30.905611       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:53:30.905960       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:53:30.906116       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
E0722 11:53:30.930465       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)

E0722 11:53:30.930499       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I0722 11:53:33.016461       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:53:33.016480       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:53:33.016628       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:53:33.016670       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
E0722 11:53:33.047125       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)

E0722 11:53:33.047165       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I0722 11:53:37.128287       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:53:37.128311       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:53:37.128643       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:53:37.128736       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
E0722 11:53:37.149897       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)

E0722 11:53:37.149934       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I0722 11:53:45.168354       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:53:45.168374       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:53:45.168550       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:53:45.168601       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
E0722 11:53:45.191869       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)

E0722 11:53:45.191915       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount
Output: mount error(13): Permission denied
Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
I0722 11:54:01.214526       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:54:01.214570       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/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":{"csi.storage.k8s.io/pv/name":"pvc-2121e425-6322-457e-8760-4556d37e79f6","csi.storage.k8s.io/pvc/name":"pvc-fsb44","csi.storage.k8s.io/pvc/namespace":"azurefile-5541","enableLargeFileshares":"true","networkEndpointType":"privateEndpoint","secretName":"sercet-test","secretNamespace":"kube-system","secretnamespace":"kube-system","server":"fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net","skuName":"Standard_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#"}
I0722 11:54:01.214771       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount) fstype() volumeID(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) context(map[csi.storage.k8s.io/pv/name:pvc-2121e425-6322-457e-8760-4556d37e79f6 csi.storage.k8s.io/pvc/name:pvc-fsb44 csi.storage.k8s.io/pvc/namespace:azurefile-5541 enableLargeFileshares:true networkEndpointType:privateEndpoint secretName:sercet-test secretNamespace:kube-system secretnamespace:kube-system server:fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net skuName:Standard_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:54:01.214828       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> //fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount)
I0722 11:54:01.392469       1 nodeserver.go:255] volume(kubetest-l6rouidd#fbba8ae8d29a347fcb0ee24#pvc-2121e425-6322-457e-8760-4556d37e79f6#) mount "//fbba8ae8d29a347fcb0ee24.privatelink.file.core.windows.net/pvc-2121e425-6322-457e-8760-4556d37e79f6" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-2121e425-6322-457e-8760-4556d37e79f6/globalmount" succeeded
... skipping 214 lines ...
I0722 11:59:26.835345       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd])
I0722 11:59:26.922365       1 mount_linux.go:449] Output: ""
I0722 11:59:26.922389       1 mount_linux.go:408] Disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd" appears to be unformatted, attempting to format as type: "ext4" with options: [-F -m0 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd]
I0722 11:59:27.430933       1 mount_linux.go:418] Disk successfully formatted (mkfs): ext4 - /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:27.430965       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:27.430984       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount)
E0722 11:59:27.483335       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:27.483387       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"
I0722 11:59:28.060388       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:28.060454       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/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-df75b5df-e90b-4465-8361-0b0076b94410","csi.storage.k8s.io/pvc/name":"pvc-8kz4s","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"}
I0722 11:59:28.060640       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-df75b5df-e90b-4465-8361-0b0076b94410 csi.storage.k8s.io/pvc/name:pvc-8kz4s csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:28.070382       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount
I0722 11:59:28.070409       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:28.070462       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:28.070481       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd])
I0722 11:59:28.148701       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd\nTYPE=ext4\n"
I0722 11:59:28.148727       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd
I0722 11:59:28.256883       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:28.256916       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount)
E0722 11:59:28.284502       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:28.284620       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"
I0722 11:59:29.371353       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:29.371368       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/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-df75b5df-e90b-4465-8361-0b0076b94410","csi.storage.k8s.io/pvc/name":"pvc-8kz4s","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"}
I0722 11:59:29.371497       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-df75b5df-e90b-4465-8361-0b0076b94410 csi.storage.k8s.io/pvc/name:pvc-8kz4s csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:29.381259       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount
I0722 11:59:29.381290       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:29.381325       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:29.381344       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd])
I0722 11:59:29.465414       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd\nTYPE=ext4\n"
I0722 11:59:29.465441       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd
I0722 11:59:29.579277       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:29.579312       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount)
E0722 11:59:29.613405       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:29.613446       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"
I0722 11:59:31.673615       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:31.673639       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/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-df75b5df-e90b-4465-8361-0b0076b94410","csi.storage.k8s.io/pvc/name":"pvc-8kz4s","csi.storage.k8s.io/pvc/namespace":"azurefile-1577","diskname":"pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd","fsType":"ext4","secretnamespace":"azurefile-1577","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"}
I0722 11:59:31.673861       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-df75b5df-e90b-4465-8361-0b0076b94410 csi.storage.k8s.io/pvc/name:pvc-8kz4s csi.storage.k8s.io/pvc/namespace:azurefile-1577 diskname:pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd fsType:ext4 secretnamespace:azurefile-1577 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:31.683685       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount
I0722 11:59:31.683718       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:31.683757       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-df75b5df-e90b-4465-8361-0b0076b94410#pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:31.683776       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd])
I0722 11:59:31.763382       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd\nTYPE=ext4\n"
I0722 11:59:31.763415       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd
I0722 11:59:31.872456       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
I0722 11:59:31.872494       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount)
E0722 11:59:31.904305       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-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:31.904350       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-df75b5df-e90b-4465-8361-0b0076b94410/proxy-mount/pvcd-df75b5df-e90b-4465-8361-0b0076b94410.vhd"
I0722 11:59:42.406926       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:42.406943       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/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-7d0d9984-7bd2-48ba-9607-68dc80823a53","csi.storage.k8s.io/pvc/name":"pvc-pkpdc","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"}
I0722 11:59:42.407091       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 csi.storage.k8s.io/pvc/name:pvc-pkpdc csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:42.407157       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> //fcd9369a974ea4ad38ccc2f.file.core.windows.net/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount)
I0722 11:59:42.447995       1 nodeserver.go:255] volume(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd) mount "//fcd9369a974ea4ad38ccc2f.file.core.windows.net/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount" succeeded
I0722 11:59:42.448044       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:42.448058       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd])
I0722 11:59:42.534093       1 mount_linux.go:449] Output: ""
I0722 11:59:42.534119       1 mount_linux.go:408] Disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd" appears to be unformatted, attempting to format as type: "ext4" with options: [-F -m0 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd]
I0722 11:59:43.026431       1 mount_linux.go:418] Disk successfully formatted (mkfs): ext4 - /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:43.026462       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:43.026480       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount)
E0722 11:59:43.069722       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:43.069783       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"
I0722 11:59:43.610292       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:43.610328       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/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-7d0d9984-7bd2-48ba-9607-68dc80823a53","csi.storage.k8s.io/pvc/name":"pvc-pkpdc","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"}
I0722 11:59:43.610540       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 csi.storage.k8s.io/pvc/name:pvc-pkpdc csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:43.620048       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount
I0722 11:59:43.620083       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:43.620118       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:43.620137       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd])
I0722 11:59:43.701760       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd\nTYPE=ext4\n"
I0722 11:59:43.701787       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd
I0722 11:59:43.817800       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:43.817837       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount)
E0722 11:59:43.856001       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:43.856052       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"
I0722 11:59:44.914144       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:44.914165       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/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-7d0d9984-7bd2-48ba-9607-68dc80823a53","csi.storage.k8s.io/pvc/name":"pvc-pkpdc","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"}
I0722 11:59:44.914310       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 csi.storage.k8s.io/pvc/name:pvc-pkpdc csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:44.924383       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount
I0722 11:59:44.924411       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:44.924450       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:44.924467       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd])
I0722 11:59:45.006205       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd\nTYPE=ext4\n"
I0722 11:59:45.006239       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd
I0722 11:59:45.119792       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:45.119830       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount)
E0722 11:59:45.152666       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:45.152713       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"
I0722 11:59:47.222819       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:47.222840       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/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-7d0d9984-7bd2-48ba-9607-68dc80823a53","csi.storage.k8s.io/pvc/name":"pvc-pkpdc","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"}
I0722 11:59:47.222976       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 csi.storage.k8s.io/pvc/name:pvc-pkpdc csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:47.232990       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount
I0722 11:59:47.233018       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:47.233050       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:47.233100       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd])
I0722 11:59:47.313232       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd\nTYPE=ext4\n"
I0722 11:59:47.313260       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd
I0722 11:59:47.425225       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:47.425261       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount)
E0722 11:59:47.462278       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:47.462553       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"
I0722 11:59:51.538348       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 11:59:51.538371       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/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-7d0d9984-7bd2-48ba-9607-68dc80823a53","csi.storage.k8s.io/pvc/name":"pvc-pkpdc","csi.storage.k8s.io/pvc/namespace":"azurefile-953","diskname":"pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd","fsType":"ext4","secretnamespace":"azurefile-953","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"}
I0722 11:59:51.538574       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount) fstype(ext4) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53 csi.storage.k8s.io/pvc/name:pvc-pkpdc csi.storage.k8s.io/pvc/namespace:azurefile-953 diskname:pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd fsType:ext4 secretnamespace:azurefile-953 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-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])
I0722 11:59:51.548591       1 nodeserver.go:419] already mounted to target /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount
I0722 11:59:51.548616       1 nodeserver.go:241] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd is already mounted on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:51.548653       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53#pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd with mount options([barrier=1 errors=remount-ro invalid loop mount noatime options])
I0722 11:59:51.548666       1 mount_linux.go:446] Attempting to determine if disk "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd])
I0722 11:59:51.632718       1 mount_linux.go:449] Output: "DEVNAME=/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd\nTYPE=ext4\n"
I0722 11:59:51.632745       1 mount_linux.go:340] Checking for issues with fsck on disk: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd
I0722 11:59:51.753094       1 mount_linux.go:436] Attempting to mount disk /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
I0722 11:59:51.753126       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount)
E0722 11:59:51.789895       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-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount
Output: mount: /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount: wrong fs type, bad option, bad superblock on /dev/loop0, missing codepage or helper program, or other error.

E0722 11:59:51.789940       1 utils.go:123] GRPC error: rpc error: code = Internal desc = could not format "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/globalmount" and mount it at "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-7d0d9984-7bd2-48ba-9607-68dc80823a53/proxy-mount/pvcd-7d0d9984-7bd2-48ba-9607-68dc80823a53.vhd"
I0722 12:00:03.176421       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:00:03.176734       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-8a3838f8-ee1b-4797-afb9-1a673b648492/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"xfs"}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-8a3838f8-ee1b-4797-afb9-1a673b648492","csi.storage.k8s.io/pvc/name":"pvc-8z2sc","csi.storage.k8s.io/pvc/namespace":"azurefile-3033","diskname":"pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd","fsType":"xfs","secretnamespace":"azurefile-3033","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd"}
I0722 12:00:03.177296       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-8a3838f8-ee1b-4797-afb9-1a673b648492/proxy-mount) fstype(xfs) volumeID(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd) context(map[csi.storage.k8s.io/pv/name:pvc-8a3838f8-ee1b-4797-afb9-1a673b648492 csi.storage.k8s.io/pvc/name:pvc-8z2sc csi.storage.k8s.io/pvc/namespace:azurefile-3033 diskname:pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd fsType:xfs secretnamespace:azurefile-3033 skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([]) mountOptions([dir_mode=0777,file_mode=0777,cache=strict,actimeo=30 nostrictsync actimeo=30 mfsymlinks file_mode=0777])
I0722 12:00:03.177626       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t cifs -o dir_mode=0777,file_mode=0777,cache=strict,actimeo=30,nostrictsync,actimeo=30,mfsymlinks,file_mode=0777,<masked> //fcd9369a974ea4ad38ccc2f.file.core.windows.net/pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-8a3838f8-ee1b-4797-afb9-1a673b648492/proxy-mount)
I0722 12:00:03.222034       1 nodeserver.go:255] volume(kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd) mount "//fcd9369a974ea4ad38ccc2f.file.core.windows.net/pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-8a3838f8-ee1b-4797-afb9-1a673b648492/proxy-mount" succeeded
I0722 12:00:03.222086       1 nodeserver.go:275] NodeStageVolume: volume kubetest-l6rouidd#fcd9369a974ea4ad38ccc2f#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492#pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd formatting /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-8a3838f8-ee1b-4797-afb9-1a673b648492/globalmount and mounting at /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-8a3838f8-ee1b-4797-afb9-1a673b648492/proxy-mount/pvcd-8a3838f8-ee1b-4797-afb9-1a673b648492.vhd with mount options([loop])
... skipping 419 lines ...
I0722 12:06:56.914528       1 nodeserver.go:311] NodeUnstageVolume: unmount volume kubetest-l6rouidd#f8967c6d906eb4b1f83e8ae#pvcn-c2d3c296-dba9-440d-926e-37163be9f60a# on /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-c2d3c296-dba9-440d-926e-37163be9f60a/globalmount successfully
I0722 12:06:56.914536       1 utils.go:125] GRPC response: {}
I0722 12:08:28.467536       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:08:28.467554       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}
I0722 12:08:28.467676       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount) fstype() volumeID(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) context(map[csi.storage.k8s.io/pv/name:pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 csi.storage.k8s.io/pvc/name:pvc-h4jkm csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576])
I0722 12:08:28.467720       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount)
E0722 12:08:28.549871       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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known

E0722 12:08:28.549931       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) mount "f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known
I0722 12:08:29.055646       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:08:29.055676       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}
I0722 12:08:29.055860       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount) fstype() volumeID(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) context(map[csi.storage.k8s.io/pv/name:pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 csi.storage.k8s.io/pvc/name:pvc-h4jkm csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576])
I0722 12:08:29.055926       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount)
E0722 12:08:29.067910       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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known

E0722 12:08:29.067944       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) mount "f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known
I0722 12:08:30.159027       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:08:30.159057       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}
I0722 12:08:30.159607       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount) fstype() volumeID(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) context(map[csi.storage.k8s.io/pv/name:pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 csi.storage.k8s.io/pvc/name:pvc-h4jkm csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576])
I0722 12:08:30.159685       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount)
E0722 12:08:30.170618       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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known

E0722 12:08:30.170652       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) mount "f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known
I0722 12:08:32.264795       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:08:32.264814       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}
I0722 12:08:32.264940       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount) fstype() volumeID(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) context(map[csi.storage.k8s.io/pv/name:pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 csi.storage.k8s.io/pvc/name:pvc-h4jkm csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576])
I0722 12:08:32.264990       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount)
E0722 12:08:32.274492       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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known

E0722 12:08:32.274526       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) mount "f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known
I0722 12:08:36.288361       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:08:36.288390       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}
I0722 12:08:36.288777       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount) fstype() volumeID(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) context(map[csi.storage.k8s.io/pv/name:pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 csi.storage.k8s.io/pvc/name:pvc-h4jkm csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576])
I0722 12:08:36.289030       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount)
E0722 12:08:36.300217       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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known

E0722 12:08:36.300251       1 utils.go:123] GRPC error: rpc error: code = Internal desc = volume(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) mount "f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/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 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount
Output: mount.nfs: Failed to resolve server f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net: Name or service not known
I0722 12:08:44.315969       1 utils.go:118] GRPC call: /csi.v1.Node/NodeStageVolume
I0722 12:08:44.315997       1 utils.go:119] GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount","volume_capability":{"AccessType":{"Mount":{"mount_flags":["rsize=1048576","wsize=1048576"]}},"access_mode":{"mode":1}},"volume_context":{"csi.storage.k8s.io/pv/name":"pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543","csi.storage.k8s.io/pvc/name":"pvc-h4jkm","csi.storage.k8s.io/pvc/namespace":"azurefile-90","networkEndpointType":"privateEndpoint","protocol":"nfs","secretnamespace":"azurefile-90","server":"f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net","skuName":"Premium_LRS","storage.kubernetes.io/csiProvisionerIdentity":"1626954620472-8081-file.csi.azure.com"},"volume_id":"kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#"}
I0722 12:08:44.316188       1 nodeserver.go:234] cifsMountPath(/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount) fstype() volumeID(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) context(map[csi.storage.k8s.io/pv/name:pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 csi.storage.k8s.io/pvc/name:pvc-h4jkm csi.storage.k8s.io/pvc/namespace:azurefile-90 networkEndpointType:privateEndpoint protocol:nfs secretnamespace:azurefile-90 server:f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net skuName:Premium_LRS storage.kubernetes.io/csiProvisionerIdentity:1626954620472-8081-file.csi.azure.com]) mountflags([rsize=1048576 wsize=1048576]) mountOptions([rsize=1048576 vers=4,minorversion=1,sec=sys wsize=1048576])
I0722 12:08:44.316264       1 mount_linux.go:175] Mounting cmd (mount) with arguments (-t nfs -o rsize=1048576,vers=4,minorversion=1,sec=sys,wsize=1048576 f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543 /var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount)
I0722 12:08:44.438376       1 nodeserver.go:255] volume(kubetest-l6rouidd#f4fd9b451d20d446fa0753b#pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543#) mount "f4fd9b451d20d446fa0753b.privatelink.file.core.windows.net:/f4fd9b451d20d446fa0753b/pvcn-0bfbef12-9b32-428b-bbfd-0c80d1fe6543" on "/var/lib/kubelet/plugins/kubernetes.io/csi/pv/pvc-0bfbef12-9b32-428b-bbfd-0c80d1fe6543/globalmount" succeeded
I0722 12:08:44.438408       1 utils.go:125] GRPC response: {}
... skipping 420 lines ...
Uninstalled Azure File CSI driver successfully.
2021/07/22 12:10:22 ===================================================

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

Ran 31 of 34 Specs in 1234.743 seconds
SUCCESS! -- 31 Passed | 0 Failed | 0 Pending | 3 Skipped
--- PASS: TestE2E (1234.74s)
PASS
ok  	sigs.k8s.io/azurefile-csi-driver/test/e2e	1234.786s
2021/07/22 12:10:22 process.go:155: Step 'make e2e-test' finished in 22m1.793034507s
2021/07/22 12:10:22 aksengine_helpers.go:425: downloading /root/tmp086438365/log-dump.sh from https://raw.githubusercontent.com/kubernetes-sigs/cloud-provider-azure/master/hack/log-dump/log-dump.sh
2021/07/22 12:10:22 util.go:68: curl https://raw.githubusercontent.com/kubernetes-sigs/cloud-provider-azure/master/hack/log-dump/log-dump.sh
... skipping 60 lines ...