I0506 01:17:19.463140       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0506 01:17:19.463288       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0506 01:17:19.463305       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0506 01:17:19.463309       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0506 01:17:19.463313       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0506 01:17:19.463316       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0506 01:17:19.463320       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0506 01:17:19.463322       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0506 01:17:19.463325       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0506 01:17:19.463340       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0506 01:17:19.463904       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0506 01:17:19.463973       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0506 01:17:20.949450       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:20.951933       1 utils.go:199] ID: 1 GRPC request: {}
I0506 01:17:20.952144       1 utils.go:205] ID: 1 GRPC response: {}
I0506 01:17:20.956536       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 01:17:20.956611       1 utils.go:199] ID: 2 GRPC request: {}
I0506 01:17:20.956628       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0506 01:17:20.956700       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 01:17:20.958594       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:20.958619       1 utils.go:199] ID: 3 GRPC request: {}
I0506 01:17:20.958631       1 utils.go:205] ID: 3 GRPC response: {}
I0506 01:17:20.959459       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0506 01:17:20.959530       1 utils.go:199] ID: 4 GRPC request: {}
I0506 01:17:20.959751       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0506 01:17:20.960857       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 01:17:20.960929       1 utils.go:199] ID: 5 GRPC request: {}
I0506 01:17:20.960942       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0506 01:17:20.961102       1 utils.go:205] ID: 5 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0506 01:17:24.943859       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:24.943906       1 utils.go:199] ID: 6 GRPC request: {}
I0506 01:17:24.943926       1 utils.go:205] ID: 6 GRPC response: {}
I0506 01:17:24.946007       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 01:17:24.946039       1 utils.go:199] ID: 7 GRPC request: {}
I0506 01:17:24.946052       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0506 01:17:24.946088       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 01:17:24.949393       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:24.949504       1 utils.go:199] ID: 8 GRPC request: {}
I0506 01:17:24.949532       1 utils.go:205] ID: 8 GRPC response: {}
I0506 01:17:24.949986       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0506 01:17:24.950020       1 utils.go:199] ID: 9 GRPC request: {}
I0506 01:17:24.950157       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0506 01:17:24.950734       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 01:17:24.950757       1 utils.go:199] ID: 10 GRPC request: {}
I0506 01:17:24.950765       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0506 01:17:24.950883       1 utils.go:205] ID: 10 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0506 01:17:24.951451       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 01:17:24.951502       1 utils.go:199] ID: 11 GRPC request: {}
I0506 01:17:24.951515       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0506 01:17:24.951668       1 utils.go:205] ID: 11 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0506 01:17:24.952191       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 01:17:24.952219       1 utils.go:199] ID: 12 GRPC request: {}
I0506 01:17:24.952230       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0506 01:17:24.952320       1 utils.go:205] ID: 12 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0506 01:17:26.194012       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 01:17:26.194056       1 utils.go:199] ID: 13 GRPC request: {}
I0506 01:17:26.194065       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0506 01:17:26.194095       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 01:17:26.198991       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:26.199009       1 utils.go:199] ID: 14 GRPC request: {}
I0506 01:17:26.199022       1 utils.go:205] ID: 14 GRPC response: {}
I0506 01:17:26.199935       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 01:17:26.200011       1 utils.go:199] ID: 15 GRPC request: {}
I0506 01:17:26.200028       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0506 01:17:26.200635       1 utils.go:205] ID: 15 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0506 01:17:27.393147       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:27.393303       1 utils.go:199] ID: 16 GRPC request: {}
I0506 01:17:27.393326       1 utils.go:205] ID: 16 GRPC response: {}
I0506 01:17:27.395896       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 01:17:27.395929       1 utils.go:199] ID: 17 GRPC request: {}
I0506 01:17:27.395938       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0506 01:17:27.395970       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 01:17:27.398725       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0506 01:17:27.398820       1 utils.go:199] ID: 18 GRPC request: {}
I0506 01:17:27.398888       1 utils.go:205] ID: 18 GRPC response: {}
I0506 01:17:27.399533       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0506 01:17:27.399568       1 utils.go:199] ID: 19 GRPC request: {}
I0506 01:17:27.399754       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0506 01:17:27.400937       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 01:17:27.400973       1 utils.go:199] ID: 20 GRPC request: {}
I0506 01:17:27.400989       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0506 01:17:27.401168       1 utils.go:205] ID: 20 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0506 01:18:27.634644       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0506 01:18:27.634706       1 utils.go:199] ID: 21 GRPC request: {}
I0506 01:18:27.634751       1 utils.go:205] ID: 21 GRPC response: {}
I0506 01:19:27.578986       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0506 01:19:27.579077       1 utils.go:199] ID: 22 GRPC request: {}
I0506 01:19:27.579101       1 utils.go:205] ID: 22 GRPC response: {}
I0506 01:20:27.577239       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0506 01:20:27.577298       1 utils.go:199] ID: 23 GRPC request: {}
I0506 01:20:27.577321       1 utils.go:205] ID: 23 GRPC response: {}
I0506 01:20:36.959604       1 utils.go:198] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 GRPC call: /csi.v1.Controller/CreateVolume
I0506 01:20:36.959991       1 utils.go:199] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","pool":"kube"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0506 01:20:36.960521       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0506 01:20:36.978556       1 omap.go:80] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0506 01:20:36.993628       1 omap.go:159] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8:6e87af04-403b-4450-8611-228a1502e8bd])
I0506 01:20:37.002609       1 omap.go:159] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 set omap keys (pool="kube", namespace="", name="csi.volume.6e87af04-403b-4450-8611-228a1502e8bd"): map[csi.imagename:csi-vol-6e87af04-403b-4450-8611-228a1502e8bd csi.volname:pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 csi.volume.owner:default])
I0506 01:20:37.002659       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6e87af04-403b-4450-8611-228a1502e8bd) and image name (csi-vol-6e87af04-403b-4450-8611-228a1502e8bd) for request name (pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8)
I0506 01:20:37.002768       1 rbd_util.go:423] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 rbd: create kube/csi-vol-6e87af04-403b-4450-8611-228a1502e8bd size 5120M (features: [layering]) using mon 199.204.45.44
I0506 01:20:37.003057       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 setting image options on kube/csi-vol-6e87af04-403b-4450-8611-228a1502e8bd
I0506 01:20:38.784210       1 controllerserver.go:763] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 created image kube/csi-vol-6e87af04-403b-4450-8611-228a1502e8bd backed for request name pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8
I0506 01:20:38.819181       1 omap.go:159] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 set omap keys (pool="kube", namespace="", name="csi.volume.6e87af04-403b-4450-8611-228a1502e8bd"): map[csi.imageid:37d91dc17ab3])
I0506 01:20:38.913896       1 utils.go:205] ID: 24 Req-ID: pvc-582c3ad6-1eb7-48cc-8310-2e08276060a8 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-6e87af04-403b-4450-8611-228a1502e8bd","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6e87af04-403b-4450-8611-228a1502e8bd"}}
I0506 01:21:27.590933       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0506 01:21:27.590999       1 utils.go:199] ID: 25 GRPC request: {}
I0506 01:21:27.591041       1 utils.go:205] ID: 25 GRPC response: {}