ci-gce-pd-csi-driver-latest-k8s-master-windows-2019 #1540592701605416960
Recent runs || View in Spyglass
Recent runs || View in Spyglass
... skipping 154 lines ... W0625 07:10:41.300] I0625 07:10:41.298716 6528 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=d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b GCE_PD_CSI_STAGING_IMAGE=gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver] W0625 07:10:41.300] Makefile:48: gcp-pd-driver-windows only supports amd64. I0625 07:10:41.400] make: Entering directory '/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver' I0625 07:10:41.402] # Ensure we use a builder that can leverage it (the default on linux will not) I0625 07:10:41.402] DOCKER_CLI_EXPERIMENTAL=enabled docker buildx rm multiarch-multiplatform-builder I0625 07:10:41.467] DOCKER_CLI_EXPERIMENTAL=enabled docker buildx create --use --name=multiarch-multiplatform-builder W0625 07:10:41.568] error: no builder "multiarch-multiplatform-builder" found W0625 07:10:41.568] make: [Makefile:134: init-buildx] Error 1 (ignored) W0625 07:10:41.644] Unable to find image 'multiarch/qemu-user-static:latest' locally I0625 07:10:41.745] multiarch-multiplatform-builder I0625 07:10:41.745] DOCKER_CLI_EXPERIMENTAL=enabled docker run --rm --privileged multiarch/qemu-user-static --reset --credential yes --persistent yes W0625 07:10:41.903] latest: Pulling from multiarch/qemu-user-static W0625 07:10:41.903] 19d511225f94: Pulling fs layer W0625 07:10:41.903] 6a33d6a40b9b: Pulling fs layer ... skipping 219 lines ... W0625 07:11:04.346] #11 6.757 Setting up libtinfo6:amd64 (6.1+20181013-2+deb10u2) ... W0625 07:11:04.346] #11 6.812 Selecting previously unselected package bash. W0625 07:11:04.347] #11 6.812 (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.) W0625 07:11:04.497] #11 6.825 Preparing to unpack .../archives/bash_5.0-4_amd64.deb ... W0625 07:11:04.497] #11 6.836 Unpacking bash (5.0-4) ... W0625 07:11:04.773] #11 7.138 Setting up bash (5.0-4) ... W0625 07:11:04.774] #11 7.168 update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist W0625 07:11:04.774] #11 7.250 Selecting previously unselected package libuuid1:amd64. W0625 07:11:04.894] #11 7.250 (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.) W0625 07:11:04.895] #11 7.263 Preparing to unpack .../libuuid1_2.33.1-0.1_amd64.deb ... W0625 07:11:04.895] #11 7.268 Unpacking libuuid1:amd64 (2.33.1-0.1) ... W0625 07:11:04.895] #11 7.318 Setting up libuuid1:amd64 (2.33.1-0.1) ... W0625 07:11:04.895] #11 7.370 Selecting previously unselected package libblkid1:amd64. ... skipping 494 lines ... W0625 07:12:56.822] #11 47.38 Setting up libtinfo6:arm64 (6.1+20181013-2+deb10u2) ... W0625 07:12:57.123] #11 47.69 Selecting previously unselected package bash. W0625 07:12:57.124] #11 47.69 (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.) W0625 07:12:57.124] #11 47.71 Preparing to unpack .../archives/bash_5.0-4_arm64.deb ... W0625 07:12:57.273] #11 47.82 Unpacking bash (5.0-4) ... W0625 07:12:58.477] #11 49.08 Setting up bash (5.0-4) ... W0625 07:12:58.628] #11 49.25 update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist W0625 07:12:59.816] #11 50.50 Selecting previously unselected package libuuid1:arm64. W0625 07:12:59.968] #11 50.50 (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.) W0625 07:12:59.968] #11 50.52 Preparing to unpack .../libuuid1_2.33.1-0.1_arm64.deb ... W0625 07:12:59.968] #11 50.53 Unpacking libuuid1:arm64 (2.33.1-0.1) ... W0625 07:13:00.268] #11 50.81 Setting up libuuid1:arm64 (2.33.1-0.1) ... W0625 07:13:00.402] #11 51.08 Selecting previously unselected package libblkid1:arm64. ... skipping 891 lines ... W0625 07:54:46.468] NODE_NAMES=e2e-test-prow-minion-group-l4v2 W0625 07:54:46.468] Trying to find master named 'e2e-test-prow-master' W0625 07:54:46.468] Looking for address 'e2e-test-prow-master-ip' I0625 07:54:47.581] Waiting up to 300 seconds for cluster initialization. I0625 07:54:47.581] I0625 07:54:47.581] This will continually check to see if the API for kubernetes is reachable. I0625 07:54:47.582] This may time out if there was some uncaught error during start up. I0625 07:54:47.582] W0625 07:54:47.682] Using master: e2e-test-prow-master (external IP: 34.68.50.66; internal IP: (not set)) I0625 07:55:37.879] ...............Kubernetes cluster created. I0625 07:55:38.026] Cluster "k8s-gce-gci-1-6-mstr-ctl-skew_e2e-test-prow" set. I0625 07:55:38.167] User "k8s-gce-gci-1-6-mstr-ctl-skew_e2e-test-prow" set. I0625 07:55:38.307] Context "k8s-gce-gci-1-6-mstr-ctl-skew_e2e-test-prow" created. ... skipping 54 lines ... I0625 08:03:06.039] e2e-test-prow-windows-node-group-xc2f Ready <none> 36s v1.25.0-alpha.1.137+d2c5779dadc9ed W0625 08:03:06.420] Warning: v1 ComponentStatus is deprecated in v1.19+ I0625 08:03:06.521] Validate output: W0625 08:03:06.792] Warning: v1 ComponentStatus is deprecated in v1.19+ W0625 08:03:06.805] Done, listing cluster services: W0625 08:03:06.806] I0625 08:03:06.906] NAME STATUS MESSAGE ERROR I0625 08:03:06.906] etcd-1 Healthy {"health":"true","reason":""} I0625 08:03:06.907] etcd-0 Healthy {"health":"true","reason":""} I0625 08:03:06.907] scheduler Healthy ok I0625 08:03:06.907] controller-manager Healthy ok I0625 08:03:06.907] [0;32mCluster validation succeeded[0m I0625 08:03:07.419] [0;32mKubernetes control plane[0m is running at [0;33mhttps://34.68.50.66[0m ... skipping 95 lines ... I0625 08:05:06.442] GCE_PD_SA_DIR is /tmp/gcp-pd-driver-tmp1698431453 I0625 08:05:06.442] PKGDIR is /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver I0625 08:05:06.442] installing kustomize I0625 08:05:07.166] {Version:kustomize/v3.9.4 GitCommit:e41d94ddef8cca7af2709c4104b41c4d43cd236c BuildDate:2021-02-09T19:22:10Z GoOs:linux GoArch:amd64} W0625 08:05:07.266] + [[ true != true ]] W0625 08:05:07.267] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh get namespace gce-pd-csi-driver -v=2 W0625 08:05:07.396] Error from server (NotFound): namespaces "gce-pd-csi-driver" not found W0625 08:05:07.400] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh create namespace gce-pd-csi-driver -v=2 I0625 08:05:07.564] namespace/gce-pd-csi-driver created W0625 08:05:07.665] + [[ ! stable-master == *noauth* ]] W0625 08:05:07.665] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh get secret cloud-sa -v=2 -n gce-pd-csi-driver W0625 08:05:07.722] Error from server (NotFound): secrets "cloud-sa" not found W0625 08:05:07.726] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh create secret generic cloud-sa -v=2 --from-file=/tmp/gcp-pd-driver-tmp1698431453/cloud-sa.json -n gce-pd-csi-driver I0625 08:05:07.877] secret/cloud-sa created W0625 08:05:07.977] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh get clusterrolebinding -v=2 cluster-admin-binding W0625 08:05:08.035] Error from server (NotFound): clusterrolebindings.rbac.authorization.k8s.io "cluster-admin-binding" not found W0625 08:05:08.040] ++ gcloud config get-value account W0625 08:05:08.745] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh create clusterrolebinding cluster-admin-binding -v=2 --clusterrole cluster-admin --user pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com I0625 08:05:08.900] clusterrolebinding.rbac.authorization.k8s.io/cluster-admin-binding created W0625 08:05:09.001] + /tmp/gcp-pd-driver-tmp3211756875/kubernetes/cluster/kubectl.sh version W0625 08:05:09.071] 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. W0625 08:05:09.075] + readonly tmp_spec=/tmp/gcp-compute-persistent-disk-csi-driver-specs-generated.yaml ... skipping 521 lines ... I0625 08:05:10.371] - args: I0625 08:05:10.371] - --v=5 I0625 08:05:10.371] - --csi-address=/csi/csi.sock I0625 08:05:10.371] - --http-endpoint=:22013 I0625 08:05:10.371] - --leader-election I0625 08:05:10.371] - --leader-election-namespace=$(PDCSI_NAMESPACE) I0625 08:05:10.372] - --handle-volume-inuse-error=false I0625 08:05:10.372] env: I0625 08:05:10.372] - name: PDCSI_NAMESPACE I0625 08:05:10.372] valueFrom: I0625 08:05:10.372] fieldRef: I0625 08:05:10.372] fieldPath: metadata.namespace I0625 08:05:10.372] image: k8s.gcr.io/sig-storage/csi-resizer:v1.4.0 ... skipping 384 lines ... W0625 08:19:14.335] Args: W0625 08:19:14.335] --v=5 W0625 08:19:14.335] --csi-address=/csi/csi.sock W0625 08:19:14.335] --http-endpoint=:22013 W0625 08:19:14.335] --leader-election W0625 08:19:14.335] --leader-election-namespace=$(PDCSI_NAMESPACE) W0625 08:19:14.335] --handle-volume-inuse-error=false W0625 08:19:14.336] State: Running W0625 08:19:14.336] Started: Sat, 25 Jun 2022 08:05:25 +0000 W0625 08:19:14.336] Ready: True W0625 08:19:14.336] Restart Count: 0 W0625 08:19:14.336] Liveness: http-get http://:http-endpoint/healthz/leader-election delay=10s timeout=10s period=20s #success=1 #failure=1 W0625 08:19:14.336] Environment: ... skipping 481 lines ... W0625 08:19:14.403] Normal Created 13m kubelet Created container csi-driver-registrar W0625 08:19:14.403] Normal Started 13m kubelet Started container csi-driver-registrar W0625 08:19:14.403] Normal Pulling 13m kubelet Pulling image "gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver:d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b" W0625 08:19:14.403] Normal Pulled 59s kubelet Successfully pulled image "gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver:d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b" in 12m26.8102203s W0625 08:19:14.404] Normal Created 59s kubelet Created container gce-pd-driver W0625 08:19:14.404] Normal Started 31s kubelet Started container gce-pd-driver W0625 08:19:14.404] Warning Unhealthy 12s (x2 over 21s) 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 W0625 08:19:14.404] W0625 08:19:14.404] W0625 08:19:14.405] Name: csi-gce-pd-node-win-d6qr2 W0625 08:19:14.405] Namespace: gce-pd-csi-driver W0625 08:19:14.405] Priority: 900001000 W0625 08:19:14.405] Priority Class Name: csi-gce-pd-node ... skipping 256 lines ... W0625 08:19:14.451] Normal Created 13m kubelet Created container csi-driver-registrar W0625 08:19:14.451] Normal Started 13m kubelet Started container csi-driver-registrar W0625 08:19:14.452] Normal Pulling 13m kubelet Pulling image "gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver:d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b" W0625 08:19:14.452] Normal Pulled 58s kubelet Successfully pulled image "gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver:d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b" in 12m30.7110507s W0625 08:19:14.452] Normal Created 57s kubelet Created container gce-pd-driver W0625 08:19:14.452] Normal Started 27s kubelet Started container gce-pd-driver W0625 08:19:14.453] Warning Unhealthy 21s 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 W0625 08:19:14.483] I0625 08:19:14.483415 6528 main.go:429] Tainting linux nodes W0625 08:19:14.661] I0625 08:19:14.661384 6528 main.go:443] taint linux nodes: node/e2e-test-prow-master, output node/e2e-test-prow-master modified W0625 08:19:14.751] I0625 08:19:14.751335 6528 main.go:443] taint linux nodes: node/e2e-test-prow-minion-group-l4v2, output node/e2e-test-prow-minion-group-l4v2 modified W0625 08:19:14.821] I0625 08:19:14.820764 6528 main.go:469] kubernetes cluster server version: v1.25.0-alpha.1.137+d2c5779dadc9ed W0625 08:19:14.823] I0625 08:19:14.823117 6528 main.go:741] Copying kubernetes binaries to path=/workspace/_artifacts/sc-windows/9676be22-f455-11ec-9e31-9224b4edca5e to run the tests W0625 08:19:14.823] I0625 08:19:14.823169 6528 main.go:796] copying /tmp/gcp-pd-driver-tmp3211756875/kubernetes/_output/dockerized/bin/linux/amd64/kubectl to /workspace/_artifacts/sc-windows/9676be22-f455-11ec-9e31-9224b4edca5e/kubectl ... skipping 4 lines ... W0625 08:19:16.559] I0625 08:19:16.559050 6528 utils.go:18] cmd args=[kubetest2 gce --run-id=9676be22-f455-11ec-9e31-9224b4edca5e --test=ginkgo --legacy-mode --repo-root=/tmp/gcp-pd-driver-tmp3211756875/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] W0625 08:19:16.628] I0625 08:19:16.628566 173148 app.go:62] The files in RunDir shall not be part of Artifacts W0625 08:19:16.629] I0625 08:19:16.628773 173148 app.go:63] pass rundir-in-artifacts flag True for RunDir to be part of Artifacts W0625 08:19:16.630] I0625 08:19:16.628796 173148 app.go:65] RunDir for this run: "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/9676be22-f455-11ec-9e31-9224b4edca5e" W0625 08:19:16.632] I0625 08:19:16.632592 173148 app.go:129] ID for this run: "9676be22-f455-11ec-9e31-9224b4edca5e" W0625 08:19:16.650] I0625 08:19:16.650390 173169 ginkgo.go:120] Using kubeconfig at /root/.kube/config W0625 08:19:16.651] F0625 08:19:16.651082 173169 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/9676be22-f455-11ec-9e31-9224b4edca5e/kubectl"): stat /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/9676be22-f455-11ec-9e31-9224b4edca5e/kubectl: no such file or directory W0625 08:19:16.657] Error: exit status 255 W0625 08:19:16.662] I0625 08:19:16.662189 6528 main.go:741] Copying kubernetes binaries to path=/workspace/_artifacts/pd-volumesnapshotclass/9676be22-f455-11ec-9e31-9224b4edca5e to run the tests W0625 08:19:16.662] I0625 08:19:16.662239 6528 main.go:796] copying /tmp/gcp-pd-driver-tmp3211756875/kubernetes/_output/dockerized/bin/linux/amd64/kubectl to /workspace/_artifacts/pd-volumesnapshotclass/9676be22-f455-11ec-9e31-9224b4edca5e/kubectl W0625 08:19:16.803] I0625 08:19:16.803257 6528 main.go:796] copying /tmp/gcp-pd-driver-tmp3211756875/kubernetes/_output/dockerized/bin/linux/amd64/e2e.test to /workspace/_artifacts/pd-volumesnapshotclass/9676be22-f455-11ec-9e31-9224b4edca5e/e2e.test W0625 08:19:17.596] I0625 08:19:17.595842 6528 main.go:796] copying /tmp/gcp-pd-driver-tmp3211756875/kubernetes/_output/dockerized/bin/linux/amd64/ginkgo to /workspace/_artifacts/pd-volumesnapshotclass/9676be22-f455-11ec-9e31-9224b4edca5e/ginkgo W0625 08:19:17.626] I0625 08:19:17.626363 6528 utils.go:16] Running Tests W0625 08:19:17.627] I0625 08:19:17.626397 6528 utils.go:17] cmd env=[] W0625 08:19:17.628] I0625 08:19:17.626408 6528 utils.go:18] cmd args=[kubetest2 gce --run-id=9676be22-f455-11ec-9e31-9224b4edca5e --test=ginkgo --legacy-mode --repo-root=/tmp/gcp-pd-driver-tmp3211756875/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] W0625 08:19:17.660] I0625 08:19:17.660057 173182 app.go:62] The files in RunDir shall not be part of Artifacts W0625 08:19:17.660] I0625 08:19:17.660586 173182 app.go:63] pass rundir-in-artifacts flag True for RunDir to be part of Artifacts W0625 08:19:17.661] I0625 08:19:17.660663 173182 app.go:65] RunDir for this run: "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/9676be22-f455-11ec-9e31-9224b4edca5e" W0625 08:19:17.664] I0625 08:19:17.664114 173182 app.go:129] ID for this run: "9676be22-f455-11ec-9e31-9224b4edca5e" W0625 08:19:17.681] I0625 08:19:17.681670 173198 ginkgo.go:120] Using kubeconfig at /root/.kube/config W0625 08:19:17.682] F0625 08:19:17.682270 173198 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/9676be22-f455-11ec-9e31-9224b4edca5e/kubectl"): stat /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/9676be22-f455-11ec-9e31-9224b4edca5e/kubectl: no such file or directory W0625 08:19:17.688] Error: exit status 255 W0625 08:19:17.694] I0625 08:19:17.693875 6528 utils.go:16] Deleting driver W0625 08:19:17.698] I0625 08:19:17.693894 6528 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=9676be22-f455-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/1540592701605416960/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=1540592701605416960 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=9676be22-f455-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=9676be22-f455-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":"1540592701605416960","prowjobid":"9676be22-f455-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=1540592701605416960 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-gce-gci-1-6-mstr-ctl-skew GCE_PD_CSI_STAGING_VERSION=d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b GCE_PD_CSI_STAGING_IMAGE=gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver GCE_PD_KUBECTL=/tmp/gcp-pd-driver-tmp3211756875/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] W0625 08:19:17.698] I0625 08:19:17.693987 6528 utils.go:18] cmd args=[/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/delete-driver.sh] I0625 08:19:17.799] PKGDIR is /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver I0625 08:19:17.799] installing kustomize I0625 08:19:19.113] {Version:kustomize/v3.9.4 GitCommit:e41d94ddef8cca7af2709c4104b41c4d43cd236c BuildDate:2021-02-09T19:22:10Z GoOs:linux GoArch:amd64} ... skipping 92 lines ... W0625 08:25:46.569] Associated tags: W0625 08:25:46.570] - d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b W0625 08:25:46.570] Tags: W0625 08:25:46.570] - gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver:d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b W0625 08:25:46.798] Deleted [gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver:d3e7c3e0-fe87-4dc5-b77b-c4c73e55220b]. W0625 08:25:47.297] Deleted [gcr.io/k8s-gce-gci-1-6-mstr-ctl-skew/gcp-persistent-disk-csi-driver@sha256:f7137ff60ab8755b2515825a7b55d5a17251c736a96aadcbdacaae48afdf3b4f]. W0625 08:25:48.286] F0625 08:25:48.286066 6528 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 W0625 08:25:48.294] Traceback (most recent call last): W0625 08:25:48.294] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module> W0625 08:25:48.298] main(ARGS.env, ARGS.cmd + ARGS.args) W0625 08:25:48.298] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W0625 08:25:48.298] check(*cmd) W0625 08:25:48.298] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W0625 08:25:48.298] subprocess.check_call(cmd) W0625 08:25:48.298] File "/usr/lib/python2.7/subprocess.py", line 190, in check_call W0625 08:25:48.299] raise CalledProcessError(retcode, cmd) W0625 08:25:48.299] subprocess.CalledProcessError: Command '('test/run-windows-k8s-integration.sh',)' returned non-zero exit status 255 E0625 08:25:48.315] Command failed I0625 08:25:48.315] process 436 exited with code 1 after 77.3m E0625 08:25:48.316] FAIL: ci-gce-pd-csi-driver-latest-k8s-master-windows-2019 I0625 08:25:48.317] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0625 08:25:49.018] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com] I0625 08:25:49.160] process 174705 exited with code 0 after 0.0m I0625 08:25:49.160] Call: gcloud config get-value account I0625 08:25:49.883] process 174719 exited with code 0 after 0.0m I0625 08:25:49.883] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com I0625 08:25:49.884] Upload result and artifacts... I0625 08:25:49.884] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1540592701605416960 I0625 08:25:49.884] Call: gsutil ls gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1540592701605416960/artifacts W0625 08:25:51.154] CommandException: One or more URLs matched no objects. E0625 08:25:51.406] Command failed I0625 08:25:51.406] process 174733 exited with code 1 after 0.0m W0625 08:25:51.407] Remote dir gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1540592701605416960/artifacts not exist yet I0625 08:25:51.407] 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/1540592701605416960/artifacts I0625 08:25:56.165] process 174873 exited with code 0 after 0.1m W0625 08:25:56.166] metadata path /workspace/_artifacts/metadata.json does not exist W0625 08:25:56.166] metadata not found or invalid, init with empty metadata ... skipping 15 lines ...