This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2022-06-27 19:13
Elapsed1h24m
Revision
Builder34806551-f64d-11ec-9e31-9224b4edca5e
infra-commit1f3b6048e
reposigs.k8s.io/gcp-compute-persistent-disk-csi-driver
repo-commit8c3c4241cec03379e0a0a34c8f543c9234995a32
repos{u'sigs.k8s.io/gcp-compute-persistent-disk-csi-driver': u'master'}

No Test Failures!


Error lines from build-log.txt

... skipping 156 lines ...
W0627 19:15:47.333] I0627 19:15:47.332173    6542 utils.go:18] cmd args=[make -C /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver build-and-push-multi-arch GCE_PD_CSI_STAGING_VERSION=ea7de539-b062-4698-a0e3-388fce14a4ab GCE_PD_CSI_STAGING_IMAGE=gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver]
W0627 19:15:47.334] Makefile:48: gcp-pd-driver-windows only supports amd64.
I0627 19:15:47.434] make: Entering directory '/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver'
I0627 19:15:47.434] # Ensure we use a builder that can leverage it (the default on linux will not)
I0627 19:15:47.435] DOCKER_CLI_EXPERIMENTAL=enabled docker buildx rm multiarch-multiplatform-builder
I0627 19:15:47.500] DOCKER_CLI_EXPERIMENTAL=enabled docker buildx create --use --name=multiarch-multiplatform-builder
W0627 19:15:47.601] error: no builder "multiarch-multiplatform-builder" found
W0627 19:15:47.601] make: [Makefile:134: init-buildx] Error 1 (ignored)
I0627 19:15:47.702] multiarch-multiplatform-builder
I0627 19:15:47.702] DOCKER_CLI_EXPERIMENTAL=enabled docker run --rm --privileged multiarch/qemu-user-static --reset --credential yes --persistent yes
W0627 19:15:47.803] Unable to find image 'multiarch/qemu-user-static:latest' locally
W0627 19:15:47.942] latest: Pulling from multiarch/qemu-user-static
W0627 19:15:47.943] 19d511225f94: Pulling fs layer
W0627 19:15:47.944] 6a33d6a40b9b: Pulling fs layer
... skipping 246 lines ...
W0627 19:16:27.083] #11 13.54 Setting up libtinfo6:amd64 (6.1+20181013-2+deb10u2) ...
W0627 19:16:27.083] #11 13.61 Selecting previously unselected package bash.
W0627 19:16:27.084] #11 13.61 (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 3900 files and directories currently installed.)
W0627 19:16:27.084] #11 13.62 Preparing to unpack .../archives/bash_5.0-4_amd64.deb ...
W0627 19:16:27.084] #11 13.63 Unpacking bash (5.0-4) ...
W0627 19:16:27.084] #11 13.96 Setting up bash (5.0-4) ...
W0627 19:16:27.084] #11 14.00 update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist

W0627 19:16:27.084] #11 14.08 Selecting previously unselected package libuuid1:amd64.
W0627 19:16:27.085] #11 14.08 (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 3972 files and directories currently installed.)
W0627 19:16:27.085] #11 14.09 Preparing to unpack .../libuuid1_2.33.1-0.1_amd64.deb ...
W0627 19:16:27.085] #11 14.10 Unpacking libuuid1:amd64 (2.33.1-0.1) ...
W0627 19:16:27.085] #11 14.15 Setting up libuuid1:amd64 (2.33.1-0.1) ...
W0627 19:16:27.085] #11 14.22 Selecting previously unselected package libblkid1:amd64.
... skipping 502 lines ...
W0627 19:18:13.624] #12 51.50 Setting up libtinfo6:arm64 (6.1+20181013-2+deb10u2) ...
W0627 19:18:14.075] #12 51.81 Selecting previously unselected package bash.
W0627 19:18:14.076] #12 51.81 (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 3897 files and directories currently installed.)
W0627 19:18:14.076] #12 51.84 Preparing to unpack .../archives/bash_5.0-4_arm64.deb ...
W0627 19:18:14.076] #12 51.95 Unpacking bash (5.0-4) ...
W0627 19:18:15.411] #12 53.29 Setting up bash (5.0-4) ...
W0627 19:18:15.713] #12 53.47 update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist

W0627 19:18:17.067] #12 54.83 Selecting previously unselected package libuuid1:arm64.
W0627 19:18:17.068] #12 54.83 (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 3969 files and directories currently installed.)
W0627 19:18:17.068] #12 54.85 Preparing to unpack .../libuuid1_2.33.1-0.1_arm64.deb ...
W0627 19:18:17.069] #12 54.87 Unpacking libuuid1:arm64 (2.33.1-0.1) ...
W0627 19:18:17.368] #12 55.17 Setting up libuuid1:arm64 (2.33.1-0.1) ...
W0627 19:18:17.622] #12 55.48 Selecting previously unselected package libblkid1:arm64.
... skipping 896 lines ...
W0627 20:08:58.694] Trying to find master named 'e2e-test-prow-master'
W0627 20:08:58.695] Looking for address 'e2e-test-prow-master-ip'
W0627 20:08:59.968] Using master: e2e-test-prow-master (external IP: 35.239.128.119; internal IP: (not set))
I0627 20:09:00.069] Waiting up to 300 seconds for cluster initialization.
I0627 20:09:00.069] 
I0627 20:09:00.069]   This will continually check to see if the API for kubernetes is reachable.
I0627 20:09:00.069]   This may time out if there was some uncaught error during start up.
I0627 20:09:00.070] 
I0627 20:09:51.472] ................Kubernetes cluster created.
I0627 20:09:51.625] Cluster "k8s-jkns-gci-gce-sd-log_e2e-test-prow" set.
I0627 20:09:51.777] User "k8s-jkns-gci-gce-sd-log_e2e-test-prow" set.
I0627 20:09:51.932] Context "k8s-jkns-gci-gce-sd-log_e2e-test-prow" created.
I0627 20:09:52.091] Switched to context "k8s-jkns-gci-gce-sd-log_e2e-test-prow".
... skipping 47 lines ...
I0627 20:16:35.358] e2e-test-prow-minion-group-nt0b         Ready                      <none>   5m56s   v1.25.0-alpha.1.148+c6b0652b89ad64
I0627 20:16:35.359] e2e-test-prow-windows-node-group-cfqs   Ready                      <none>   18s     v1.25.0-alpha.1.148+c6b0652b89ad64
I0627 20:16:35.359] e2e-test-prow-windows-node-group-phcx   Ready                      <none>   20s     v1.25.0-alpha.1.148+c6b0652b89ad64
I0627 20:16:35.359] e2e-test-prow-windows-node-group-sm24   Ready                      <none>   21s     v1.25.0-alpha.1.148+c6b0652b89ad64
W0627 20:16:35.543] Warning: v1 ComponentStatus is deprecated in v1.19+
I0627 20:16:35.643] Validate output:
I0627 20:16:35.733] NAME                 STATUS    MESSAGE                         ERROR
I0627 20:16:35.734] etcd-1               Healthy   {"health":"true","reason":""}   
I0627 20:16:35.734] controller-manager   Healthy   ok                              
I0627 20:16:35.734] etcd-0               Healthy   {"health":"true","reason":""}   
I0627 20:16:35.735] scheduler            Healthy   ok                              
I0627 20:16:35.738] Cluster validation succeeded
W0627 20:16:35.839] Warning: v1 ComponentStatus is deprecated in v1.19+
... skipping 98 lines ...
I0627 20:18:25.354] GCE_PD_SA_DIR is /tmp/gcp-pd-driver-tmp3956920179
I0627 20:18:25.355] PKGDIR is /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver
I0627 20:18:25.355] installing kustomize
I0627 20:18:26.432] {Version:kustomize/v3.9.4 GitCommit:e41d94ddef8cca7af2709c4104b41c4d43cd236c BuildDate:2021-02-09T19:22:10Z GoOs:linux GoArch:amd64}
W0627 20:18:26.594] + [[ true != true ]]
W0627 20:18:26.595] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh get namespace gce-pd-csi-driver -v=2
W0627 20:18:27.015] Error from server (NotFound): namespaces "gce-pd-csi-driver" not found
W0627 20:18:27.026] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh create namespace gce-pd-csi-driver -v=2
I0627 20:18:27.442] namespace/gce-pd-csi-driver created
W0627 20:18:27.543] + [[ ! stable-master == *noauth* ]]
W0627 20:18:27.543] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh get secret cloud-sa -v=2 -n gce-pd-csi-driver
W0627 20:18:27.775] Error from server (NotFound): secrets "cloud-sa" not found
W0627 20:18:27.782] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh create secret generic cloud-sa -v=2 --from-file=/tmp/gcp-pd-driver-tmp3956920179/cloud-sa.json -n gce-pd-csi-driver
I0627 20:18:28.180] secret/cloud-sa created
W0627 20:18:28.282] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh get clusterrolebinding -v=2 cluster-admin-binding
W0627 20:18:28.663] Error from server (NotFound): clusterrolebindings.rbac.authorization.k8s.io "cluster-admin-binding" not found
W0627 20:18:28.674] ++ gcloud config get-value account
W0627 20:18:29.983] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh create clusterrolebinding cluster-admin-binding -v=2 --clusterrole cluster-admin --user pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0627 20:18:30.479] clusterrolebinding.rbac.authorization.k8s.io/cluster-admin-binding created
W0627 20:18:30.580] + /tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh version
W0627 20:18:30.941] WARNING: This version information is deprecated and will be replaced with the output from kubectl version --short.  Use --output=yaml|json to get the full version.
W0627 20:18:30.955] + readonly tmp_spec=/tmp/gcp-compute-persistent-disk-csi-driver-specs-generated.yaml
... skipping 521 lines ...
I0627 20:18:33.332]       - args:
I0627 20:18:33.332]         - --v=5
I0627 20:18:33.332]         - --csi-address=/csi/csi.sock
I0627 20:18:33.332]         - --http-endpoint=:22013
I0627 20:18:33.332]         - --leader-election
I0627 20:18:33.332]         - --leader-election-namespace=$(PDCSI_NAMESPACE)
I0627 20:18:33.332]         - --handle-volume-inuse-error=false
I0627 20:18:33.333]         env:
I0627 20:18:33.333]         - name: PDCSI_NAMESPACE
I0627 20:18:33.333]           valueFrom:
I0627 20:18:33.333]             fieldRef:
I0627 20:18:33.333]               fieldPath: metadata.namespace
I0627 20:18:33.333]         image: k8s.gcr.io/sig-storage/csi-resizer:v1.4.0
... skipping 384 lines ...
W0627 20:30:58.884]     Args:
W0627 20:30:58.884]       --v=5
W0627 20:30:58.884]       --csi-address=/csi/csi.sock
W0627 20:30:58.884]       --http-endpoint=:22013
W0627 20:30:58.884]       --leader-election
W0627 20:30:58.884]       --leader-election-namespace=$(PDCSI_NAMESPACE)
W0627 20:30:58.884]       --handle-volume-inuse-error=false
W0627 20:30:58.884]     State:          Running
W0627 20:30:58.884]       Started:      Mon, 27 Jun 2022 20:18:45 +0000
W0627 20:30:58.884]     Ready:          True
W0627 20:30:58.884]     Restart Count:  0
W0627 20:30:58.885]     Liveness:       http-get http://:http-endpoint/healthz/leader-election delay=10s timeout=10s period=20s #success=1 #failure=1
W0627 20:30:58.885]     Environment:
... skipping 481 lines ...
W0627 20:30:58.948]   Normal   Created    11m   kubelet            Created container csi-driver-registrar
W0627 20:30:58.948]   Normal   Started    11m   kubelet            Started container csi-driver-registrar
W0627 20:30:58.948]   Normal   Pulling    11m   kubelet            Pulling image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab"
W0627 20:30:58.948]   Normal   Pulled     57s   kubelet            Successfully pulled image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab" in 10m58.2578979s
W0627 20:30:58.949]   Normal   Created    56s   kubelet            Created container gce-pd-driver
W0627 20:30:58.949]   Normal   Started    29s   kubelet            Started container gce-pd-driver
W0627 20:30:58.949]   Warning  Unhealthy  22s   kubelet            Liveness probe failed: command "/csi-node-driver-registrar.exe --kubelet-registration-path=C:\\\\var\\\\lib\\\\kubelet\\\\plugins\\\\pd.csi.storage.gke.io\\\\csi.sock --mode=kubelet-registration-probe" timed out
W0627 20:30:58.949] 
W0627 20:30:58.949] 
W0627 20:30:58.949] Name:                 csi-gce-pd-node-win-mr55b
W0627 20:30:58.949] Namespace:            gce-pd-csi-driver
W0627 20:30:58.949] Priority:             900001000
W0627 20:30:58.949] Priority Class Name:  csi-gce-pd-node
... skipping 122 lines ...
W0627 20:30:58.966]   Normal   Created    11m   kubelet            Created container csi-driver-registrar
W0627 20:30:58.966]   Normal   Started    11m   kubelet            Started container csi-driver-registrar
W0627 20:30:58.967]   Normal   Pulling    11m   kubelet            Pulling image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab"
W0627 20:30:58.967]   Normal   Pulled     62s   kubelet            Successfully pulled image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab" in 10m52.8849078s
W0627 20:30:58.967]   Normal   Created    62s   kubelet            Created container gce-pd-driver
W0627 20:30:58.967]   Normal   Started    33s   kubelet            Started container gce-pd-driver
W0627 20:30:58.968]   Warning  Unhealthy  22s   kubelet            Liveness probe failed: command "/csi-node-driver-registrar.exe --kubelet-registration-path=C:\\\\var\\\\lib\\\\kubelet\\\\plugins\\\\pd.csi.storage.gke.io\\\\csi.sock --mode=kubelet-registration-probe" timed out
W0627 20:30:58.968] 
W0627 20:30:58.968] 
W0627 20:30:58.968] Name:                 csi-gce-pd-node-win-nwcgp
W0627 20:30:58.968] Namespace:            gce-pd-csi-driver
W0627 20:30:58.968] Priority:             900001000
W0627 20:30:58.968] Priority Class Name:  csi-gce-pd-node
... skipping 19 lines ...
W0627 20:30:58.970]       --v=5
W0627 20:30:58.971]       --csi-address=unix://C:\\csi\\csi.sock
W0627 20:30:58.971]       --kubelet-registration-path=C:\\var\\lib\\kubelet\\plugins\\pd.csi.storage.gke.io\\csi.sock
W0627 20:30:58.971]     State:          Running
W0627 20:30:58.971]       Started:      Mon, 27 Jun 2022 20:30:57 +0000
W0627 20:30:58.971]     Last State:     Terminated
W0627 20:30:58.971]       Reason:       Error
W0627 20:30:58.971]       Exit Code:    1
W0627 20:30:58.971]       Started:      Mon, 27 Jun 2022 20:19:05 +0000
W0627 20:30:58.972]       Finished:     Mon, 27 Jun 2022 20:30:52 +0000
W0627 20:30:58.972]     Ready:          True
W0627 20:30:58.972]     Restart Count:  1
W0627 20:30:58.972]     Liveness:       exec [/csi-node-driver-registrar.exe --kubelet-registration-path=C:\\var\\lib\\kubelet\\plugins\\pd.csi.storage.gke.io\\csi.sock --mode=kubelet-registration-probe] delay=3s timeout=1s period=10s #success=1 #failure=3
... skipping 13 lines ...
W0627 20:30:58.976]       --v=5
W0627 20:30:58.976]       --endpoint=unix:/csi/csi.sock
W0627 20:30:58.976]       --run-controller-service=false
W0627 20:30:58.976]     State:          Running
W0627 20:30:58.977]       Started:      Mon, 27 Jun 2022 20:30:49 +0000
W0627 20:30:58.977]     Last State:     Terminated
W0627 20:30:58.977]       Reason:       Error
W0627 20:30:58.977]       Exit Code:    255
W0627 20:30:58.977]       Started:      Mon, 27 Jun 2022 20:30:37 +0000
W0627 20:30:58.977]       Finished:     Mon, 27 Jun 2022 20:30:44 +0000
W0627 20:30:58.977]     Ready:          True
W0627 20:30:58.977]     Restart Count:  1
W0627 20:30:58.977]     Environment:    <none>
... skipping 71 lines ...
W0627 20:30:58.991]   Normal   Scheduled  12m                default-scheduler  Successfully assigned gce-pd-csi-driver/csi-gce-pd-node-win-nwcgp to e2e-test-prow-windows-node-group-cfqs
W0627 20:30:58.991]   Normal   Pulling    12m                kubelet            Pulling image "k8s.gcr.io/sig-storage/csi-node-driver-registrar:v2.5.0"
W0627 20:30:58.991]   Normal   Pulled     11m                kubelet            Successfully pulled image "k8s.gcr.io/sig-storage/csi-node-driver-registrar:v2.5.0" in 24.1022778s
W0627 20:30:58.993]   Normal   Pulling    11m                kubelet            Pulling image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab"
W0627 20:30:58.993]   Normal   Pulled     51s                kubelet            Successfully pulled image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab" in 11m1.5245179s
W0627 20:30:58.993]   Normal   Pulled     12s                kubelet            Container image "gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab" already present on machine
W0627 20:30:58.994]   Warning  Unhealthy  12s                kubelet            Liveness probe failed: command "/csi-node-driver-registrar.exe --kubelet-registration-path=C:\\\\var\\\\lib\\\\kubelet\\\\plugins\\\\pd.csi.storage.gke.io\\\\csi.sock --mode=kubelet-registration-probe" timed out
W0627 20:30:58.994]   Normal   Created    12s (x2 over 51s)  kubelet            Created container gce-pd-driver
W0627 20:30:58.994]   Normal   Started    9s (x2 over 21s)   kubelet            Started container gce-pd-driver
W0627 20:30:58.995]   Normal   Pulled     5s                 kubelet            Container image "k8s.gcr.io/sig-storage/csi-node-driver-registrar:v2.5.0" already present on machine
W0627 20:30:58.995]   Normal   Created    4s (x2 over 11m)   kubelet            Created container csi-driver-registrar
W0627 20:30:58.995]   Normal   Started    1s (x2 over 11m)   kubelet            Started container csi-driver-registrar
W0627 20:30:59.042] I0627 20:30:59.039801    6542 main.go:429] Tainting linux nodes
... skipping 9 lines ...
W0627 20:31:01.632] I0627 20:31:01.631650    6542 utils.go:18] cmd args=[kubetest2 gce --run-id=34806551-f64d-11ec-9e31-9224b4edca5e --test=ginkgo --legacy-mode --repo-root=/tmp/gcp-pd-driver-tmp4087690480/kubernetes --artifacts=/workspace/_artifacts/sc-windows -- --use-built-binaries --focus-regex=External.Storage --skip-regex=\[Disruptive\]|\[Serial\]|\[LinuxOnly\] --parallel=4 --test-args=--storage.testdriver=/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/test/k8s-integration/config/test-config.yaml  --node-os-distro=windows --allowed-not-ready-nodes=2]
W0627 20:31:01.735] I0627 20:31:01.735177  172497 app.go:62] The files in RunDir shall not be part of Artifacts
W0627 20:31:01.735] I0627 20:31:01.735361  172497 app.go:63] pass rundir-in-artifacts flag True for RunDir to be part of Artifacts
W0627 20:31:01.736] I0627 20:31:01.735385  172497 app.go:65] RunDir for this run: "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/34806551-f64d-11ec-9e31-9224b4edca5e"
W0627 20:31:01.739] I0627 20:31:01.739080  172497 app.go:129] ID for this run: "34806551-f64d-11ec-9e31-9224b4edca5e"
W0627 20:31:01.762] I0627 20:31:01.762290  172511 ginkgo.go:120] Using kubeconfig at /root/.kube/config
W0627 20:31:01.763] F0627 20:31:01.763093  172511 ginkgo.go:215] failed to run ginkgo tester: failed to validate pre-built binary kubectl (checked at "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/34806551-f64d-11ec-9e31-9224b4edca5e/kubectl"): stat /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/34806551-f64d-11ec-9e31-9224b4edca5e/kubectl: no such file or directory
W0627 20:31:01.763] goroutine 1 [running]:
W0627 20:31:01.764] k8s.io/klog/v2.stacks(0x1)
W0627 20:31:01.764] 	/go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:860 +0x8a
W0627 20:31:01.764] k8s.io/klog/v2.(*loggingT).output(0x1163340, 0x3, 0x0, 0xc0004a78f0, 0x1, {0xdfd6e7?, 0x1?}, 0x1163ca0?, 0x0)
W0627 20:31:01.764] 	/go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:825 +0x686
W0627 20:31:01.764] k8s.io/klog/v2.(*loggingT).printfDepth(0x1163340, 0x21a0?, 0x0, {0x0, 0x0}, 0x407f99?, {0xbe901a, 0x1f}, {0xc000493d60, 0x1, ...})
... skipping 9 lines ...
W0627 20:31:01.766] 
W0627 20:31:01.766] goroutine 6 [select]:
W0627 20:31:01.766] go.opencensus.io/stats/view.(*worker).start(0xc000372400)
W0627 20:31:01.766] 	/go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:276 +0xad
W0627 20:31:01.766] created by go.opencensus.io/stats/view.init.0
W0627 20:31:01.767] 	/go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:34 +0x8d
W0627 20:31:01.769] Error: exit status 255
W0627 20:31:01.778] I0627 20:31:01.774331    6542 main.go:741] Copying kubernetes binaries to path=/workspace/_artifacts/pd-volumesnapshotclass/34806551-f64d-11ec-9e31-9224b4edca5e to run the tests
W0627 20:31:01.779] I0627 20:31:01.774411    6542 main.go:796] copying /tmp/gcp-pd-driver-tmp4087690480/kubernetes/_output/dockerized/bin/linux/amd64/kubectl to /workspace/_artifacts/pd-volumesnapshotclass/34806551-f64d-11ec-9e31-9224b4edca5e/kubectl
W0627 20:31:01.984] I0627 20:31:01.983857    6542 main.go:796] copying /tmp/gcp-pd-driver-tmp4087690480/kubernetes/_output/dockerized/bin/linux/amd64/e2e.test to /workspace/_artifacts/pd-volumesnapshotclass/34806551-f64d-11ec-9e31-9224b4edca5e/e2e.test
W0627 20:31:03.097] I0627 20:31:03.097380    6542 main.go:796] copying /tmp/gcp-pd-driver-tmp4087690480/kubernetes/_output/dockerized/bin/linux/amd64/ginkgo to /workspace/_artifacts/pd-volumesnapshotclass/34806551-f64d-11ec-9e31-9224b4edca5e/ginkgo
W0627 20:31:03.134] I0627 20:31:03.134642    6542 utils.go:16] Running Tests
W0627 20:31:03.135] I0627 20:31:03.135158    6542 utils.go:17] cmd env=[]
W0627 20:31:03.136] I0627 20:31:03.135454    6542 utils.go:18] cmd args=[kubetest2 gce --run-id=34806551-f64d-11ec-9e31-9224b4edca5e --test=ginkgo --legacy-mode --repo-root=/tmp/gcp-pd-driver-tmp4087690480/kubernetes --artifacts=/workspace/_artifacts/pd-volumesnapshotclass -- --use-built-binaries --focus-regex=Driver:\s*csi-gcepd.*Feature:VolumeSnapshotDataSource --skip-regex=\[Disruptive\]|\[Serial\]|\[LinuxOnly\] --parallel=4 --test-args=--storage.testdriver=/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/test/k8s-integration/config/test-config.yaml  --node-os-distro=windows --allowed-not-ready-nodes=2]
W0627 20:31:03.185] I0627 20:31:03.185142  172527 app.go:62] The files in RunDir shall not be part of Artifacts
W0627 20:31:03.185] I0627 20:31:03.185253  172527 app.go:63] pass rundir-in-artifacts flag True for RunDir to be part of Artifacts
W0627 20:31:03.186] I0627 20:31:03.185286  172527 app.go:65] RunDir for this run: "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/34806551-f64d-11ec-9e31-9224b4edca5e"
W0627 20:31:03.189] I0627 20:31:03.189424  172527 app.go:129] ID for this run: "34806551-f64d-11ec-9e31-9224b4edca5e"
W0627 20:31:03.208] I0627 20:31:03.208015  172543 ginkgo.go:120] Using kubeconfig at /root/.kube/config
W0627 20:31:03.208] F0627 20:31:03.208210  172543 ginkgo.go:215] failed to run ginkgo tester: failed to validate pre-built binary kubectl (checked at "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/34806551-f64d-11ec-9e31-9224b4edca5e/kubectl"): stat /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/34806551-f64d-11ec-9e31-9224b4edca5e/kubectl: no such file or directory
W0627 20:31:03.209] goroutine 1 [running]:
W0627 20:31:03.209] k8s.io/klog/v2.stacks(0x1)
W0627 20:31:03.209] 	/go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:860 +0x8a
W0627 20:31:03.209] k8s.io/klog/v2.(*loggingT).output(0x1163340, 0x3, 0x0, 0xc0004a78f0, 0x1, {0xdfd6e7?, 0x1?}, 0x1163ca0?, 0x0)
W0627 20:31:03.209] 	/go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:825 +0x686
W0627 20:31:03.210] k8s.io/klog/v2.(*loggingT).printfDepth(0x1163340, 0x21a0?, 0x0, {0x0, 0x0}, 0x407f99?, {0xbe901a, 0x1f}, {0xc000493d60, 0x1, ...})
... skipping 9 lines ...
W0627 20:31:03.211] 
W0627 20:31:03.211] goroutine 6 [select]:
W0627 20:31:03.211] go.opencensus.io/stats/view.(*worker).start(0xc000372400)
W0627 20:31:03.211] 	/go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:276 +0xad
W0627 20:31:03.211] created by go.opencensus.io/stats/view.init.0
W0627 20:31:03.211] 	/go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:34 +0x8d
W0627 20:31:03.213] Error: exit status 255
W0627 20:31:03.219] I0627 20:31:03.219247    6542 utils.go:16] Deleting driver
W0627 20:31:03.224] I0627 20:31:03.219278    6542 utils.go:17] cmd env=[KUBERNETES_SERVICE_PORT_HTTPS=443 GCE_SSH_PUBLIC_KEY_FILE=/etc/ssh-key-secret/ssh-public KUBERNETES_SERVICE_PORT=443 JOB_TYPE=periodic BOSKOS_METRICS_SERVICE_PORT=9090 JENKINS_GCE_SSH_PRIVATE_KEY_FILE=/etc/ssh-key-secret/ssh-private HOSTNAME=34806551-f64d-11ec-9e31-9224b4edca5e BOSKOS_PORT_80_TCP_ADDR=10.63.250.132 KUBE_GCE_ENABLE_IP_ALIASES=true CLOUDSDK_CORE_DISABLE_PROMPTS=1 BOSKOS_METRICS_SERVICE_HOST=10.63.252.110 PREPULL_YAML=/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/test/k8s-integration/prepull.yaml LOG_DUMP_SCRIPT_PATH=/workspace/log-dump.sh GCS_ARTIFACTS_DIR=gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541499937945030656/artifacts DOCKER_IN_DOCKER_IPV6_ENABLED=false PWD=/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver BOSKOS_SERVICE_PORT=80 SOURCE_DATE_EPOCH=1655412664 BOSKOS_SERVICE_HOST=10.63.250.132 GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json BUILD_NUMBER=1541499937945030656 NUM_WINDOWS_NODES=3 TEST_TMPDIR=/bazel-scratch/.cache/bazel DOCKER_IN_DOCKER_ENABLED=true HOME=/root ARTIFACTS=/workspace/_artifacts KUBERNETES_PORT_443_TCP=tcp://10.63.240.1:443 IMAGE=gcr.io/k8s-staging-test-infra/kubekins-e2e:v20220624-1a63fdd9f2-master BOSKOS_METRICS_PORT_9090_TCP_PORT=9090 KUBETEST_IN_DOCKER=true BAZEL_REMOTE_CACHE_ENABLED=false CLOUDSDK_CONFIG=/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/.config/gcloud BOOTSTRAP_MIGRATION=yes GCE_SSH_PRIVATE_KEY_FILE=/etc/ssh-key-secret/ssh-private PROW_JOB_ID=34806551-f64d-11ec-9e31-9224b4edca5e JOB_NAME=ci-gce-pd-csi-driver-latest-k8s-master-windows-2019 BOSKOS_SERVICE_PORT_DEFAULT=80 BOSKOS_PORT_80_TCP_PORT=80 WORKSPACE=/workspace BOSKOS_METRICS_PORT_9090_TCP_PROTO=tcp JENKINS_GCE_SSH_PUBLIC_KEY_FILE=/etc/ssh-key-secret/ssh-public BOSKOS_METRICS_SERVICE_PORT_METRICS=9090 BOSKOS_PORT=tcp://10.63.250.132:80 BOSKOS_PORT_80_TCP=tcp://10.63.250.132:80 BOSKOS_PORT_80_TCP_PROTO=tcp TERM=xterm NODE_NAME=34806551-f64d-11ec-9e31-9224b4edca5e USER=prow KUBE_BUILD_PLATFORMS=linux/amd64 windows/amd64 AWS_SSH_PRIVATE_KEY_FILE=/root/.ssh/kube_aws_rsa BOSKOS_METRICS_PORT_9090_TCP_ADDR=10.63.252.110 GOPROXY=https://proxy.golang.org SHLVL=2 USE_TEST_INFRA_LOG_DUMPING=true KUBERNETES_PORT_443_TCP_PROTO=tcp KUBERNETES_PORT_443_TCP_ADDR=10.63.240.1 JOB_SPEC={"type":"periodic","job":"ci-gce-pd-csi-driver-latest-k8s-master-windows-2019","buildid":"1541499937945030656","prowjobid":"34806551-f64d-11ec-9e31-9224b4edca5e","extra_refs":[{"org":"kubernetes-sigs","repo":"gcp-compute-persistent-disk-csi-driver","base_ref":"master","path_alias":"sigs.k8s.io/gcp-compute-persistent-disk-csi-driver"}]} KUBELET_TEST_ARGS=--feature-gates=KubeletPodResources=false BAZEL_VERSION=3.4.1 BOSKOS_METRICS_PORT=tcp://10.63.252.110:9090 E2E_GOOGLE_APPLICATION_CREDENTIALS=/etc/service-account/service-account.json BOSKOS_METRICS_PORT_9090_TCP=tcp://10.63.252.110:9090 KUBERNETES_SERVICE_HOST=10.63.240.1 KUBERNETES_NODE_PLATFORM=windows KUBERNETES_PORT=tcp://10.63.240.1:443 KUBERNETES_PORT_443_TCP_PORT=443 WINDOWS_ENABLE_DSR=true NUM_NODES=1 PATH=/go/bin:/go/bin:/usr/local/go/bin:/google-cloud-sdk/bin:/workspace:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin CI=true GOOGLE_APPLICATION_CREDENTIALS_DEPRECATED=Migrate to workload identity, contact sig-testing GO_TARBALL=go1.18.3.linux-amd64.tar.gz WINDOWS_NODE_OS_DISTRIBUTION=win2019 BUILD_ID=1541499937945030656 AWS_SSH_PUBLIC_KEY_FILE=/root/.ssh/kube_aws_rsa.pub OLDPWD=/workspace/test-infra GOPATH=/go _=/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/bin/k8s-integration-test PROJECT=k8s-jkns-gci-gce-sd-log GCE_PD_CSI_STAGING_VERSION=ea7de539-b062-4698-a0e3-388fce14a4ab GCE_PD_CSI_STAGING_IMAGE=gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver GCE_PD_KUBECTL=/tmp/gcp-pd-driver-tmp4087690480/kubernetes/cluster/kubectl.sh MASTER_SIZE=n1-standard-4 KUBE_GCE_ZONE=us-central1-b KUBECONFIG=/root/.kube/config GOPATH=/go GCE_PD_DRIVER_VERSION=stable-master]
W0627 20:31:03.224] I0627 20:31:03.219329    6542 utils.go:18] cmd args=[/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/delete-driver.sh]
I0627 20:31:03.324] PKGDIR is /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver
I0627 20:31:03.325] installing kustomize
I0627 20:31:04.352] {Version:kustomize/v3.9.4 GitCommit:e41d94ddef8cca7af2709c4104b41c4d43cd236c BuildDate:2021-02-09T19:22:10Z GoOs:linux GoArch:amd64}
... skipping 92 lines ...
W0627 20:37:51.586]   Associated tags:
W0627 20:37:51.587]  - ea7de539-b062-4698-a0e3-388fce14a4ab
W0627 20:37:51.587] Tags:
W0627 20:37:51.587] - gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab
W0627 20:37:51.818] Deleted [gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver:ea7de539-b062-4698-a0e3-388fce14a4ab].
W0627 20:37:52.254] Deleted [gcr.io/k8s-jkns-gci-gce-sd-log/gcp-persistent-disk-csi-driver@sha256:14dd10bfbe4385ebae3dce9b14c5f3b12b2f2ae890c61e2f25884ca0299d1e3a].
W0627 20:37:53.586] F0627 20:37:53.585790    6542 main.go:196] Failed to run integration test: runCSITests failed: failed to run tests on e2e cluster: exit status 1 failed to run tests on e2e cluster: exit status 1
W0627 20:37:53.603] Traceback (most recent call last):
W0627 20:37:53.604]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module>
W0627 20:37:53.614]     main(ARGS.env, ARGS.cmd + ARGS.args)
W0627 20:37:53.615]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main
W0627 20:37:53.615]     check(*cmd)
W0627 20:37:53.615]   File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check
W0627 20:37:53.615]     subprocess.check_call(cmd)
W0627 20:37:53.615]   File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
W0627 20:37:53.616]     raise CalledProcessError(retcode, cmd)
W0627 20:37:53.620] subprocess.CalledProcessError: Command '('test/run-windows-k8s-integration.sh',)' returned non-zero exit status 255
E0627 20:37:53.647] Command failed
I0627 20:37:53.648] process 430 exited with code 1 after 84.3m
E0627 20:37:53.650] FAIL: ci-gce-pd-csi-driver-latest-k8s-master-windows-2019
I0627 20:37:53.654] Call:  gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json
W0627 20:37:54.635] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com]
I0627 20:37:54.845] process 174046 exited with code 0 after 0.0m
I0627 20:37:54.846] Call:  gcloud config get-value account
I0627 20:37:55.736] process 174060 exited with code 0 after 0.0m
I0627 20:37:55.736] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com
I0627 20:37:55.736] Upload result and artifacts...
I0627 20:37:55.737] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541499937945030656
I0627 20:37:55.737] Call:  gsutil ls gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541499937945030656/artifacts
W0627 20:37:57.447] CommandException: One or more URLs matched no objects.
E0627 20:37:57.802] Command failed
I0627 20:37:57.803] process 174074 exited with code 1 after 0.0m
W0627 20:37:57.803] Remote dir gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541499937945030656/artifacts not exist yet
I0627 20:37:57.803] Call:  gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541499937945030656/artifacts
I0627 20:38:04.028] process 174214 exited with code 0 after 0.1m
W0627 20:38:04.029] metadata path /workspace/_artifacts/metadata.json does not exist
W0627 20:38:04.030] metadata not found or invalid, init with empty metadata
... skipping 15 lines ...