I0531 14:15:38.549292       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0531 14:15:38.549447       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0531 14:15:38.549467       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0531 14:15:38.549472       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0531 14:15:38.549477       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0531 14:15:38.549483       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0531 14:15:38.549490       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0531 14:15:38.549495       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0531 14:15:38.549499       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0531 14:15:38.549505       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0531 14:15:38.549862       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0531 14:15:38.549862       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0531 14:15:40.693263       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:40.694810       1 utils.go:199] ID: 1 GRPC request: {}
I0531 14:15:40.694990       1 utils.go:205] ID: 1 GRPC response: {}
I0531 14:15:40.697138       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0531 14:15:40.697335       1 utils.go:199] ID: 2 GRPC request: {}
I0531 14:15:40.697354       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0531 14:15:40.697494       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0531 14:15:40.698921       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:40.698942       1 utils.go:199] ID: 3 GRPC request: {}
I0531 14:15:40.698974       1 utils.go:205] ID: 3 GRPC response: {}
I0531 14:15:40.699476       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0531 14:15:40.699573       1 utils.go:199] ID: 4 GRPC request: {}
I0531 14:15:40.699768       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0531 14:15:40.700830       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0531 14:15:40.700918       1 utils.go:199] ID: 5 GRPC request: {}
I0531 14:15:40.700940       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0531 14:15:40.701071       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}}}]}
I0531 14:15:42.003829       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:42.003869       1 utils.go:199] ID: 6 GRPC request: {}
I0531 14:15:42.003884       1 utils.go:205] ID: 6 GRPC response: {}
I0531 14:15:42.005797       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0531 14:15:42.005821       1 utils.go:199] ID: 7 GRPC request: {}
I0531 14:15:42.005828       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0531 14:15:42.005848       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0531 14:15:42.007296       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:42.007318       1 utils.go:199] ID: 8 GRPC request: {}
I0531 14:15:42.007328       1 utils.go:205] ID: 8 GRPC response: {}
I0531 14:15:42.010096       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0531 14:15:42.010152       1 utils.go:199] ID: 9 GRPC request: {}
I0531 14:15:42.010880       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0531 14:15:42.014439       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0531 14:15:42.014467       1 utils.go:199] ID: 10 GRPC request: {}
I0531 14:15:42.014474       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0531 14:15:42.014548       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}}}]}
I0531 14:15:42.015395       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0531 14:15:42.015414       1 utils.go:199] ID: 11 GRPC request: {}
I0531 14:15:42.015420       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0531 14:15:42.015566       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}}}]}
I0531 14:15:42.016066       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0531 14:15:42.016089       1 utils.go:199] ID: 12 GRPC request: {}
I0531 14:15:42.016134       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0531 14:15:42.016347       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}}}]}
I0531 14:15:43.997897       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0531 14:15:43.997934       1 utils.go:199] ID: 13 GRPC request: {}
I0531 14:15:43.997942       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0531 14:15:43.997964       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0531 14:15:44.001253       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:44.001299       1 utils.go:199] ID: 14 GRPC request: {}
I0531 14:15:44.001327       1 utils.go:205] ID: 14 GRPC response: {}
I0531 14:15:44.002094       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0531 14:15:44.002130       1 utils.go:199] ID: 15 GRPC request: {}
I0531 14:15:44.002143       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0531 14:15:44.002341       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}}}]}
I0531 14:15:45.395134       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:45.395164       1 utils.go:199] ID: 16 GRPC request: {}
I0531 14:15:45.395194       1 utils.go:205] ID: 16 GRPC response: {}
I0531 14:15:45.397693       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0531 14:15:45.397709       1 utils.go:199] ID: 17 GRPC request: {}
I0531 14:15:45.397733       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0531 14:15:45.397772       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0531 14:15:45.401936       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0531 14:15:45.401968       1 utils.go:199] ID: 18 GRPC request: {}
I0531 14:15:45.401983       1 utils.go:205] ID: 18 GRPC response: {}
I0531 14:15:45.402461       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0531 14:15:45.402508       1 utils.go:199] ID: 19 GRPC request: {}
I0531 14:15:45.402610       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0531 14:15:45.403203       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0531 14:15:45.403230       1 utils.go:199] ID: 20 GRPC request: {}
I0531 14:15:45.403238       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0531 14:15:45.403320       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}}}]}
I0531 14:16:45.607210       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0531 14:16:45.607241       1 utils.go:199] ID: 21 GRPC request: {}
I0531 14:16:45.607253       1 utils.go:205] ID: 21 GRPC response: {}
I0531 14:17:45.560281       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0531 14:17:45.560350       1 utils.go:199] ID: 22 GRPC request: {}
I0531 14:17:45.560406       1 utils.go:205] ID: 22 GRPC response: {}
I0531 14:18:45.558931       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0531 14:18:45.558977       1 utils.go:199] ID: 23 GRPC request: {}
I0531 14:18:45.559000       1 utils.go:205] ID: 23 GRPC response: {}
I0531 14:19:19.406547       1 utils.go:198] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 GRPC call: /csi.v1.Controller/CreateVolume
I0531 14:19:19.407041       1 utils.go:199] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82","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}}]}
I0531 14:19:19.407451       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0531 14:19:19.433567       1 omap.go:80] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0531 14:19:19.453004       1 omap.go:159] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82:7f16bbb4-d34c-45e9-aff6-2e2b13effa01])
I0531 14:19:19.462438       1 omap.go:159] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 set omap keys (pool="kube", namespace="", name="csi.volume.7f16bbb4-d34c-45e9-aff6-2e2b13effa01"): map[csi.imagename:csi-vol-7f16bbb4-d34c-45e9-aff6-2e2b13effa01 csi.volname:pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 csi.volume.owner:default])
I0531 14:19:19.462465       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-7f16bbb4-d34c-45e9-aff6-2e2b13effa01) and image name (csi-vol-7f16bbb4-d34c-45e9-aff6-2e2b13effa01) for request name (pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82)
I0531 14:19:19.462657       1 rbd_util.go:423] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 rbd: create kube/csi-vol-7f16bbb4-d34c-45e9-aff6-2e2b13effa01 size 5120M (features: [layering]) using mon 199.204.45.169
I0531 14:19:19.462706       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 setting image options on kube/csi-vol-7f16bbb4-d34c-45e9-aff6-2e2b13effa01
I0531 14:19:19.496604       1 controllerserver.go:763] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 created image kube/csi-vol-7f16bbb4-d34c-45e9-aff6-2e2b13effa01 backed for request name pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82
I0531 14:19:19.542458       1 omap.go:159] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 set omap keys (pool="kube", namespace="", name="csi.volume.7f16bbb4-d34c-45e9-aff6-2e2b13effa01"): map[csi.imageid:37edaf0c1b40])
I0531 14:19:19.640307       1 utils.go:205] ID: 24 Req-ID: pvc-b29ffd03-cab2-42c6-ae9f-888ea3185a82 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-7f16bbb4-d34c-45e9-aff6-2e2b13effa01","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-7f16bbb4-d34c-45e9-aff6-2e2b13effa01"}}
I0531 14:19:45.570722       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0531 14:19:45.570765       1 utils.go:199] ID: 25 GRPC request: {}
I0531 14:19:45.570786       1 utils.go:205] ID: 25 GRPC response: {}