This job view page is being replaced by Spyglass soon. Check out the new job view.
PRjsafrane: Rework volume reconstruction
ResultABORTED
Tests 0 failed / 12 succeeded
Started2022-11-02 15:07
Elapsed1h7m
Revision5d2a018ad2601a5dc4a17845bfb0640db45c5ede
Refs 113262

No Test Failures!


Show 12 Passed Tests

Show 48 Skipped Tests

Error lines from build-log.txt

... skipping 126 lines ...
/home/prow/go/src/k8s.io/kubernetes /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure
/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100   138  100   138    0     0   4451      0 --:--:-- --:--:-- --:--:--  4451

100    33  100    33    0     0    434      0 --:--:-- --:--:-- --:--:--   434
Error response from daemon: manifest for capzci.azurecr.io/kube-apiserver:v1.26.0-alpha.3.58_6a6635c48422b9 not found: manifest unknown: manifest tagged by "v1.26.0-alpha.3.58_6a6635c48422b9" is not found
Building Kubernetes
make: Entering directory '/home/prow/go/src/k8s.io/kubernetes'
+++ [1102 15:07:58] Verifying Prerequisites....
+++ [1102 15:07:59] Building Docker image kube-build:build-7624de3211-5-v1.25.0-go1.19.2-bullseye.0
+++ [1102 15:10:23] Creating data container kube-build-data-7624de3211-5-v1.25.0-go1.19.2-bullseye.0
+++ [1102 15:10:38] Syncing sources to container
... skipping 771 lines ...
certificate.cert-manager.io "selfsigned-cert" deleted
# Create secret for AzureClusterIdentity
./hack/create-identity-secret.sh
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
make[2]: Nothing to be done for 'kubectl'.
make[2]: Leaving directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
Error from server (NotFound): secrets "cluster-identity-secret" not found
secret/cluster-identity-secret created
secret/cluster-identity-secret labeled
# Create customized cloud provider configs
./hack/create-custom-cloud-provider-config.sh
make[2]: Entering directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
make[2]: Nothing to be done for 'kubectl'.
... skipping 134 lines ...
# Wait for the kubeconfig to become available.
timeout --foreground 300 bash -c "while ! /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 get secrets | grep capz-z3i3kp-kubeconfig; do sleep 1; done"
capz-z3i3kp-kubeconfig                 cluster.x-k8s.io/secret   1      1s
# Get kubeconfig and store it locally.
/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 get secrets capz-z3i3kp-kubeconfig -o json | jq -r .data.value | base64 --decode > ./kubeconfig
timeout --foreground 600 bash -c "while ! /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 --kubeconfig=./kubeconfig get nodes | grep control-plane; do sleep 1; done"
error: the server doesn't have a resource type "nodes"
capz-z3i3kp-control-plane-p7ssx   NotReady   control-plane   1s    v1.26.0-alpha.3.58+6a6635c48422b9
run "/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/hack/tools/bin/kubectl-v1.22.4 --kubeconfig=./kubeconfig ..." to work with the new target cluster
make[1]: Leaving directory '/home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure'
Waiting for 1 control plane machine(s), 2 worker machine(s), and  windows machine(s) to become Ready
node/capz-z3i3kp-control-plane-p7ssx condition met
node/capz-z3i3kp-mp-0000000 condition met
... skipping 62 lines ...
Dynamic Provisioning [single-az] 
  should create a volume on demand with mount options [kubernetes.io/azure-disk] [disk.csi.azure.com] [Windows]
  /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/dynamic_provisioning_test.go:101
STEP: Creating a kubernetes client
Nov  2 15:41:44.208: INFO: >>> kubeConfig: /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/kubeconfig
STEP: Building a namespace api object, basename azuredisk
Nov  2 15:41:44.472: INFO: Error listing PodSecurityPolicies; assuming PodSecurityPolicy is disabled: the server could not find the requested resource
STEP: Waiting for a default service account to be provisioned in namespace
STEP: Waiting for kube-root-ca.crt to be provisioned in namespace
2022/11/02 15:41:44 Check driver pods if restarts ...
check the driver pods if restarts ...
======================================================================================
2022/11/02 15:41:44 Check successfully
Nov  2 15:41:44.841: INFO: >>> kubeConfig: /home/prow/go/src/sigs.k8s.io/cluster-api-provider-azure/kubeconfig
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Nov  2 15:41:44.963: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-vhbv2" in namespace "azuredisk-8081" to be "Succeeded or Failed"
Nov  2 15:41:45.021: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 58.049929ms
Nov  2 15:41:47.052: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 2.089656118s
Nov  2 15:41:49.084: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 4.121539234s
Nov  2 15:41:51.117: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 6.154262752s
Nov  2 15:41:53.150: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 8.187189239s
Nov  2 15:41:55.184: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 10.220764888s
... skipping 9 lines ...
Nov  2 15:42:15.510: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 30.547523763s
Nov  2 15:42:17.543: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 32.580649621s
Nov  2 15:42:19.577: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 34.61452807s
Nov  2 15:42:21.612: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Pending", Reason="", readiness=false. Elapsed: 36.649433306s
Nov  2 15:42:23.647: INFO: Pod "azuredisk-volume-tester-vhbv2": Phase="Succeeded", Reason="", readiness=false. Elapsed: 38.683906742s
STEP: Saw pod success
Nov  2 15:42:23.647: INFO: Pod "azuredisk-volume-tester-vhbv2" satisfied condition "Succeeded or Failed"
Nov  2 15:42:23.647: INFO: deleting Pod "azuredisk-8081"/"azuredisk-volume-tester-vhbv2"
Nov  2 15:42:23.695: INFO: Pod azuredisk-volume-tester-vhbv2 has the following logs: hello world

STEP: Deleting pod azuredisk-volume-tester-vhbv2 in namespace azuredisk-8081
STEP: validating provisioned PV
STEP: checking the PV
... skipping 104 lines ...
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod has 'FailedMount' event
Nov  2 15:44:10.358: INFO: deleting Pod "azuredisk-5466"/"azuredisk-volume-tester-58hkm"
Nov  2 15:44:10.404: INFO: Error getting logs for pod azuredisk-volume-tester-58hkm: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-58hkm)
STEP: Deleting pod azuredisk-volume-tester-58hkm in namespace azuredisk-5466
STEP: validating provisioned PV
STEP: checking the PV
Nov  2 15:44:10.507: INFO: deleting PVC "azuredisk-5466"/"pvc-xzs4r"
Nov  2 15:44:10.507: INFO: Deleting PersistentVolumeClaim "pvc-xzs4r"
STEP: waiting for claim's PV "pvc-6b284846-e5a8-46aa-bdeb-dde22d2e6b96" to be deleted
... skipping 62 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Nov  2 15:46:57.509: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-bfn45" in namespace "azuredisk-2790" to be "Succeeded or Failed"
Nov  2 15:46:57.540: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 31.100054ms
Nov  2 15:46:59.573: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 2.063787487s
Nov  2 15:47:01.605: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 4.096607483s
Nov  2 15:47:03.638: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 6.128876446s
Nov  2 15:47:05.670: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 8.160921967s
Nov  2 15:47:07.702: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 10.193573381s
... skipping 9 lines ...
Nov  2 15:47:28.034: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 30.524894482s
Nov  2 15:47:30.067: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 32.557735918s
Nov  2 15:47:32.100: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Pending", Reason="", readiness=false. Elapsed: 34.591629204s
Nov  2 15:47:34.134: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Running", Reason="", readiness=false. Elapsed: 36.6255522s
Nov  2 15:47:36.169: INFO: Pod "azuredisk-volume-tester-bfn45": Phase="Succeeded", Reason="", readiness=false. Elapsed: 38.660035912s
STEP: Saw pod success
Nov  2 15:47:36.169: INFO: Pod "azuredisk-volume-tester-bfn45" satisfied condition "Succeeded or Failed"
Nov  2 15:47:36.169: INFO: deleting Pod "azuredisk-2790"/"azuredisk-volume-tester-bfn45"
Nov  2 15:47:36.218: INFO: Pod azuredisk-volume-tester-bfn45 has the following logs: e2e-test

STEP: Deleting pod azuredisk-volume-tester-bfn45 in namespace azuredisk-2790
STEP: validating provisioned PV
STEP: checking the PV
... skipping 46 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with an error
Nov  2 15:48:47.627: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-pspbd" in namespace "azuredisk-5356" to be "Error status code"
Nov  2 15:48:47.658: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 31.186894ms
Nov  2 15:48:49.691: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 2.064249645s
Nov  2 15:48:51.724: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 4.096906537s
Nov  2 15:48:53.756: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 6.12871604s
Nov  2 15:48:55.787: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 8.160643094s
Nov  2 15:48:57.820: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 10.19338927s
Nov  2 15:48:59.852: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 12.22555849s
Nov  2 15:49:01.884: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 14.257307722s
Nov  2 15:49:03.917: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 16.290607073s
Nov  2 15:49:05.949: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 18.322619719s
Nov  2 15:49:07.982: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 20.355024644s
Nov  2 15:49:10.015: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 22.38802758s
Nov  2 15:49:12.050: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Pending", Reason="", readiness=false. Elapsed: 24.422886635s
Nov  2 15:49:14.083: INFO: Pod "azuredisk-volume-tester-pspbd": Phase="Failed", Reason="", readiness=false. Elapsed: 26.456224466s
STEP: Saw pod failure
Nov  2 15:49:14.083: INFO: Pod "azuredisk-volume-tester-pspbd" satisfied condition "Error status code"
STEP: checking that pod logs contain expected message
Nov  2 15:49:14.128: INFO: deleting Pod "azuredisk-5356"/"azuredisk-volume-tester-pspbd"
Nov  2 15:49:14.162: INFO: Pod azuredisk-volume-tester-pspbd has the following logs: touch: /mnt/test-1/data: Read-only file system

STEP: Deleting pod azuredisk-volume-tester-pspbd in namespace azuredisk-5356
STEP: validating provisioned PV
... skipping 408 lines ...
STEP: setting up the StorageClass
STEP: creating a StorageClass 
STEP: setting up the PVC and PV
STEP: creating a PVC
STEP: setting up the pod
STEP: deploying the pod
STEP: checking that the pod's command exits with no error
Nov  2 16:00:14.397: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-94zph" in namespace "azuredisk-59" to be "Succeeded or Failed"
Nov  2 16:00:14.433: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 36.793185ms
Nov  2 16:00:16.465: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 2.068784206s
Nov  2 16:00:18.499: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 4.10241907s
Nov  2 16:00:20.533: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 6.136834245s
Nov  2 16:00:22.567: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 8.170352128s
Nov  2 16:00:24.603: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 10.206173145s
... skipping 46 lines ...
Nov  2 16:02:00.221: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 1m45.824041904s
Nov  2 16:02:02.255: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 1m47.858062924s
Nov  2 16:02:04.288: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 1m49.891456061s
Nov  2 16:02:06.321: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 1m51.924828373s
Nov  2 16:02:08.355: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 1m53.958448561s
Nov  2 16:02:10.389: INFO: Pod "azuredisk-volume-tester-94zph": Phase="Pending", Reason="", readiness=false. Elapsed: 1m55.992253665s
{"component":"entrypoint","file":"k8s.io/test-infra/prow/entrypoint/run.go:168","func":"k8s.io/test-infra/prow/entrypoint.Options.ExecuteProcess","level":"error","msg":"Entrypoint received interrupt: terminated","severity":"error","time":"2022-11-02T16:02:12Z"}
++ early_exit_handler
++ '[' -n 162 ']'
++ kill -TERM 162
++ cleanup_dind
++ [[ true == \t\r\u\e ]]
++ echo 'Cleaning up after docker'
... skipping 4 lines ...