This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: chore: fix broken post-csi-driver-smb-push-images pipeline
ResultABORTED
Tests 0 failed / 8 succeeded
Started2022-04-30 12:43
Elapsed36m11s
Revision2e8e6b4e79e67f4d4fc2531955b821074ae7d73d
Refs 465

No Test Failures!


Show 8 Passed Tests

Show 2 Skipped Tests

Error lines from build-log.txt

... skipping 76 lines ...

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 11156  100 11156    0     0   201k      0 --:--:-- --:--:-- --:--:--  201k
Downloading https://get.helm.sh/helm-v3.8.2-linux-amd64.tar.gz
Verifying checksum... Done.
Preparing to install helm into /usr/local/bin
helm installed into /usr/local/bin/helm
docker pull k8sprow.azurecr.io/smb-csi:e2e-6ac2ca216f53524c34e5d31b08be5dc30086febe || make container-all push-manifest
Error response from daemon: manifest for k8sprow.azurecr.io/smb-csi:e2e-6ac2ca216f53524c34e5d31b08be5dc30086febe not found: manifest unknown: manifest tagged by "e2e-6ac2ca216f53524c34e5d31b08be5dc30086febe" is not found
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/csi-driver-smb'
CGO_ENABLED=0 GOOS=windows go build -a -ldflags "" -mod vendor -o _output/amd64/smbplugin.exe ./cmd/smbplugin
docker buildx rm container-builder || true
error: no builder "container-builder" found
docker buildx create --use --name=container-builder
container-builder
# enable qemu for arm64 build
# https://github.com/docker/buildx/issues/464#issuecomment-741507760
docker run --privileged --rm tonistiigi/binfmt --uninstall qemu-aarch64,arm
Unable to find image 'tonistiigi/binfmt:latest' locally
... skipping 2613 lines ...
Apr 30 13:10:20.606: INFO: PersistentVolumeClaim pvc-nchz5 found but phase is Pending instead of Bound.
Apr 30 13:10:22.712: INFO: PersistentVolumeClaim pvc-nchz5 found and phase=Bound (2.211880895s)
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
Apr 30 13:10:23.031: INFO: Waiting up to 15m0s for pod "smb-volume-tester-vt2qs" in namespace "smb-1318" to be "Succeeded or Failed"
Apr 30 13:10:23.136: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 104.5996ms
Apr 30 13:10:25.241: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 2.209844469s
Apr 30 13:10:27.346: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 4.314767327s
Apr 30 13:10:29.451: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 6.419366188s
Apr 30 13:10:31.555: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 8.523973062s
Apr 30 13:10:33.661: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 10.630247597s
... skipping 35 lines ...
Apr 30 13:11:49.466: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 1m26.435020672s
Apr 30 13:11:51.571: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 1m28.539620104s
Apr 30 13:11:53.676: INFO: Pod "smb-volume-tester-vt2qs": Phase="Pending", Reason="", readiness=false. Elapsed: 1m30.644489503s
Apr 30 13:11:55.783: INFO: Pod "smb-volume-tester-vt2qs": Phase="Running", Reason="", readiness=true. Elapsed: 1m32.751726412s
Apr 30 13:11:57.891: INFO: Pod "smb-volume-tester-vt2qs": Phase="Succeeded", Reason="", readiness=false. Elapsed: 1m34.860290126s
STEP: Saw pod success
Apr 30 13:11:57.891: INFO: Pod "smb-volume-tester-vt2qs" satisfied condition "Succeeded or Failed"
Apr 30 13:11:57.891: INFO: deleting Pod "smb-1318"/"smb-volume-tester-vt2qs"
Apr 30 13:11:58.047: INFO: Pod smb-volume-tester-vt2qs has the following logs: hello world

STEP: Deleting pod smb-volume-tester-vt2qs in namespace smb-1318
Apr 30 13:11:58.178: INFO: deleting PVC "smb-1318"/"pvc-nchz5"
Apr 30 13:11:58.178: INFO: Deleting PersistentVolumeClaim "pvc-nchz5"
... skipping 395 lines ...
Apr 30 13:14:16.267: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-2kjm9] to have phase Bound
Apr 30 13:14:16.370: INFO: PersistentVolumeClaim pvc-2kjm9 found and phase=Bound (102.877598ms)
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
Apr 30 13:14:16.695: INFO: Waiting up to 15m0s for pod "smb-volume-tester-c4s86" in namespace "smb-3090" to be "Succeeded or Failed"
Apr 30 13:14:16.798: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 103.588204ms
Apr 30 13:14:18.904: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 2.209205768s
Apr 30 13:14:21.009: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 4.314420213s
Apr 30 13:14:23.114: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 6.419454462s
Apr 30 13:14:25.218: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 8.523124399s
Apr 30 13:14:27.324: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 10.628907195s
Apr 30 13:14:29.429: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 12.733986529s
Apr 30 13:14:31.534: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 14.839634357s
Apr 30 13:14:33.644: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 16.949640774s
Apr 30 13:14:35.757: INFO: Pod "smb-volume-tester-c4s86": Phase="Pending", Reason="", readiness=false. Elapsed: 19.062395586s
Apr 30 13:14:37.865: INFO: Pod "smb-volume-tester-c4s86": Phase="Succeeded", Reason="", readiness=false. Elapsed: 21.169849695s
STEP: Saw pod success
Apr 30 13:14:37.865: INFO: Pod "smb-volume-tester-c4s86" satisfied condition "Succeeded or Failed"
Apr 30 13:14:37.865: INFO: deleting Pod "smb-3090"/"smb-volume-tester-c4s86"
Apr 30 13:14:37.977: INFO: Pod smb-volume-tester-c4s86 has the following logs: hello world

STEP: Deleting pod smb-volume-tester-c4s86 in namespace smb-3090
Apr 30 13:14:38.113: INFO: deleting PVC "smb-3090"/"pvc-2kjm9"
Apr 30 13:14:38.113: INFO: Deleting PersistentVolumeClaim "pvc-2kjm9"
... skipping 74 lines ...
Apr 30 13:14:43.364: INFO: Waiting up to timeout=5m0s for PersistentVolumeClaims [pvc-wq82j] to have phase Bound
Apr 30 13:14:43.475: INFO: PersistentVolumeClaim pvc-wq82j found and phase=Bound (111.247635ms)
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
Apr 30 13:14:43.790: INFO: Waiting up to 15m0s for pod "smb-volume-tester-4747z" in namespace "smb-4078" to be "Succeeded or Failed"
Apr 30 13:14:43.894: INFO: Pod "smb-volume-tester-4747z": Phase="Pending", Reason="", readiness=false. Elapsed: 103.524332ms
Apr 30 13:14:46.000: INFO: Pod "smb-volume-tester-4747z": Phase="Pending", Reason="", readiness=false. Elapsed: 2.209515835s
Apr 30 13:14:48.107: INFO: Pod "smb-volume-tester-4747z": Phase="Pending", Reason="", readiness=false. Elapsed: 4.316465783s
Apr 30 13:14:50.212: INFO: Pod "smb-volume-tester-4747z": Phase="Pending", Reason="", readiness=false. Elapsed: 6.421927003s
Apr 30 13:14:52.318: INFO: Pod "smb-volume-tester-4747z": Phase="Pending", Reason="", readiness=false. Elapsed: 8.527440128s
Apr 30 13:14:54.422: INFO: Pod "smb-volume-tester-4747z": Phase="Pending", Reason="", readiness=false. Elapsed: 10.631885197s
Apr 30 13:14:56.528: INFO: Pod "smb-volume-tester-4747z": Phase="Succeeded", Reason="", readiness=false. Elapsed: 12.737767851s
STEP: Saw pod success
Apr 30 13:14:56.528: INFO: Pod "smb-volume-tester-4747z" satisfied condition "Succeeded or Failed"
Apr 30 13:14:56.528: INFO: deleting Pod "smb-4078"/"smb-volume-tester-4747z"
Apr 30 13:14:56.644: INFO: Pod smb-volume-tester-4747z has the following logs: hello world

STEP: Deleting pod smb-volume-tester-4747z in namespace smb-4078
Apr 30 13:14:56.767: INFO: deleting PVC "smb-4078"/"pvc-wq82j"
Apr 30 13:14:56.767: INFO: Deleting PersistentVolumeClaim "pvc-wq82j"
... skipping 733 lines ...
  Normal   Pulling    5m27s  kubelet            Pulling image "registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0"
  Normal   Pulled     5m22s  kubelet            Successfully pulled image "registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0" in 5.0824515s
  Normal   Pulling    5m20s  kubelet            Pulling image "k8sprow.azurecr.io/smb-csi:e2e-6ac2ca216f53524c34e5d31b08be5dc30086febe"
  Normal   Pulled     4m52s  kubelet            Successfully pulled image "k8sprow.azurecr.io/smb-csi:e2e-6ac2ca216f53524c34e5d31b08be5dc30086febe" in 27.6315252s
  Normal   Created    4m52s  kubelet            Created container smb
  Normal   Started    4m51s  kubelet            Started container smb
  Warning  Unhealthy  4m13s  kubelet            Liveness probe failed: F0430 13:10:48.034688    6424 main.go:159] Kubelet plugin registration hasn't succeeded yet, file=C:\var\lib\kubelet\plugins\smb.csi.k8s.io\registration doesn't exist.
goroutine 1 [running]:
k8s.io/klog/v2.stacks(0x1)
  /workspace/vendor/k8s.io/klog/v2/klog.go:1038 +0x8a
k8s.io/klog/v2.(*loggingT).output(0x1c11b60, 0x3, 0x0, 0xc00023ecb0, 0x0, {0x18d2787, 0x1}, 0xc0002c2db0, 0x0)
  /workspace/vendor/k8s.io/klog/v2/klog.go:987 +0x5fd
k8s.io/klog/v2.(*loggingT).printf(0x16e5ee0, 0x4, 0x0, {0x0, 0x0}, {0x170ef3b, 0x48}, {0xc0002c2db0, 0x1, 0x1})
... skipping 5 lines ...

goroutine 5 [chan receive]:
k8s.io/klog/v2.(*loggingT).flushDaemon(0xc0000d8960)
  /workspace/vendor/k8s.io/klog/v2/klog.go:1181 +0x6a
created by k8s.io/klog/v2.init.0
           /workspace/vendor/k8s.io/klog/v2/klog.go:420 +0xfb
  Warning  Unhealthy  4m3s  kubelet  Liveness probe failed: F0430 13:10:57.951145    6580 main.go:159] Kubelet plugin registration hasn't succeeded yet, file=C:\var\lib\kubelet\plugins\smb.csi.k8s.io\registration doesn't exist.
goroutine 1 [running]:
k8s.io/klog/v2.stacks(0x1)
  /workspace/vendor/k8s.io/klog/v2/klog.go:1038 +0x8a
k8s.io/klog/v2.(*loggingT).output(0x1c11b60, 0x3, 0x0, 0xc0000fec40, 0x0, {0x18d2787, 0x1}, 0xc000296d90, 0x0)
  /workspace/vendor/k8s.io/klog/v2/klog.go:987 +0x5fd
k8s.io/klog/v2.(*loggingT).printf(0x16e5ee0, 0x4, 0x0, {0x0, 0x0}, {0x170ef3b, 0x48}, {0xc000296d90, 0x1, 0x1})
... skipping 6 lines ...
goroutine 18 [chan receive]:
k8s.io/klog/v2.(*loggingT).flushDaemon(0x0)
  /workspace/vendor/k8s.io/klog/v2/klog.go:1181 +0x6a
created by k8s.io/klog/v2.init.0
           /workspace/vendor/k8s.io/klog/v2/klog.go:420 +0xfb
  Normal   Created    3m53s (x2 over 5m22s)  kubelet  Created container node-driver-registrar
  Warning  Unhealthy  3m53s                  kubelet  Liveness probe failed: F0430 13:11:07.954725    6792 main.go:159] Kubelet plugin registration hasn't succeeded yet, file=C:\var\lib\kubelet\plugins\smb.csi.k8s.io\registration doesn't exist.
goroutine 1 [running]:
k8s.io/klog/v2.stacks(0x1)
  /workspace/vendor/k8s.io/klog/v2/klog.go:1038 +0x8a
k8s.io/klog/v2.(*loggingT).output(0x1c11b60, 0x3, 0x0, 0xc0001eccb0, 0x0, {0x18d2787, 0x1}, 0xc00024c540, 0x0)
  /workspace/vendor/k8s.io/klog/v2/klog.go:987 +0x5fd
k8s.io/klog/v2.(*loggingT).printf(0x16e5ee0, 0x4, 0x0, {0x0, 0x0}, {0x170ef3b, 0x48}, {0xc00024c540, 0x1, 0x1})
... skipping 5 lines ...

goroutine 7 [chan receive]:
k8s.io/klog/v2.(*loggingT).flushDaemon(0xc0000a4000)
  /workspace/vendor/k8s.io/klog/v2/klog.go:1181 +0x6a
created by k8s.io/klog/v2.init.0
          /workspace/vendor/k8s.io/klog/v2/klog.go:420 +0xfb
  Normal  Killing  3m53s                  kubelet  Container node-driver-registrar failed liveness probe, will be restarted
  Normal  Pulled   3m53s                  kubelet  Container image "registry.k8s.io/sig-storage/csi-node-driver-registrar:v2.5.0" already present on machine
  Normal  Started  3m52s (x2 over 5m20s)  kubelet  Started container node-driver-registrar
print out csi-smb-node-win logs ...
======================================================================================
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:10:16.435377    5936 main.go:90] set up prometheus server on [::]:29645
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:10:16.806959    5936 smb.go:80] 
... skipping 420 lines ...
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:19.102706    5936 safe_mounter_windows.go:93] begin to replace hostname(smb-server.default.svc.cluster.local) with IP for source(//smb-server.default.svc.cluster.local/share)
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:19.103239    5936 safe_mounter_windows.go:98] resolve the name of host smb-server.default.svc.cluster.local to IPv4 address: 10.0.108.247
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:19.103239    5936 safe_mounter_windows.go:111] begin to mount \\10.0.108.247\share on c:\var\lib\kubelet\plugins\kubernetes.io\csi\pv\pvc-53dc3d5a-5cd7-437b-8b71-cef6719f72fb\globalmount
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.220508    5936 safe_mounter_windows.go:115] mount \\10.0.108.247\share on c:\var\lib\kubelet\plugins\kubernetes.io\csi\pv\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\globalmount successfully
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.220508    5936 nodeserver.go:223] volume(smb-server.default.svc.cluster.local#share#pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a) mount "//smb-server.default.svc.cluster.local/share" on "\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\\globalmount" succeeded
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.220508    5936 utils.go:83] GRPC response: {}
[pod/csi-smb-node-win-m6nhr/smb] E0430 13:14:25.223549    5936 utils.go:81] GRPC error: rpc error: code = Internal desc = volume(smb-server.default.svc.cluster.local#share#pvc-e11952e3-1058-4a03-abe8-d5823cfeb960) mount "//smb-server.default.svc.cluster.local/share" on "\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-e11952e3-1058-4a03-abe8-d5823cfeb960\\globalmount" failed with smb mapping failed with error: rpc error: code = Unknown desc = NewSmbGlobalMapping failed. output: "New-SmbGlobalMapping : Generic failure \r\nAt line:1 char:190\r\n+ ... ser, $PWord;New-SmbGlobalMapping -RemotePath $Env:smbremotepath -Cred ...\r\n+                 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\r\n    + CategoryInfo          : NotSpecified: (MSFT_SmbGlobalMapping:ROOT/Microsoft/...mbGlobalMapping) [New-SmbGlobalMa \r\n   pping], CimException\r\n    + FullyQualifiedErrorId : HRESULT 0x80041001,New-SmbGlobalMapping\r\n \r\n", err: exit status 1
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.243593    5936 utils.go:76] GRPC call: /csi.v1.Node/NodeGetCapabilities
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.243593    5936 utils.go:77] GRPC request: {}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.243593    5936 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":6}}}]}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.245408    5936 utils.go:76] GRPC call: /csi.v1.Node/NodeGetCapabilities
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.245408    5936 utils.go:77] GRPC request: {}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.245408    5936 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":6}}}]}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.247177    5936 utils.go:76] GRPC call: /csi.v1.Node/NodePublishVolume
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.247177    5936 utils.go:77] GRPC request: {"staging_target_path":"\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\\globalmount","target_path":"c:\\var\\lib\\kubelet\\pods\\33c83b76-9dff-4040-a35a-eb3eda638fbb\\volumes\\kubernetes.io~csi\\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\\mount","volume_capability":{"AccessType":{"Mount":{}},"access_mode":{"mode":7}},"volume_context":{"csi.storage.k8s.io/ephemeral":"false","csi.storage.k8s.io/pod.name":"smb-volume-tester-c4s86","csi.storage.k8s.io/pod.namespace":"smb-3090","csi.storage.k8s.io/pod.uid":"33c83b76-9dff-4040-a35a-eb3eda638fbb","csi.storage.k8s.io/serviceAccount.name":"default","source":"//smb-server.default.svc.cluster.local/","storage.kubernetes.io/csiProvisionerIdentity":"1651324184483-8081-smb.csi.k8s.io","subDir":"share"},"volume_id":"smb-server.default.svc.cluster.local#share#pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a"}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.247177    5936 safe_mounter_windows.go:181] IsLikelyNotMountPoint: c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.247177    5936 safe_mounter_windows.go:238] Exists path: c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount
[pod/csi-smb-node-win-m6nhr/smb] E0430 13:14:25.248331    5936 utils.go:81] GRPC error: rpc error: code = Internal desc = volume(smb-server.default.svc.cluster.local#share#pvc-21197475-ddb8-41be-955a-636e5a61f51a) mount "//smb-server.default.svc.cluster.local/share" on "\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-21197475-ddb8-41be-955a-636e5a61f51a\\globalmount" failed with smb mapping failed with error: rpc error: code = Unknown desc = NewSmbGlobalMapping failed. output: "New-SmbGlobalMapping : Generic failure \r\nAt line:1 char:190\r\n+ ... ser, $PWord;New-SmbGlobalMapping -RemotePath $Env:smbremotepath -Cred ...\r\n+                 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\r\n    + CategoryInfo          : NotSpecified: (MSFT_SmbGlobalMapping:ROOT/Microsoft/...mbGlobalMapping) [New-SmbGlobalMa \r\n   pping], CimException\r\n    + FullyQualifiedErrorId : HRESULT 0x80041001,New-SmbGlobalMapping\r\n \r\n", err: exit status 1
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.250156    5936 safe_mounter_windows.go:238] Exists path: c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.251124    5936 smb_common_windows.go:74] Removing path: c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.251124    5936 safe_mounter_windows.go:151] Remove directory: c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.251427    5936 nodeserver.go:78] NodePublishVolume: mounting \var\lib\kubelet\plugins\kubernetes.io\csi\pv\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\globalmount at c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount with mountOptions: [bind] volumeID(smb-server.default.svc.cluster.local#share#pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a)
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.251427    5936 safe_mounter_windows.go:128] Mount: old name: \var\lib\kubelet\plugins\kubernetes.io\csi\pv\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\globalmount. new name: c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.283300    5936 nodeserver.go:85] NodePublishVolume: mount \var\lib\kubelet\plugins\kubernetes.io\csi\pv\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\globalmount at c:\var\lib\kubelet\pods\33c83b76-9dff-4040-a35a-eb3eda638fbb\volumes\kubernetes.io~csi\pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a\mount volumeID(smb-server.default.svc.cluster.local#share#pvc-7af1c262-ab92-49fa-8a90-cd0fa937b02a) successfully
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.283300    5936 utils.go:83] GRPC response: {}
[pod/csi-smb-node-win-m6nhr/smb] E0430 13:14:25.312783    5936 utils.go:81] GRPC error: rpc error: code = Internal desc = volume(smb-server.default.svc.cluster.local#share#pvc-01b011df-b74e-4a0d-8599-e05eb7490de8) mount "//smb-server.default.svc.cluster.local/share" on "\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-01b011df-b74e-4a0d-8599-e05eb7490de8\\globalmount" failed with smb mapping failed with error: rpc error: code = Unknown desc = NewSmbGlobalMapping failed. output: "New-SmbGlobalMapping : Generic failure \r\nAt line:1 char:190\r\n+ ... ser, $PWord;New-SmbGlobalMapping -RemotePath $Env:smbremotepath -Cred ...\r\n+                 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\r\n    + CategoryInfo          : NotSpecified: (MSFT_SmbGlobalMapping:ROOT/Microsoft/...mbGlobalMapping) [New-SmbGlobalMa \r\n   pping], CimException\r\n    + FullyQualifiedErrorId : HRESULT 0x80041001,New-SmbGlobalMapping\r\n \r\n", err: exit status 1
[pod/csi-smb-node-win-m6nhr/smb] E0430 13:14:25.399064    5936 utils.go:81] GRPC error: rpc error: code = Internal desc = volume(smb-server.default.svc.cluster.local#share#pvc-954f3638-a744-4bbf-8578-86711223fd92) mount "//smb-server.default.svc.cluster.local/share" on "\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-954f3638-a744-4bbf-8578-86711223fd92\\globalmount" failed with smb mapping failed with error: rpc error: code = Unknown desc = NewSmbGlobalMapping failed. output: "New-SmbGlobalMapping : Generic failure \r\nAt line:1 char:190\r\n+ ... ser, $PWord;New-SmbGlobalMapping -RemotePath $Env:smbremotepath -Cred ...\r\n+                 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\r\n    + CategoryInfo          : NotSpecified: (MSFT_SmbGlobalMapping:ROOT/Microsoft/...mbGlobalMapping) [New-SmbGlobalMa \r\n   pping], CimException\r\n    + FullyQualifiedErrorId : HRESULT 0x80041001,New-SmbGlobalMapping\r\n \r\n", err: exit status 1
[pod/csi-smb-node-win-m6nhr/smb] E0430 13:14:25.598667    5936 utils.go:81] GRPC error: rpc error: code = Internal desc = volume(smb-server.default.svc.cluster.local#share#pvc-53dc3d5a-5cd7-437b-8b71-cef6719f72fb) mount "//smb-server.default.svc.cluster.local/share" on "\\var\\lib\\kubelet\\plugins\\kubernetes.io\\csi\\pv\\pvc-53dc3d5a-5cd7-437b-8b71-cef6719f72fb\\globalmount" failed with smb mapping failed with error: rpc error: code = Unknown desc = NewSmbGlobalMapping failed. output: "New-SmbGlobalMapping : Generic failure \r\nAt line:1 char:190\r\n+ ... ser, $PWord;New-SmbGlobalMapping -RemotePath $Env:smbremotepath -Cred ...\r\n+                 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\r\n    + CategoryInfo          : NotSpecified: (MSFT_SmbGlobalMapping:ROOT/Microsoft/...mbGlobalMapping) [New-SmbGlobalMa \r\n   pping], CimException\r\n    + FullyQualifiedErrorId : HRESULT 0x80041001,New-SmbGlobalMapping\r\n \r\n", err: exit status 1
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.802194    5936 utils.go:76] GRPC call: /csi.v1.Node/NodeGetCapabilities
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.802194    5936 utils.go:77] GRPC request: {}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.802194    5936 utils.go:76] GRPC call: /csi.v1.Node/NodeGetCapabilities
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.802502    5936 utils.go:77] GRPC request: {}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.802502    5936 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":6}}}]}
[pod/csi-smb-node-win-m6nhr/smb] I0430 13:14:25.802502    5936 utils.go:83] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":6}}}]}
... skipping 703 lines ...
Uninstalled SMB CSI driver successfully.
2022/04/30 13:15:15 ===================================================

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

Ran 8 of 10 Specs in 1433.065 seconds
SUCCESS! -- 8 Passed | 0 Failed | 0 Pending | 2 Skipped
--- PASS: TestE2E (1433.07s)
PASS
ok  	github.com/kubernetes-csi/csi-driver-smb/test/e2e	1433.105s
2022/04/30 13:15:15 process.go:155: Step 'make e2e-test' finished in 24m55.841622887s
2022/04/30 13:15:15 aksengine_helpers.go:426: downloading /root/tmp2924987611/log-dump.sh from https://raw.githubusercontent.com/kubernetes-sigs/cloud-provider-azure/master/hack/log-dump/log-dump.sh
2022/04/30 13:15:15 util.go:71: curl https://raw.githubusercontent.com/kubernetes-sigs/cloud-provider-azure/master/hack/log-dump/log-dump.sh
... skipping 99 lines ...
     

   Flags

     -d

	   Detailed option, when used with list, dumps the json of the object





Write-Progress : Win32 internal error "Access is denied" 0x5 occurred while reading the console output buffer. Contact 

Microsoft Customer Support Services.
At 
C:\windows\system32\WindowsPowerShell\v1.0\Modules\Microsoft.PowerShell.Archive\Microsoft.PowerShell.Archive.psm1:1132 
char:9
+         Write-Progress -Activity $cmdletName -Status $status -Percent ...
+         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
... skipping 46 lines ...
     

   Flags

     -d

	   Detailed option, when used with list, dumps the json of the object





Write-Progress : Win32 internal error "Access is denied" 0x5 occurred while reading the console output buffer. Contact 

Microsoft Customer Support Services.
At 
C:\windows\system32\WindowsPowerShell\v1.0\Modules\Microsoft.PowerShell.Archive\Microsoft.PowerShell.Archive.psm1:1132 
char:9
+         Write-Progress -Activity $cmdletName -Status $status -Percent ...
+         ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
... skipping 8 lines ...
Warning: Permanently added '7756k8s001' (ECDSA) to the list of known hosts.
Collecting logs for vm 7756k8s002
Testing connection to host 7756k8s002.
Warning: Permanently added 'kubetest-d4bd0f8u.uksouth.cloudapp.azure.com,20.108.95.105' (ECDSA) to the list of known hosts.

Authorized uses only. All activity may be monitored and reported.
channel 0: open failed: connect failed: Name or service not known

stdio forwarding failed

ssh_exchange_identification: Connection closed by remote host
2022/04/30 13:17:09 process.go:155: Step 'bash -c /root/tmp2924987611/win-ci-logs-collector.sh kubetest-d4bd0f8u.uksouth.cloudapp.azure.com /root/tmp2924987611 /etc/ssh-key-secret/ssh-private' finished in 1m16.181163882s
2022/04/30 13:17:09 aksengine.go:1141: Deleting resource group: kubetest-d4bd0f8u.
{"component":"entrypoint","file":"k8s.io/test-infra/prow/entrypoint/run.go:169","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2022-04-30T13:18:50Z"}
++ early_exit_handler
++ '[' -n 177 ']'
++ kill -TERM 177
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...