Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

CSI-Provisioner unhandled exceptions #2263

Open
LMmarsupial opened this issue Dec 11, 2024 · 14 comments
Open

CSI-Provisioner unhandled exceptions #2263

LMmarsupial opened this issue Dec 11, 2024 · 14 comments
Labels
kind/bug Categorizes issue or PR as related to a bug.

Comments

@LMmarsupial
Copy link

/kind bug

What happened?
Followed instructions on github page to deploy to deploy ebs-csi-driver, and provision a volume using a custom storage class. EBS driver is fully deployed, but the pvc's are stuck in pending and the provisioner is throwing unhandled exceptions. Eventually it ends with rate limit exceptions.

What you expected to happen?
a pvc successfully bound with the storage class specified

How to reproduce it (as minimally and precisely as possible)?
deploy EBS driver, attempt to provision volume using storage class.

Anything else we need to know?:
attaching csi-provisioner log and describe of a volume with events. Rate limit errors continue to appear after it errors out with the unhandled exceptions. I think those are just a product of the unhandled exception.

1211 20:40:49.550015       1 feature_gate.go:354] Setting GA feature gate Topology=true. It will be removed in a future release.
I1211 20:40:49.550148       1 feature_gate.go:387] feature gates: {map[Topology:true]}
I1211 20:40:49.550193       1 csi-provisioner.go:154] Version: v5.1.0
I1211 20:40:49.550213       1 csi-provisioner.go:177] Building kube configs for running in cluster...
I1211 20:40:53.637158       1 common.go:143] "Probing CSI driver for readiness"
I1211 20:40:53.642588       1 csi-provisioner.go:230] Detected CSI driver ebs.csi.aws.com
I1211 20:40:53.642651       1 csi-provisioner.go:240] Supports migration from in-tree plugin: kubernetes.io/aws-ebs
I1211 20:40:53.643851       1 common.go:143] "Probing CSI driver for readiness"
I1211 20:40:53.645761       1 csi-provisioner.go:299] CSI driver supports PUBLISH_UNPUBLISH_VOLUME, watching VolumeAttachments
I1211 20:40:53.646735       1 controller.go:744] "Using saving PVs to API server in background"
I1211 20:40:53.647436       1 leaderelection.go:254] attempting to acquire leader lease cloud-storage/ebs-csi-aws-com...
I1211 20:41:09.240800       1 leaderelection.go:268] successfully acquired lease cloud-storage/ebs-csi-aws-com
I1211 20:41:09.241024       1 leader_election.go:184] "became leader, starting"
I1211 20:41:09.241546       1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=false
I1211 20:41:09.241672       1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=false
I1211 20:41:09.264243       1 reflector.go:368] Caches populated for *v1.StorageClass from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.267013       1 reflector.go:368] Caches populated for *v1.CSINode from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.267721       1 reflector.go:368] Caches populated for *v1.PersistentVolumeClaim from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.268438       1 reflector.go:368] Caches populated for *v1.VolumeAttachment from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.307763       1 reflector.go:368] Caches populated for *v1.Node from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.341549       1 controller.go:824] "Starting provisioner controller" component="ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-sw8nb_2e65428b-e2c0-4c66-8168-d1dc2576519e"
I1211 20:41:09.341640       1 volume_store.go:98] "Starting save volume queue"
I1211 20:41:09.344278       1 reflector.go:368] Caches populated for *v1.StorageClass from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.345516       1 reflector.go:368] Caches populated for *v1.PersistentVolume from k8s.io/[email protected]/tools/cache/reflector.go:243
I1211 20:41:09.441979       1 controller.go:873] "Started provisioner controller" component="ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-sw8nb_2e65428b-e2c0-4c66-8168-d1dc2576519e"
I1211 20:41:09.442255       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-0\""
I1211 20:41:09.442317       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-2\""
I1211 20:41:09.442350       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-1\""
I1211 20:42:09.443100       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="0cb06f85-3e26-4991-8f84-f25356658f63"
I1211 20:42:09.443698       1 controller.go:951] "Retrying syncing claim" key="0cb06f85-3e26-4991-8f84-f25356658f63" failures=0
E1211 20:42:09.443760       1 controller.go:974] "Unhandled Error" err="error syncing claim \"0cb06f85-3e26-4991-8f84-f25356658f63\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:42:09.443128       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="79b3803f-2e6d-4111-b7d7-502db8393eb9"
I1211 20:42:09.443854       1 controller.go:951] "Retrying syncing claim" key="79b3803f-2e6d-4111-b7d7-502db8393eb9" failures=0
I1211 20:42:09.443147       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="81af414d-1239-4752-9743-c4244d429407"
E1211 20:42:09.443918       1 controller.go:974] "Unhandled Error" err="error syncing claim \"79b3803f-2e6d-4111-b7d7-502db8393eb9\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:42:09.443936       1 controller.go:951] "Retrying syncing claim" key="81af414d-1239-4752-9743-c4244d429407" failures=0
E1211 20:42:09.443979       1 controller.go:974] "Unhandled Error" err="error syncing claim \"81af414d-1239-4752-9743-c4244d429407\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:42:09.443169       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:42:09.444031       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:42:09.444065       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:42:10.444382       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-1\""
I1211 20:42:10.444441       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-2\""
I1211 20:42:10.444467       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-0\""
I1211 20:43:10.444865       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="79b3803f-2e6d-4111-b7d7-502db8393eb9"
I1211 20:43:10.445419       1 controller.go:951] "Retrying syncing claim" key="79b3803f-2e6d-4111-b7d7-502db8393eb9" failures=1
E1211 20:43:10.445463       1 controller.go:974] "Unhandled Error" err="error syncing claim \"79b3803f-2e6d-4111-b7d7-502db8393eb9\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:43:10.444866       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="81af414d-1239-4752-9743-c4244d429407"
I1211 20:43:10.445537       1 controller.go:951] "Retrying syncing claim" key="81af414d-1239-4752-9743-c4244d429407" failures=1
I1211 20:43:10.444882       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="0cb06f85-3e26-4991-8f84-f25356658f63"
I1211 20:43:10.445640       1 controller.go:951] "Retrying syncing claim" key="0cb06f85-3e26-4991-8f84-f25356658f63" failures=1
E1211 20:43:10.445705       1 controller.go:974] "Unhandled Error" err="error syncing claim \"0cb06f85-3e26-4991-8f84-f25356658f63\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:43:10.444901       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:43:10.445760       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:43:10.445794       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
E1211 20:43:10.445608       1 controller.go:974] "Unhandled Error" err="error syncing claim \"81af414d-1239-4752-9743-c4244d429407\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:43:12.446259       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-2\""
I1211 20:43:12.446312       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-1\""
I1211 20:43:12.446602       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-0\""
I1211 20:44:12.447316       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="0cb06f85-3e26-4991-8f84-f25356658f63"
I1211 20:44:12.447914       1 controller.go:951] "Retrying syncing claim" key="0cb06f85-3e26-4991-8f84-f25356658f63" failures=2
E1211 20:44:12.447965       1 controller.go:974] "Unhandled Error" err="error syncing claim \"0cb06f85-3e26-4991-8f84-f25356658f63\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:44:12.447424       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="81af414d-1239-4752-9743-c4244d429407"
I1211 20:44:12.448100       1 controller.go:951] "Retrying syncing claim" key="81af414d-1239-4752-9743-c4244d429407" failures=2
E1211 20:44:12.448159       1 controller.go:974] "Unhandled Error" err="error syncing claim \"81af414d-1239-4752-9743-c4244d429407\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:44:12.447442       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="79b3803f-2e6d-4111-b7d7-502db8393eb9"
I1211 20:44:12.448294       1 controller.go:951] "Retrying syncing claim" key="79b3803f-2e6d-4111-b7d7-502db8393eb9" failures=2
E1211 20:44:12.448336       1 controller.go:974] "Unhandled Error" err="error syncing claim \"79b3803f-2e6d-4111-b7d7-502db8393eb9\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:44:12.447468       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:44:12.448435       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:44:12.448469       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:44:12.448496       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-1\""
I1211 20:44:12.448522       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-2\""
I1211 20:44:12.448627       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-0\""
I1211 20:45:12.449152       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="0cb06f85-3e26-4991-8f84-f25356658f63"
I1211 20:45:12.449302       1 controller.go:951] "Retrying syncing claim" key="0cb06f85-3e26-4991-8f84-f25356658f63" failures=3
I1211 20:45:12.449153       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="81af414d-1239-4752-9743-c4244d429407"
I1211 20:45:12.449344       1 controller.go:951] "Retrying syncing claim" key="81af414d-1239-4752-9743-c4244d429407" failures=3
I1211 20:45:12.449178       1 controller.go:1101] "Temporary error received, adding PVC to claims in progress" claimUID="79b3803f-2e6d-4111-b7d7-502db8393eb9"
I1211 20:45:12.449395       1 controller.go:951] "Retrying syncing claim" key="79b3803f-2e6d-4111-b7d7-502db8393eb9" failures=3
E1211 20:45:12.449444       1 controller.go:974] "Unhandled Error" err="error syncing claim \"81af414d-1239-4752-9743-c4244d429407\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
E1211 20:45:12.449447       1 controller.go:974] "Unhandled Error" err="error syncing claim \"79b3803f-2e6d-4111-b7d7-502db8393eb9\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
E1211 20:45:12.449350       1 controller.go:974] "Unhandled Error" err="error syncing claim \"0cb06f85-3e26-4991-8f84-f25356658f63\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded" logger="UnhandledError"
I1211 20:45:12.449212       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:45:12.449749       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:45:12.449780       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = DeadlineExceeded desc = context deadline exceeded"
I1211 20:45:12.449807       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-1" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-1\""
I1211 20:45:12.449835       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-2\""
I1211 20:45:12.449861       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-0" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Normal" reason="Provisioning" message="External provisioner is provisioning volume for claim \"infrastructure/data-nova-zookeeper-0\""
I1211 20:45:56.735973       1 controller.go:1094] "Final error received, removing PVC from claims in progress" claimUID="81af414d-1239-4752-9743-c4244d429407"
I1211 20:45:56.736023       1 controller.go:951] "Retrying syncing claim" key="81af414d-1239-4752-9743-c4244d429407" failures=4
I1211 20:45:56.736071       1 event.go:389] "Event occurred" object="infrastructure/data-nova-zookeeper-2" fieldPath="" kind="PersistentVolumeClaim" apiVersion="v1" type="Warning" reason="ProvisioningFailed" message="failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = Internal desc = Could not create volume \"pvc-81af414d-1239-4752-9743-c4244d429407\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1211 20:45:56.736081       1 controller.go:974] "Unhandled Error" err="error syncing claim \"81af414d-1239-4752-9743-c4244d429407\": failed to provision volume with StorageClass \"block-single-standard\": rpc error: code = Internal desc = Could not create volume \"pvc-81af414d-1239-4752-9743-c4244d429407\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested" logger="UnhandledError"
E1211 20:45:56.736121       1 controller.go:1044] "Unhandled Error" err="claim \"81af414d-1239-4752-9743-c4244d429407\" in work queue no longer exists" logger="UnhandledError"
I1211 20:46:00.511425       1 controller.go:1094] "Final error received, removing PVC from claims in progress" claimUID="0cb06f85-3e26-4991-8f84-f25356658f63"
I1211 20:46:00.511485       1 controller.go:951] "Retrying syncing claim" key="0cb06f85-3e26-4991-8f84-f25356658f63" failures=4
Events:
  Type     Reason                Age                   From                                                                                      Message
  ----     ------                ----                  ----                                                                                      -------
  Normal   WaitForFirstConsumer  22m                   persistentvolume-controller                                                               waiting for first consumer to be created before binding
  Warning  ProvisioningFailed    17m (x5 over 21m)     ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-bbhvs_4e6b539f-0949-45a4-a112-12e2edf78cc5  failed to provision volume with StorageClass "block-single-standard": rpc error: code = DeadlineExceeded desc = context deadline exceeded
  Normal   Provisioning          4m48s (x11 over 22m)  ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-bbhvs_4e6b539f-0949-45a4-a112-12e2edf78cc5  External provisioner is provisioning volume for claim "infrastructure/data-nova-zookeeper-0"
  Warning  ProvisioningFailed    4m48s (x6 over 17m)   ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-bbhvs_4e6b539f-0949-45a4-a112-12e2edf78cc5  failed to provision volume with StorageClass "block-single-standard": rpc error: code = Internal desc = Could not create volume "pvc-79b3803f-2e6d-4111-b7d7-502db8393eb9": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested
  Normal   ExternalProvisioning  2m32s (x82 over 22m)  persistentvolume-controller                                                               waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator

Environment

  • Kubernetes version (use kubectl version): 1.24.17
  • Driver version: 1.35
@k8s-ci-robot k8s-ci-robot added the kind/bug Categorizes issue or PR as related to a bug. label Dec 11, 2024
@AndrewSirenko
Copy link
Contributor

AndrewSirenko commented Dec 12, 2024

Hi @LMmarsupial, let me try to reproduce your issue on a 1.24 cluster.

In the meantime, would you attach:

  • TheStorageClass and PVC manifests you used
  • The ebs-plugin container logs for this timeframe
  • (And if you are able) The output of describing the ebs-csi-controller deployment (deleting any sensitive data).

I'm perplexed by the Unhandled Error lines from the external-provisioner sidecar.

Thank you.

@AndrewSirenko
Copy link
Contributor

Hi @LMmarsupial, I just performed the following test and was not able to reproduce your issue:

  1. Created an EKS 1.24 cluster
  2. Install EBS CSI Driver v1.35.1
  3. Worked through our dynamic-provisioning example

Everything worked fine for me.

Are you able to properly follow the dynamic-provisioning example?

@LMmarsupial
Copy link
Author

apiVersion: storage.k8s.io/v1
kind: StorageClass
metadata:
  name: block-single-standard
provisioner: ebs.csi.aws.com
volumeBindingMode: WaitForFirstConsumer
parameters:
  csi.storage.k8s.io/fstype: xfs
  type: gp3
  iops: "3000"
  throughput: "125"
  encrypted: "true"

apologies, I didn't save off the logs for the ebs-plugin. I will send you newer ones, but they won't line up in terms of time.

I1212 20:53:25.966702       1 metrics.go:110] "Metric server listening" address="0.0.0.0:3301" path="/metrics"
I1212 20:53:25.969573       1 main.go:157] "Initializing metadata"
I1212 20:53:29.483399       1 metadata.go:48] "Retrieved metadata from IMDS"
I1212 20:53:29.485218       1 driver.go:69] "Driver Information" Driver="ebs.csi.aws.com" Version="v1.35.0"
E1212 20:54:45.380376       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:54:45.380376       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:54:45.380376       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:55:46.382247       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:55:46.382282       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context deadline exceeded"
E1212 20:55:46.382323       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context deadline exceeded"
E1212 20:56:48.384974       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:56:48.385000       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:56:48.385059       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:57:52.387279       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:57:52.387336       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:57:52.387280       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:58:53.336519       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1212 20:59:00.389250       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:59:00.389250       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
E1212 20:59:09.339631       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1212 20:59:16.391609       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1212 20:59:16.391621       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1212 20:59:41.341487       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-af43613c-8526-432d-91a0-2a51d180f280\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1212 20:59:48.393705       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-92b40105-ae56-4e70-99b0-4d35d1f7f1e4\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
E1212 20:59:48.393705       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-978ededc-fd5a-4c76-ad78-2d61a98ac627\": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested"
> kubectl describe deployment ebs-csi-controller -n cloud-storage
Name:                   ebs-csi-controller
Namespace:              cloud-storage
CreationTimestamp:      Wed, 11 Dec 2024 20:40:47 +0000
Labels:                 app.kubernetes.io/component=csi-driver
                      app.kubernetes.io/instance=aws-storage
                      app.kubernetes.io/managed-by=Helm
                      app.kubernetes.io/name=aws-ebs-csi-driver
                      app.kubernetes.io/version=1.35.0
                      argocd.argoproj.io/instance=aws-storage
                      helm.sh/chart=aws-ebs-csi-driver-2.35.1
Annotations:            deployment.kubernetes.io/revision: 1
Selector:               app=ebs-csi-controller,app.kubernetes.io/instance=aws-storage,app.kubernetes.io/name=aws-ebs-csi-driver
Replicas:               2 desired | 2 updated | 2 total | 2 available | 0 unavailable
StrategyType:           RollingUpdate
MinReadySeconds:        0
RollingUpdateStrategy:  1 max unavailable, 25% max surge
Pod Template:
Labels:           app=ebs-csi-controller
                  app.kubernetes.io/component=csi-driver
                  app.kubernetes.io/instance=aws-storage
                  app.kubernetes.io/managed-by=Helm
                  app.kubernetes.io/name=aws-ebs-csi-driver
                  app.kubernetes.io/version=1.35.0
                  helm.sh/chart=aws-ebs-csi-driver-2.35.1
Service Account:  ebs-csi-controller-sa
Containers:
 ebs-plugin:
  Image:       ext.public.ecr.aws/ebs-csi-driver/aws-ebs-csi-driver:v1.35.0
  Ports:       9808/TCP, 3301/TCP
  Host Ports:  0/TCP, 0/TCP
  Args:
    controller
    --endpoint=$(CSI_ENDPOINT)
    --http-endpoint=0.0.0.0:3301
    --batching=true
    --logging-format=text
    --user-agent-extra=helm
    --v=2
  Limits:
    memory:  256Mi
  Requests:
    cpu:      10m
    memory:   40Mi
  Liveness:   http-get http://:healthz/healthz delay=10s timeout=3s period=10s #success=1 #failure=5
  Readiness:  http-get http://:healthz/healthz delay=10s timeout=3s period=10s #success=1 #failure=5
  Environment:
    CSI_ENDPOINT:           unix:///var/lib/csi/sockets/pluginproxy/csi.sock
    CSI_NODE_NAME:           (v1:spec.nodeName)
    AWS_ACCESS_KEY_ID:      <set to the key 'key_id' in secret 'aws-secret'>      Optional: true
    AWS_SECRET_ACCESS_KEY:  <set to the key 'access_key' in secret 'aws-secret'>  Optional: true
    AWS_EC2_ENDPOINT:       <set to the key 'endpoint' of config map 'aws-meta'>  Optional: true
  Mounts:
    /var/lib/csi/sockets/pluginproxy/ from socket-dir (rw)
 csi-provisioner:
  Image:      ext.public.ecr.aws/eks-distro/kubernetes-csi/external-provisioner:v5.1.0-eks-1-31-3
  Port:       <none>
  Host Port:  <none>
  Args:
    --timeout=60s
    --csi-address=$(ADDRESS)
    --v=2
    --feature-gates=Topology=true
    --extra-create-metadata
    --leader-election=true
    --default-fstype=ext4
    --kube-api-qps=20
    --kube-api-burst=100
    --worker-threads=100
    --retry-interval-max=30m
  Limits:
    memory:  256Mi
  Requests:
    cpu:     10m
    memory:  40Mi
  Environment:
    ADDRESS:                     /var/lib/csi/sockets/pluginproxy/csi.sock
    AWS_REGION:                  us-gov-west-1
    AWS_STS_REGIONAL_ENDPOINTS:  regional
  Mounts:
    /var/lib/csi/sockets/pluginproxy/ from socket-dir (rw)
 csi-attacher:
  Image:      ext.public.ecr.aws/eks-distro/kubernetes-csi/external-attacher:v4.7.0-eks-1-31-3
  Port:       <none>
  Host Port:  <none>
  Args:
    --timeout=60s
    --csi-address=$(ADDRESS)
    --v=2
    --leader-election=true
    --kube-api-qps=20
    --kube-api-burst=100
    --worker-threads=100
    --retry-interval-max=5m
  Limits:
    memory:  256Mi
  Requests:
    cpu:     10m
    memory:  40Mi
  Environment:
    ADDRESS:                     /var/lib/csi/sockets/pluginproxy/csi.sock
    AWS_REGION:                  us-gov-west-1
    AWS_STS_REGIONAL_ENDPOINTS:  regional
  Mounts:
    /var/lib/csi/sockets/pluginproxy/ from socket-dir (rw)
 csi-snapshotter:
  Image:      ext.public.ecr.aws/eks-distro/kubernetes-csi/external-snapshotter/csi-snapshotter:v8.0.1-eks-1-31-3
  Port:       <none>
  Host Port:  <none>
  Args:
    --csi-address=$(ADDRESS)
    --leader-election=true
    --v=2
    --extra-create-metadata
    --kube-api-qps=20
    --kube-api-burst=100
    --worker-threads=100
    --retry-interval-max=30m
  Limits:
    memory:  256Mi
  Requests:
    cpu:     10m
    memory:  40Mi
  Environment:
    ADDRESS:  /var/lib/csi/sockets/pluginproxy/csi.sock
  Mounts:
    /var/lib/csi/sockets/pluginproxy/ from socket-dir (rw)
 csi-resizer:
  Image:      ext.public.ecr.aws/eks-distro/kubernetes-csi/external-resizer:v1.12.0-eks-1-31-3
  Port:       <none>
  Host Port:  <none>
  Args:
    --timeout=60s
    --csi-address=$(ADDRESS)
    --v=2
    --handle-volume-inuse-error=false
    --leader-election=true
    --kube-api-qps=20
    --kube-api-burst=100
    --workers=100
    --retry-interval-max=30m
  Limits:
    memory:  256Mi
  Requests:
    cpu:     10m
    memory:  40Mi
  Environment:
    ADDRESS:  /var/lib/csi/sockets/pluginproxy/csi.sock
  Mounts:
    /var/lib/csi/sockets/pluginproxy/ from socket-dir (rw)
 liveness-probe:
  Image:      ext.public.ecr.aws/eks-distro/kubernetes-csi/livenessprobe:v2.14.0-eks-1-31-3
  Port:       <none>
  Host Port:  <none>
  Args:
    --csi-address=/csi/csi.sock
  Limits:
    memory:  256Mi
  Requests:
    cpu:        10m
    memory:     40Mi
  Environment:  <none>
  Mounts:
    /csi from socket-dir (rw)
Volumes:
 socket-dir:
  Type:               EmptyDir (a temporary directory that shares a pod's lifetime)
  Medium:             
  SizeLimit:          <unset>
Priority Class Name:  system-cluster-critical
Conditions:
Type           Status  Reason
----           ------  ------
Progressing    True    NewReplicaSetAvailable
Available      True    MinimumReplicasAvailable
OldReplicaSets:  <none>
NewReplicaSet:   ebs-csi-controller-559f5bd4cb (2/2 replicas created)
Events:          <none>

@LMmarsupial
Copy link
Author

LMmarsupial commented Dec 12, 2024

@AndrewSirenko I can follow the dynamic provisioning steps on monday. Unfortunately, I have no time left for the rest of the week. I appreciate your support.

@LMmarsupial
Copy link
Author

@AndrewSirenko I followed your instructions, and I was able to validate that this issue is continuing using the dynamic provisioning steps. Any further debug steps I can take?

> kubectl apply -f manifests/
persistentvolumeclaim/ebs-claim created
pod/app created
storageclass.storage.k8s.io/ebs-sc created

> kubectl get pvc ebs-claim
NAME        STATUS    VOLUME   CAPACITY   ACCESS MODES   STORAGECLASS   AGE
ebs-claim   Pending         

> kubectl describe pvc ebs-claim 
Name:          ebs-claim
Namespace:     default
StorageClass:  ebs-sc
Status:        Pending
Volume:        
Labels:        <none>
Annotations:   volume.beta.kubernetes.io/storage-provisioner: ebs.csi.aws.com
               volume.kubernetes.io/storage-provisioner: ebs.csi.aws.com
Finalizers:    [kubernetes.io/pvc-protection]
Capacity:      
Access Modes:  
VolumeMode:    Filesystem
Used By:       app
Events:
  Type     Reason                Age                From                                                                                      Message
  ----     ------                ----               ----                                                                                      -------
  Warning  ProvisioningFailed    64s                persistentvolume-controller                                                               storageclass.storage.k8s.io "ebs-sc" not found
  Normal   Provisioning          32s (x6 over 63s)  ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-55dfl_f3a11206-15d0-4338-bd5b-d970403408ef  External provisioner is provisioning volume for claim "default/ebs-claim"
  Warning  ProvisioningFailed    32s (x6 over 63s)  ebs.csi.aws.com_ebs-csi-controller-559f5bd4cb-55dfl_f3a11206-15d0-4338-bd5b-d970403408ef  failed to provision volume with StorageClass "ebs-sc": rpc error: code = Internal desc = Could not create volume "pvc-6c3a8077-46cd-46f3-8d30-58e27687c94c": could not create volume in EC2: operation error EC2: CreateVolume, failed to get rate limit token, retry quota exceeded, 0 available, 5 requested
  Normal   ExternalProvisioning  1s (x6 over 63s)   persistentvolume-controller                                                               waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
> 

@AndrewSirenko
Copy link
Contributor

Hi @LMmarsupial, thanks for following the example. I'm sorry that you're running into this.

An error like failed to get rate limit token, retry quota exceeded, 0 available, 5 requested typically signals that the EBS CSI Driver controller pod is not able to make AWS API calls due to some networking issue on the cluster. I would look to see if anything on the cluster is blocking these requests.

Re-deploying the driver via helm with controller.sdkDebugLog=true and controller.logLevel=7, and re-doing the dynamic provisioning example may yield more helpful logs in the ebs-plugin container. Would you be able to attach those logs? (with a command like kubectl get logs ebs-csi-controller-fullname -n <namespace>)

@LMmarsupial
Copy link
Author

@AndrewSirenko No problem, I'll try with a new deploy. My theory is that the rate limit issue is only appearing after several errors when it tries to provision. I think its a symptom of the issue, and not the central issue. Hopefully these debug logs will help. I'll update the thread with the logs for you when I get it deployed.

@LMmarsupial
Copy link
Author

@AndrewSirenko the log is quite long, I can't seem to nail down the behavior. I don't have up to the rate limit token calls, because its so long, but I can paste more if you need. I censored my key value with FYI.

I1216 21:23:41.797918       1 main.go:157] "Initializing metadata"
I1216 21:23:41.798210       1 metrics.go:110] "Metric server listening" address="0.0.0.0:3301" path="/metrics"
I1216 21:23:45.308563       1 ec2.go:92] "Number of attached ENIs" attachedENIs=1
I1216 21:23:45.310521       1 metadata.go:48] "Retrieved metadata from IMDS"
I1216 21:23:45.310791       1 cloud.go:374] "newEC2Cloud: batching enabled"
I1216 21:23:45.310823       1 batcher.go:91] "New: initializing Batcher" maxEntries=500 maxDelay="500ms"
I1216 21:23:45.310862       1 batcher.go:91] "New: initializing Batcher" maxEntries=500 maxDelay="500ms"
I1216 21:23:45.310910       1 batcher.go:91] "New: initializing Batcher" maxEntries=50 maxDelay="500ms"
I1216 21:23:45.310942       1 batcher.go:91] "New: initializing Batcher" maxEntries=1000 maxDelay="500ms"
I1216 21:23:45.311001       1 batcher.go:114] "taskManager: started taskManager"
I1216 21:23:45.311023       1 batcher.go:114] "taskManager: started taskManager"
I1216 21:23:45.311113       1 batcher.go:91] "New: initializing Batcher" maxEntries=1000 maxDelay="500ms"
I1216 21:23:45.311230       1 batcher.go:114] "taskManager: started taskManager"
I1216 21:23:45.311371       1 batcher.go:91] "New: initializing Batcher" maxEntries=500 maxDelay="500ms"
I1216 21:23:45.311413       1 batcher.go:114] "taskManager: started taskManager"
I1216 21:23:45.311260       1 batcher.go:114] "taskManager: started taskManager"
I1216 21:23:45.311642       1 batcher.go:114] "taskManager: started taskManager"
I1216 21:23:45.312090       1 mount_linux.go:326] Cannot create temp dir to detect safe 'not mounted' behavior: mkdir /tmp/kubelet-detect-safe-umount3838873775: read-only file system
I1216 21:23:45.312971       1 driver.go:69] "Driver Information" Driver="ebs.csi.aws.com" Version="v1.35.0"
I1216 21:23:45.313380       1 driver.go:138] "Listening for connections" address="/var/lib/csi/sockets/pluginproxy/csi.sock"
I1216 21:23:45.338201       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.342109       1 identity.go:27] "GetPluginInfo: called" args=""
I1216 21:23:45.345514       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.346075       1 identity.go:37] "GetPluginCapabilities: called" args=""
I1216 21:23:45.346976       1 controller.go:491] "ControllerGetCapabilities: called" args=""
I1216 21:23:45.533191       1 identity.go:27] "GetPluginInfo: called" args=""
I1216 21:23:45.850215       1 identity.go:27] "GetPluginInfo: called" args=""
I1216 21:23:45.852546       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.853138       1 controller.go:491] "ControllerGetCapabilities: called" args=""
I1216 21:23:45.959069       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.961772       1 identity.go:27] "GetPluginInfo: called" args=""
I1216 21:23:45.963951       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.964529       1 identity.go:37] "GetPluginCapabilities: called" args=""
I1216 21:23:45.965219       1 controller.go:491] "ControllerGetCapabilities: called" args=""
I1216 21:23:45.965218       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.967914       1 identity.go:27] "GetPluginInfo: called" args=""
I1216 21:23:45.971169       1 identity.go:61] "Probe: called" args=""
I1216 21:23:45.972099       1 identity.go:37] "GetPluginCapabilities: called" args=""
I1216 21:23:45.972918       1 controller.go:491] "ControllerGetCapabilities: called" args=""
I1216 21:23:45.973788       1 controller.go:491] "ControllerGetCapabilities: called" args=""
I1216 21:23:45.974418       1 controller.go:491] "ControllerGetCapabilities: called" args=""
I1216 21:24:00.578228       1 identity.go:61] "Probe: called" args=""
I1216 21:24:00.578228       1 identity.go:61] "Probe: called" args=""
I1216 21:24:01.298948       1 controller.go:81] "CreateVolume: called" args="name:\"pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e\" capacity_range:{required_bytes:4294967296} volume_capabilities:{mount:{fs_type:\"ext4\"} access_mode:{mode:SINGLE_NODE_WRITER}} parameters:{key:\"csi.storage.k8s.io/pv/name\" value:\"pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e\"} parameters:{key:\"csi.storage.k8s.io/pvc/name\" value:\"ebs-claim\"} parameters:{key:\"csi.storage.k8s.io/pvc/namespace\" value:\"default\"} accessibility_requirements:{requisite:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}} preferred:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}}}"
SDK 2024/12/16 21:24:01 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=1; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=26fe2591fffaf160641e06e210df8484ec867682e6656586f761ffe8c0e3faeb
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212401Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:24:01 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=2; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=e3bea94003b66413a50752c5b3a9fb9a326c53032b8550e923a09ca8c3641585
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212401Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:24:02 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=3; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=a559b929f26d1d3f2c5de2d8f83fbacf9673a1704bfe35a505d89f4f8789932a
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212402Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:24:07 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=4; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=4e229bc0c09d9f62123db08ff326c8611df49707315acfe03411af4a075ac8f2
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212407Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
I1216 21:24:10.577846       1 identity.go:61] "Probe: called" args=""
I1216 21:24:10.577846       1 identity.go:61] "Probe: called" args=""
SDK 2024/12/16 21:24:15 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=5; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=2b767e2eafcac1799fc6476f9ae773852ac2f5adf86bf4355cc80122d3c4f4cd
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212415Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
I1216 21:24:20.577742       1 identity.go:61] "Probe: called" args=""
I1216 21:24:20.577769       1 identity.go:61] "Probe: called" args=""
I1216 21:24:30.578518       1 identity.go:61] "Probe: called" args=""
I1216 21:24:30.578528       1 identity.go:61] "Probe: called" args=""
SDK 2024/12/16 21:24:35 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=6; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=307e9166046afb99b860ebdfdbb2e38a732947af3b658b0dc86fd76e27053b57
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212435Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
I1216 21:24:40.577612       1 identity.go:61] "Probe: called" args=""
I1216 21:24:40.577612       1 identity.go:61] "Probe: called" args=""
I1216 21:24:50.577696       1 identity.go:61] "Probe: called" args=""
I1216 21:24:50.577696       1 identity.go:61] "Probe: called" args=""
I1216 21:24:50.914039       1 controller.go:81] "CreateVolume: called" args="name:\"pvc-b772c970-3489-4639-8604-c92e80c84917\" capacity_range:{required_bytes:107374182400} volume_capabilities:{mount:{fs_type:\"xfs\"} access_mode:{mode:SINGLE_NODE_WRITER}} parameters:{key:\"csi.storage.k8s.io/pv/name\" value:\"pvc-b772c970-3489-4639-8604-c92e80c84917\"} parameters:{key:\"csi.storage.k8s.io/pvc/name\" value:\"data-nova-zookeeper-0\"} parameters:{key:\"csi.storage.k8s.io/pvc/namespace\" value:\"infrastructure\"} parameters:{key:\"encrypted\" value:\"true\"} parameters:{key:\"iops\" value:\"3000\"} parameters:{key:\"throughput\" value:\"125\"} parameters:{key:\"type\" value:\"gp3\"} accessibility_requirements:{requisite:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}} preferred:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}}}"
SDK 2024/12/16 21:24:50 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: de681654-db49-46d0-a372-044b65b8fc54
Amz-Sdk-Request: attempt=1; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=f41165dcd7008a2a1b6c05be606ee554844283a21f1688e57bb234ccb8e82943
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212450Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=a0d13acd01d7723b7a58b944deb297b7aabc4e9756655d71c5f8d5f93c4db8a2&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.1.Value=infrastructure&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.4.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.5.Value=data-nova-zookeeper-0&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:24:51 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: de681654-db49-46d0-a372-044b65b8fc54
Amz-Sdk-Request: attempt=2; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=18f5e92936db81674d3e3f056a7f13461347233859e8b1b0d100d026cff9058f
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212451Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=a0d13acd01d7723b7a58b944deb297b7aabc4e9756655d71c5f8d5f93c4db8a2&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.1.Value=infrastructure&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.4.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.5.Value=data-nova-zookeeper-0&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:24:54 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: de681654-db49-46d0-a372-044b65b8fc54
Amz-Sdk-Request: attempt=3; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=94264179a4bc045119058e4d9ae85173f49f1785c59a7690844bee13ef9628e4
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212454Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=a0d13acd01d7723b7a58b944deb297b7aabc4e9756655d71c5f8d5f93c4db8a2&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.1.Value=infrastructure&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.4.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.5.Value=data-nova-zookeeper-0&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:24:55 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: 50356513-74da-4499-9d4c-90c842e961e3
Amz-Sdk-Request: attempt=7; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=ab86066245ffbb91c6a115a66eaba1f102f7b83e50872930cd11912efe124690
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212455Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.1.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=ebs-claim&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.5.Value=default&Version=2016-11-15&VolumeType=gp3
I1216 21:25:00.582152       1 identity.go:61] "Probe: called" args=""
I1216 21:25:00.582825       1 identity.go:61] "Probe: called" args=""
I1216 21:25:01.297907       1 inflight.go:74] "Node Service: volume operation finished" key="pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e"
E1216 21:25:01.297945       1 driver.go:108] "GRPC error" err="rpc error: code = Internal desc = Could not create volume \"pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e\": could not create volume in EC2: operation error EC2: CreateVolume, request canceled, context canceled"
I1216 21:25:01.860035       1 controller.go:81] "CreateVolume: called" args="name:\"pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b\" capacity_range:{required_bytes:107374182400} volume_capabilities:{mount:{fs_type:\"xfs\"} access_mode:{mode:SINGLE_NODE_WRITER}} parameters:{key:\"csi.storage.k8s.io/pv/name\" value:\"pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b\"} parameters:{key:\"csi.storage.k8s.io/pvc/name\" value:\"data-nova-zookeeper-1\"} parameters:{key:\"csi.storage.k8s.io/pvc/namespace\" value:\"infrastructure\"} parameters:{key:\"encrypted\" value:\"true\"} parameters:{key:\"iops\" value:\"3000\"} parameters:{key:\"throughput\" value:\"125\"} parameters:{key:\"type\" value:\"gp3\"} accessibility_requirements:{requisite:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}} preferred:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}}}"
SDK 2024/12/16 21:25:01 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: d3d8b571-a5cd-4b9a-9e5e-c3b450e9c72f
Amz-Sdk-Request: attempt=1; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=b3b7f4e1d04c91dd88fea1338da6e1f03acc631cf6553deed18925d13b319e5a
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212501Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=183f6e91ecb85464315d2297d3b061c911ca03c2934b73e71d1ae89194813055&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=CSIVolumeName&TagSpecification.1.Tag.1.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.2.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.2.Value=infrastructure&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.3.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=data-nova-zookeeper-1&TagSpecification.1.Tag.5.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.5.Value=true&Throughput=125&Version=2016-11-15&VolumeType=gp3
I1216 21:25:01.860760       1 controller.go:81] "CreateVolume: called" args="name:\"pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8\" capacity_range:{required_bytes:107374182400} volume_capabilities:{mount:{fs_type:\"xfs\"} access_mode:{mode:SINGLE_NODE_WRITER}} parameters:{key:\"csi.storage.k8s.io/pv/name\" value:\"pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8\"} parameters:{key:\"csi.storage.k8s.io/pvc/name\" value:\"data-nova-zookeeper-2\"} parameters:{key:\"csi.storage.k8s.io/pvc/namespace\" value:\"infrastructure\"} parameters:{key:\"encrypted\" value:\"true\"} parameters:{key:\"iops\" value:\"3000\"} parameters:{key:\"throughput\" value:\"125\"} parameters:{key:\"type\" value:\"gp3\"} accessibility_requirements:{requisite:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}} preferred:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}}}"
SDK 2024/12/16 21:25:01 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: 20996c6a-286e-40f1-ab2c-547e24277a7c
Amz-Sdk-Request: attempt=1; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=b17f1a57d97a12f0614d4ef8b4fbe37281c573b0d16ff4b4d9be603d2667a306
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212501Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=bef36f7556cee6b293dcd242234c7476766c9d2e485b610e857474e048451eca&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=data-nova-zookeeper-2&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=infrastructure&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:02 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: de681654-db49-46d0-a372-044b65b8fc54
Amz-Sdk-Request: attempt=4; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=7108ab38557ccb46e4029bf3256e211b4063e12e201ceb0a30378351e8c2e333
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212502Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=a0d13acd01d7723b7a58b944deb297b7aabc4e9756655d71c5f8d5f93c4db8a2&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.1.Value=infrastructure&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.4.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.5.Value=data-nova-zookeeper-0&Throughput=125&Version=2016-11-15&VolumeType=gp3
I1216 21:25:02.299651       1 controller.go:81] "CreateVolume: called" args="name:\"pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e\" capacity_range:{required_bytes:4294967296} volume_capabilities:{mount:{fs_type:\"ext4\"} access_mode:{mode:SINGLE_NODE_WRITER}} parameters:{key:\"csi.storage.k8s.io/pv/name\" value:\"pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e\"} parameters:{key:\"csi.storage.k8s.io/pvc/name\" value:\"ebs-claim\"} parameters:{key:\"csi.storage.k8s.io/pvc/namespace\" value:\"default\"} accessibility_requirements:{requisite:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}} preferred:{segments:{key:\"kubernetes.io/os\" value:\"linux\"} segments:{key:\"topology.ebs.csi.aws.com/zone\" value:\"us-gov-west-1b\"} segments:{key:\"topology.kubernetes.io/zone\" value:\"us-gov-west-1b\"}}}"
SDK 2024/12/16 21:25:02 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: bd1374a3-7928-4b64-903a-04a811abf991
Amz-Sdk-Request: attempt=1; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=6ffd8b9f4e0882a329dbb67df427ed8444e448139b5520e3ccd49443cd759434
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212502Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=ebs-claim&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=default&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:03 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: 20996c6a-286e-40f1-ab2c-547e24277a7c
Amz-Sdk-Request: attempt=2; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=40fd6bc3b97c882d501ba02a09a7be8a79d4a7278de2cd94f8ac0891c5636e77
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212503Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=bef36f7556cee6b293dcd242234c7476766c9d2e485b610e857474e048451eca&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=data-nova-zookeeper-2&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=infrastructure&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:03 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: d3d8b571-a5cd-4b9a-9e5e-c3b450e9c72f
Amz-Sdk-Request: attempt=2; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=313c4f801acc72834fe79c3bbb4bbed4797ec89c6d7da4c4db0866e93fc7c6e9
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212503Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=183f6e91ecb85464315d2297d3b061c911ca03c2934b73e71d1ae89194813055&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=CSIVolumeName&TagSpecification.1.Tag.1.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.2.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.2.Value=infrastructure&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.3.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=data-nova-zookeeper-1&TagSpecification.1.Tag.5.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.5.Value=true&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:04 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: bd1374a3-7928-4b64-903a-04a811abf991
Amz-Sdk-Request: attempt=2; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=a3cc36952c5f0006c6ede8d1c49a3f32bc5c60a8a98eb4d19e5bb2e89f5d1ec0
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212504Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=ebs-claim&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=default&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:04 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: d3d8b571-a5cd-4b9a-9e5e-c3b450e9c72f
Amz-Sdk-Request: attempt=3; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=7014aaf5b30c846f9c971c63691daf2a57560352e78834f2ae65fb1150d0c2a5
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212504Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=183f6e91ecb85464315d2297d3b061c911ca03c2934b73e71d1ae89194813055&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=CSIVolumeName&TagSpecification.1.Tag.1.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.2.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.2.Value=infrastructure&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.3.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=data-nova-zookeeper-1&TagSpecification.1.Tag.5.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.5.Value=true&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:06 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: 20996c6a-286e-40f1-ab2c-547e24277a7c
Amz-Sdk-Request: attempt=3; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=061241d212e3bccdc8a3ddecfb0f717266c9273f8d4a784aaf7fa455ae22687a
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212506Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=bef36f7556cee6b293dcd242234c7476766c9d2e485b610e857474e048451eca&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=data-nova-zookeeper-2&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=infrastructure&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:07 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: bd1374a3-7928-4b64-903a-04a811abf991
Amz-Sdk-Request: attempt=3; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=52266c5b2a01920317cd7f6bd0772b36f7e53b897d134fcc0f88f8c70f300fd5
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212507Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=ebs-claim&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=default&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:09 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: d3d8b571-a5cd-4b9a-9e5e-c3b450e9c72f
Amz-Sdk-Request: attempt=4; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=0ff2d336bf5ac4d72907b860bc550a208681e8eb76f1864f55e3fbf71f687bc9
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212509Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=183f6e91ecb85464315d2297d3b061c911ca03c2934b73e71d1ae89194813055&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=CSIVolumeName&TagSpecification.1.Tag.1.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.2.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.2.Value=infrastructure&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.3.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=data-nova-zookeeper-1&TagSpecification.1.Tag.5.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.5.Value=true&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:09 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: 20996c6a-286e-40f1-ab2c-547e24277a7c
Amz-Sdk-Request: attempt=4; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=33fac99f0774e10fc3b51e740bef56b2c7581ca954937c3552cc65a432b6c20f
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212509Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=bef36f7556cee6b293dcd242234c7476766c9d2e485b610e857474e048451eca&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=data-nova-zookeeper-2&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=infrastructure&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:10 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: 20996c6a-286e-40f1-ab2c-547e24277a7c
Amz-Sdk-Request: attempt=5; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=3364b59370ee1a73e2d96b2ffe16e870bfec38c416cf10c1f087ef791a8b8edf
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212510Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=bef36f7556cee6b293dcd242234c7476766c9d2e485b610e857474e048451eca&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=data-nova-zookeeper-2&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=infrastructure&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-c267c36e-dd4b-45a6-91f2-5714c48bbaf8&Throughput=125&Version=2016-11-15&VolumeType=gp3
I1216 21:25:10.578153       1 identity.go:61] "Probe: called" args=""
I1216 21:25:10.578519       1 identity.go:61] "Probe: called" args=""
SDK 2024/12/16 21:25:12 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 821
Amz-Sdk-Invocation-Id: bd1374a3-7928-4b64-903a-04a811abf991
Amz-Sdk-Request: attempt=4; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=26846f0cb37bc093e221ec134251c8cb1468ffed27ab077b424e56201ae974c6
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212512Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=2e771a3e7b3ec359fd2afbfa0f6794b81f48c856f710ae2a594ad6b1f2b81fd5&Encrypted=false&MultiAttachEnabled=false&Size=4&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.1.Value=true&TagSpecification.1.Tag.2.Key=CSIVolumeName&TagSpecification.1.Tag.2.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.3.Value=ebs-claim&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.4.Value=default&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.5.Value=pvc-0b8f282f-cd3f-460d-99ca-1098870b9c2e&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:18 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: de681654-db49-46d0-a372-044b65b8fc54
Amz-Sdk-Request: attempt=5; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=9787e5a6b22abe279681da8e2639d82b9bd45ce629642bcbe67f4fd70f976a18
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212518Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=a0d13acd01d7723b7a58b944deb297b7aabc4e9756655d71c5f8d5f93c4db8a2&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.1.Value=infrastructure&TagSpecification.1.Tag.2.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.2.Value=true&TagSpecification.1.Tag.3.Key=CSIVolumeName&TagSpecification.1.Tag.3.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.4.Value=pvc-b772c970-3489-4639-8604-c92e80c84917&TagSpecification.1.Tag.5.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.5.Value=data-nova-zookeeper-0&Throughput=125&Version=2016-11-15&VolumeType=gp3
SDK 2024/12/16 21:25:18 DEBUG Request
POST / HTTP/1.1
Host: ec2.us-gov-west-1.amazonaws.com
User-Agent: m/F aws-sdk-go-v2/1.30.5 os/linux lang/go#1.23.1 md/GOOS#linux md/GOARCH#amd64 exec-env/aws-ebs-csi-driver-v1.35.0-helm api/ec2#1.177.3
Content-Length: 866
Amz-Sdk-Invocation-Id: d3d8b571-a5cd-4b9a-9e5e-c3b450e9c72f
Amz-Sdk-Request: attempt=5; max=50
Authorization: AWS4-HMAC-SHA256 Credential=<cred>/20241216/us-gov-west-1/ec2/aws4_request, SignedHeaders=amz-sdk-invocation-id;amz-sdk-request;content-length;content-type;host;x-amz-date, Signature=d1849a9aa73ee22df53108986ad5060a3e75433bf924e063cc005f9786162be8
Content-Type: application/x-www-form-urlencoded
X-Amz-Date: 20241216T212518Z
Accept-Encoding: gzip
Action=CreateVolume&AvailabilityZone=us-gov-west-1b&ClientToken=183f6e91ecb85464315d2297d3b061c911ca03c2934b73e71d1ae89194813055&Encrypted=true&Iops=3000&MultiAttachEnabled=false&Size=100&TagSpecification.1.ResourceType=volume&TagSpecification.1.Tag.1.Key=CSIVolumeName&TagSpecification.1.Tag.1.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.2.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fnamespace&TagSpecification.1.Tag.2.Value=infrastructure&TagSpecification.1.Tag.3.Key=kubernetes.io%2Fcreated-for%2Fpv%2Fname&TagSpecification.1.Tag.3.Value=pvc-3fd50244-2ee4-44f6-b7b0-684e9b9fe32b&TagSpecification.1.Tag.4.Key=kubernetes.io%2Fcreated-for%2Fpvc%2Fname&TagSpecification.1.Tag.4.Value=data-nova-zookeeper-1&TagSpecification.1.Tag.5.Key=ebs.csi.aws.com%2Fcluster&TagSpecification.1.Tag.5.Value=true&Throughput=125&Version=2016-11-15&VolumeType=gp3
I1216 21:25:20.577662       1 identity.go:61] "Probe: called" args=""

@AndrewSirenko
Copy link
Contributor

@LMmarsupial No need for more logs. These logs show that the driver is making AWS API calls that are never getting a response. Thank you.

This means it's even more likely there is some networking/auth issue that is blocking these calls from being made. This issue would be hard to pin down because it could be due to allow/denylists, dns, subnet/VPC configuration, service-account issue, CNI plugin issues, etc.

Are you relying on EKS? If so, can you try creating a fresh EKS cluster in us-west-2, and perhaps try following the EKS add-on installation guide instead? Comparing with a working cluster may help you pin-point the issue.

@LMmarsupial
Copy link
Author

that makes sense. I feel like auth could definitely be causing this. It seems like it is getting unauthorized or whatever issue so many times, then getting locked with the rate limit token.

I am not using EKS, I'm currently using rancher with rke.

Is there any other method I can use to get some better error info? The debug flag you added was very helpful in allowing me to see what's going on.

thanks!

@AndrewSirenko
Copy link
Contributor

AndrewSirenko commented Dec 16, 2024

I am not using EKS, I'm currently using rancher with rke.

Ah, IIRC using IAM roles for ServiceAccounts is EKS only. And I see in your deployment that you give a ServiceAccount name. That might be the issue:

Service Account:  ebs-csi-controller-sa

You may need to setup driver permissions in another way (e.g. ensure/patch nodes where EBS CSI Controller runs to have the IAM role using something IAM instance profile).

Rancher May have their own installation guide for EBS CSI Driver.

Is there any other method I can use to get some better error info?

You can try to manually make an EC2 DescribeVolumes call with the IAM Role rule out the IAM policy being wrong.

You can use something like an ephemeral debug container to try to prove you can connect to the EC2 endpoint from the EBS CSI Controller pod.

@AndrewSirenko
Copy link
Contributor

@LMmarsupial any luck with either using IAM Instance profile for the EBS CSI Controller nodes, or (less recommended) using a secret?

@LMmarsupial
Copy link
Author

Hey @AndrewSirenko, I tried adding a IAM role for the EBS CSI controller, but its already using an admin access key, so I don't think it made a difference.

I was using the ephemeral debug container, and I realized that it may be proxy settings. I now have it properly configured with proxy settings, but i'm still seeing similar log lines.

I also had issues with CA certificates when I was running AWS CLI commands. I am thinking that I may need to mount our CA certificate to the csi controller deploy in order to have it properly configured.

@LMmarsupial
Copy link
Author

I don't think its CA related, but I do believe it is still network related.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
kind/bug Categorizes issue or PR as related to a bug.
Projects
None yet
Development

No branches or pull requests

3 participants