ci-gce-pd-csi-driver-latest-k8s-master-windows-2019 #1541620883385225216
Recent runs || View in Spyglass
Recent runs || View in Spyglass
... skipping 155 lines ... W0628 03:16:20.065] I0628 03:16:20.065575 6560 utils.go:17] cmd env=[] W0628 03:16:20.066] I0628 03:16:20.065602 6560 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=d58ec35a-2856-405b-96c7-c70eef503870 GCE_PD_CSI_STAGING_IMAGE=gcr.io/k8s-jkns-pr-kubeadm/gcp-persistent-disk-csi-driver] W0628 03:16:20.067] Makefile:48: gcp-pd-driver-windows only supports amd64. I0628 03:16:20.168] make: Entering directory '/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver' I0628 03:16:20.168] # Ensure we use a builder that can leverage it (the default on linux will not) I0628 03:16:20.169] DOCKER_CLI_EXPERIMENTAL=enabled docker buildx rm multiarch-multiplatform-builder W0628 03:16:20.391] error: no builder "multiarch-multiplatform-builder" found W0628 03:16:20.400] make: [Makefile:134: init-buildx] Error 1 (ignored) I0628 03:16:20.500] DOCKER_CLI_EXPERIMENTAL=enabled docker buildx create --use --name=multiarch-multiplatform-builder I0628 03:16:20.548] multiarch-multiplatform-builder I0628 03:16:20.556] DOCKER_CLI_EXPERIMENTAL=enabled docker run --rm --privileged multiarch/qemu-user-static --reset --credential yes --persistent yes W0628 03:16:20.657] Unable to find image 'multiarch/qemu-user-static:latest' locally W0628 03:16:20.816] latest: Pulling from multiarch/qemu-user-static W0628 03:16:20.816] 19d511225f94: Pulling fs layer ... skipping 239 lines ... W0628 03:16:44.923] #11 7.000 Setting up libtinfo6:amd64 (6.1+20181013-2+deb10u2) ... W0628 03:16:44.923] #11 7.063 Selecting previously unselected package bash. W0628 03:16:44.923] #11 7.063 (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.) W0628 03:16:44.924] #11 7.070 Preparing to unpack .../archives/bash_5.0-4_amd64.deb ... W0628 03:16:44.924] #11 7.084 Unpacking bash (5.0-4) ... W0628 03:16:44.924] #11 7.416 Setting up bash (5.0-4) ... W0628 03:16:44.924] #11 7.454 update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist W0628 03:16:44.924] #11 7.547 Selecting previously unselected package libuuid1:amd64. W0628 03:16:44.925] #11 7.547 (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.) W0628 03:16:45.032] #11 7.558 Preparing to unpack .../libuuid1_2.33.1-0.1_amd64.deb ... W0628 03:16:45.032] #11 7.565 Unpacking libuuid1:amd64 (2.33.1-0.1) ... W0628 03:16:45.033] #11 7.625 Setting up libuuid1:amd64 (2.33.1-0.1) ... W0628 03:16:45.033] #11 7.692 Selecting previously unselected package libblkid1:amd64. ... skipping 492 lines ... W0628 03:18:26.954] #11 51.24 Setting up libtinfo6:arm64 (6.1+20181013-2+deb10u2) ... W0628 03:18:27.255] #11 51.55 Selecting previously unselected package bash. W0628 03:18:27.256] #11 51.56 (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.) W0628 03:18:27.256] #11 51.58 Preparing to unpack .../archives/bash_5.0-4_arm64.deb ... W0628 03:18:27.405] #11 51.69 Unpacking bash (5.0-4) ... W0628 03:18:28.608] #11 53.03 Setting up bash (5.0-4) ... W0628 03:18:28.909] #11 53.21 update-alternatives: error: alternative path /usr/share/man/man7/bash-builtins.7.gz doesn't exist W0628 03:18:30.263] #11 54.54 Selecting previously unselected package libuuid1:arm64. W0628 03:18:30.264] #11 54.54 (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.) W0628 03:18:30.264] #11 54.57 Preparing to unpack .../libuuid1_2.33.1-0.1_arm64.deb ... W0628 03:18:30.265] #11 54.58 Unpacking libuuid1:arm64 (2.33.1-0.1) ... W0628 03:18:30.564] #11 54.88 Setting up libuuid1:arm64 (2.33.1-0.1) ... W0628 03:18:30.865] #11 55.19 Selecting previously unselected package libblkid1:arm64. ... skipping 884 lines ... W0628 04:07:05.066] Trying to find master named 'e2e-test-prow-master' W0628 04:07:05.067] Looking for address 'e2e-test-prow-master-ip' W0628 04:07:07.010] Using master: e2e-test-prow-master (external IP: 34.66.209.175; internal IP: (not set)) I0628 04:07:07.111] Waiting up to 300 seconds for cluster initialization. I0628 04:07:07.111] I0628 04:07:07.111] This will continually check to see if the API for kubernetes is reachable. I0628 04:07:07.111] This may time out if there was some uncaught error during start up. I0628 04:07:07.111] I0628 04:07:38.802] ...........Kubernetes cluster created. I0628 04:07:39.038] Cluster "k8s-jkns-pr-kubeadm_e2e-test-prow" set. I0628 04:07:39.290] User "k8s-jkns-pr-kubeadm_e2e-test-prow" set. I0628 04:07:39.560] Context "k8s-jkns-pr-kubeadm_e2e-test-prow" created. I0628 04:07:39.807] Switched to context "k8s-jkns-pr-kubeadm_e2e-test-prow". ... skipping 48 lines ... I0628 04:14:25.715] e2e-test-prow-windows-node-group-wknr Ready <none> 14s v1.25.0-alpha.1.171+10bea49c12d3ea W0628 04:14:26.098] Warning: v1 ComponentStatus is deprecated in v1.19+ I0628 04:14:26.199] Validate output: W0628 04:14:26.521] Warning: v1 ComponentStatus is deprecated in v1.19+ W0628 04:14:26.531] Done, listing cluster services: W0628 04:14:26.532] I0628 04:14:26.632] NAME STATUS MESSAGE ERROR I0628 04:14:26.633] etcd-1 Healthy {"health":"true","reason":""} I0628 04:14:26.633] etcd-0 Healthy {"health":"true","reason":""} I0628 04:14:26.633] controller-manager Healthy ok I0628 04:14:26.633] scheduler Healthy ok I0628 04:14:26.634] [0;32mCluster validation succeeded[0m I0628 04:14:26.798] [0;32mKubernetes control plane[0m is running at [0;33mhttps://34.66.209.175[0m ... skipping 95 lines ... I0628 04:16:10.870] GCE_PD_SA_DIR is /tmp/gcp-pd-driver-tmp2364926004 I0628 04:16:10.872] PKGDIR is /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver I0628 04:16:10.874] installing kustomize I0628 04:16:12.908] {Version:kustomize/v3.9.4 GitCommit:e41d94ddef8cca7af2709c4104b41c4d43cd236c BuildDate:2021-02-09T19:22:10Z GoOs:linux GoArch:amd64} W0628 04:16:13.039] + [[ true != true ]] W0628 04:16:13.040] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh get namespace gce-pd-csi-driver -v=2 W0628 04:16:13.777] Error from server (NotFound): namespaces "gce-pd-csi-driver" not found W0628 04:16:13.787] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh create namespace gce-pd-csi-driver -v=2 I0628 04:16:14.539] namespace/gce-pd-csi-driver created W0628 04:16:14.641] + [[ ! stable-master == *noauth* ]] W0628 04:16:14.641] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh get secret cloud-sa -v=2 -n gce-pd-csi-driver W0628 04:16:15.276] Error from server (NotFound): secrets "cloud-sa" not found W0628 04:16:15.290] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh create secret generic cloud-sa -v=2 --from-file=/tmp/gcp-pd-driver-tmp2364926004/cloud-sa.json -n gce-pd-csi-driver I0628 04:16:16.013] secret/cloud-sa created W0628 04:16:16.117] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh get clusterrolebinding -v=2 cluster-admin-binding W0628 04:16:16.601] Error from server (NotFound): clusterrolebindings.rbac.authorization.k8s.io "cluster-admin-binding" not found W0628 04:16:16.614] ++ gcloud config get-value account W0628 04:16:18.604] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh create clusterrolebinding cluster-admin-binding -v=2 --clusterrole cluster-admin --user pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com I0628 04:16:19.309] clusterrolebinding.rbac.authorization.k8s.io/cluster-admin-binding created W0628 04:16:19.413] + /tmp/gcp-pd-driver-tmp1050122847/kubernetes/cluster/kubectl.sh version W0628 04:16:20.066] 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. W0628 04:16:20.079] + readonly tmp_spec=/tmp/gcp-compute-persistent-disk-csi-driver-specs-generated.yaml ... skipping 521 lines ... I0628 04:16:23.950] - args: I0628 04:16:23.951] - --v=5 I0628 04:16:23.951] - --csi-address=/csi/csi.sock I0628 04:16:23.952] - --http-endpoint=:22013 I0628 04:16:23.952] - --leader-election I0628 04:16:23.952] - --leader-election-namespace=$(PDCSI_NAMESPACE) I0628 04:16:23.952] - --handle-volume-inuse-error=false I0628 04:16:23.953] env: I0628 04:16:23.953] - name: PDCSI_NAMESPACE I0628 04:16:23.953] valueFrom: I0628 04:16:23.953] fieldRef: I0628 04:16:23.953] fieldPath: metadata.namespace I0628 04:16:23.954] image: k8s.gcr.io/sig-storage/csi-resizer:v1.4.0 ... skipping 384 lines ... W0628 04:25:59.028] Args: W0628 04:25:59.028] --v=5 W0628 04:25:59.028] --csi-address=/csi/csi.sock W0628 04:25:59.029] --http-endpoint=:22013 W0628 04:25:59.029] --leader-election W0628 04:25:59.029] --leader-election-namespace=$(PDCSI_NAMESPACE) W0628 04:25:59.029] --handle-volume-inuse-error=false W0628 04:25:59.029] State: Running W0628 04:25:59.029] Started: Tue, 28 Jun 2022 04:16:36 +0000 W0628 04:25:59.029] Ready: True W0628 04:25:59.030] Restart Count: 0 W0628 04:25:59.030] Liveness: http-get http://:http-endpoint/healthz/leader-election delay=10s timeout=10s period=20s #success=1 #failure=1 W0628 04:25:59.030] Environment: ... skipping 765 lines ... W0628 04:26:01.299] I0628 04:26:01.297941 6560 utils.go:18] cmd args=[kubetest2 gce --run-id=57e1c2c3-f690-11ec-a97c-3a5bc0fa4454 --test=ginkgo --legacy-mode --repo-root=/tmp/gcp-pd-driver-tmp1050122847/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] W0628 04:26:01.381] I0628 04:26:01.381474 172255 app.go:62] The files in RunDir shall not be part of Artifacts W0628 04:26:01.382] I0628 04:26:01.381638 172255 app.go:63] pass rundir-in-artifacts flag True for RunDir to be part of Artifacts W0628 04:26:01.382] I0628 04:26:01.381663 172255 app.go:65] RunDir for this run: "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454" W0628 04:26:01.385] I0628 04:26:01.384949 172255 app.go:129] ID for this run: "57e1c2c3-f690-11ec-a97c-3a5bc0fa4454" W0628 04:26:01.406] I0628 04:26:01.405658 172269 ginkgo.go:120] Using kubeconfig at /root/.kube/config W0628 04:26:01.406] F0628 04:26:01.406450 172269 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/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/kubectl"): stat /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/kubectl: no such file or directory W0628 04:26:01.408] goroutine 1 [running]: W0628 04:26:01.408] k8s.io/klog/v2.stacks(0x1) W0628 04:26:01.408] /go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:860 +0x8a W0628 04:26:01.408] k8s.io/klog/v2.(*loggingT).output(0x1163340, 0x3, 0x0, 0xc0004c58f0, 0x1, {0xdfd6e7?, 0x1?}, 0x1163ca0?, 0x0) W0628 04:26:01.409] /go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:825 +0x686 W0628 04:26:01.409] k8s.io/klog/v2.(*loggingT).printfDepth(0x1163340, 0x21a0?, 0x0, {0x0, 0x0}, 0x407f99?, {0xbe901a, 0x1f}, {0xc0004b1d30, 0x1, ...}) ... skipping 9 lines ... W0628 04:26:01.411] W0628 04:26:01.411] goroutine 19 [select]: W0628 04:26:01.411] go.opencensus.io/stats/view.(*worker).start(0xc000392400) W0628 04:26:01.411] /go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:276 +0xad W0628 04:26:01.411] created by go.opencensus.io/stats/view.init.0 W0628 04:26:01.411] /go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:34 +0x8d W0628 04:26:01.414] Error: exit status 255 W0628 04:26:01.421] I0628 04:26:01.421705 6560 main.go:741] Copying kubernetes binaries to path=/workspace/_artifacts/pd-volumesnapshotclass/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454 to run the tests W0628 04:26:01.422] I0628 04:26:01.421768 6560 main.go:796] copying /tmp/gcp-pd-driver-tmp1050122847/kubernetes/_output/dockerized/bin/linux/amd64/kubectl to /workspace/_artifacts/pd-volumesnapshotclass/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/kubectl W0628 04:26:01.568] I0628 04:26:01.568460 6560 main.go:796] copying /tmp/gcp-pd-driver-tmp1050122847/kubernetes/_output/dockerized/bin/linux/amd64/e2e.test to /workspace/_artifacts/pd-volumesnapshotclass/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/e2e.test W0628 04:26:02.371] I0628 04:26:02.370879 6560 main.go:796] copying /tmp/gcp-pd-driver-tmp1050122847/kubernetes/_output/dockerized/bin/linux/amd64/ginkgo to /workspace/_artifacts/pd-volumesnapshotclass/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/ginkgo W0628 04:26:02.402] I0628 04:26:02.401920 6560 utils.go:16] Running Tests W0628 04:26:02.402] I0628 04:26:02.401970 6560 utils.go:17] cmd env=[] W0628 04:26:02.403] I0628 04:26:02.401989 6560 utils.go:18] cmd args=[kubetest2 gce --run-id=57e1c2c3-f690-11ec-a97c-3a5bc0fa4454 --test=ginkgo --legacy-mode --repo-root=/tmp/gcp-pd-driver-tmp1050122847/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] W0628 04:26:02.442] I0628 04:26:02.441883 172287 app.go:62] The files in RunDir shall not be part of Artifacts W0628 04:26:02.442] I0628 04:26:02.441953 172287 app.go:63] pass rundir-in-artifacts flag True for RunDir to be part of Artifacts W0628 04:26:02.442] I0628 04:26:02.441974 172287 app.go:65] RunDir for this run: "/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454" W0628 04:26:02.445] I0628 04:26:02.444972 172287 app.go:129] ID for this run: "57e1c2c3-f690-11ec-a97c-3a5bc0fa4454" W0628 04:26:02.463] I0628 04:26:02.462968 172303 ginkgo.go:120] Using kubeconfig at /root/.kube/config W0628 04:26:02.464] F0628 04:26:02.464010 172303 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/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/kubectl"): stat /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/overlays/stable-master/_rundir/57e1c2c3-f690-11ec-a97c-3a5bc0fa4454/kubectl: no such file or directory W0628 04:26:02.464] goroutine 1 [running]: W0628 04:26:02.465] k8s.io/klog/v2.stacks(0x1) W0628 04:26:02.465] /go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:860 +0x8a W0628 04:26:02.465] k8s.io/klog/v2.(*loggingT).output(0x1163340, 0x3, 0x0, 0xc0004258f0, 0x1, {0xdfd6e7?, 0x1?}, 0x1163ca0?, 0x0) W0628 04:26:02.465] /go/pkg/mod/k8s.io/klog/v2@v2.60.1/klog.go:825 +0x686 W0628 04:26:02.466] k8s.io/klog/v2.(*loggingT).printfDepth(0x1163340, 0x21a0?, 0x0, {0x0, 0x0}, 0x407f99?, {0xbe901a, 0x1f}, {0xc000411d60, 0x1, ...}) ... skipping 9 lines ... W0628 04:26:02.467] W0628 04:26:02.468] goroutine 6 [select]: W0628 04:26:02.469] go.opencensus.io/stats/view.(*worker).start(0xc0002f2400) W0628 04:26:02.469] /go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:276 +0xad W0628 04:26:02.469] created by go.opencensus.io/stats/view.init.0 W0628 04:26:02.470] /go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:34 +0x8d W0628 04:26:02.471] Error: exit status 255 W0628 04:26:02.477] I0628 04:26:02.476854 6560 utils.go:16] Deleting driver W0628 04:26:02.481] I0628 04:26:02.476876 6560 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=57e1c2c3-f690-11ec-a97c-3a5bc0fa4454 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/1541620883385225216/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=1541620883385225216 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=57e1c2c3-f690-11ec-a97c-3a5bc0fa4454 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=57e1c2c3-f690-11ec-a97c-3a5bc0fa4454 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":"1541620883385225216","prowjobid":"57e1c2c3-f690-11ec-a97c-3a5bc0fa4454","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=1541620883385225216 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-pr-kubeadm GCE_PD_CSI_STAGING_VERSION=d58ec35a-2856-405b-96c7-c70eef503870 GCE_PD_CSI_STAGING_IMAGE=gcr.io/k8s-jkns-pr-kubeadm/gcp-persistent-disk-csi-driver GCE_PD_KUBECTL=/tmp/gcp-pd-driver-tmp1050122847/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] W0628 04:26:02.481] I0628 04:26:02.476929 6560 utils.go:18] cmd args=[/go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver/deploy/kubernetes/delete-driver.sh] I0628 04:26:02.582] PKGDIR is /go/src/sigs.k8s.io/gcp-compute-persistent-disk-csi-driver I0628 04:26:02.582] installing kustomize I0628 04:26:03.635] {Version:kustomize/v3.9.4 GitCommit:e41d94ddef8cca7af2709c4104b41c4d43cd236c BuildDate:2021-02-09T19:22:10Z GoOs:linux GoArch:amd64} ... skipping 92 lines ... W0628 04:32:54.674] Associated tags: W0628 04:32:54.674] - d58ec35a-2856-405b-96c7-c70eef503870 W0628 04:32:54.674] Tags: W0628 04:32:54.675] - gcr.io/k8s-jkns-pr-kubeadm/gcp-persistent-disk-csi-driver:d58ec35a-2856-405b-96c7-c70eef503870 W0628 04:32:54.973] Deleted [gcr.io/k8s-jkns-pr-kubeadm/gcp-persistent-disk-csi-driver:d58ec35a-2856-405b-96c7-c70eef503870]. W0628 04:32:55.315] Deleted [gcr.io/k8s-jkns-pr-kubeadm/gcp-persistent-disk-csi-driver@sha256:13065cee7c0ea90241d7bc0026bf8c681ee78a9b80f82c13a50460a42efd5f87]. W0628 04:32:56.481] F0628 04:32:56.481031 6560 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 W0628 04:32:56.497] Traceback (most recent call last): W0628 04:32:56.497] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module> W0628 04:32:56.501] main(ARGS.env, ARGS.cmd + ARGS.args) W0628 04:32:56.502] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W0628 04:32:56.502] check(*cmd) W0628 04:32:56.502] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W0628 04:32:56.503] subprocess.check_call(cmd) W0628 04:32:56.503] File "/usr/lib/python2.7/subprocess.py", line 190, in check_call W0628 04:32:56.505] raise CalledProcessError(retcode, cmd) W0628 04:32:56.511] subprocess.CalledProcessError: Command '('test/run-windows-k8s-integration.sh',)' returned non-zero exit status 255 E0628 04:32:56.536] Command failed I0628 04:32:56.537] process 436 exited with code 1 after 78.8m E0628 04:32:56.538] FAIL: ci-gce-pd-csi-driver-latest-k8s-master-windows-2019 I0628 04:32:56.540] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W0628 04:32:57.295] Activated service account credentials for: [pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com] I0628 04:32:57.473] process 173805 exited with code 0 after 0.0m I0628 04:32:57.473] Call: gcloud config get-value account I0628 04:32:58.231] process 173819 exited with code 0 after 0.0m I0628 04:32:58.231] Will upload results to gs://kubernetes-jenkins/logs using pr-kubekins@kubernetes-jenkins-pull.iam.gserviceaccount.com I0628 04:32:58.232] Upload result and artifacts... I0628 04:32:58.232] Gubernator results at https://gubernator.k8s.io/build/kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541620883385225216 I0628 04:32:58.233] Call: gsutil ls gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541620883385225216/artifacts W0628 04:32:59.628] CommandException: One or more URLs matched no objects. E0628 04:32:59.927] Command failed I0628 04:32:59.928] process 173833 exited with code 1 after 0.0m W0628 04:32:59.928] Remote dir gs://kubernetes-jenkins/logs/ci-gce-pd-csi-driver-latest-k8s-master-windows-2019/1541620883385225216/artifacts not exist yet I0628 04:32:59.928] 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/1541620883385225216/artifacts I0628 04:33:05.477] process 173973 exited with code 0 after 0.1m W0628 04:33:05.478] metadata path /workspace/_artifacts/metadata.json does not exist W0628 04:33:05.479] metadata not found or invalid, init with empty metadata ... skipping 15 lines ...