I0422 16:33:34.650567       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0422 16:33:34.650706       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0422 16:33:34.650716       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0422 16:33:34.650720       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0422 16:33:34.650723       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0422 16:33:34.650725       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0422 16:33:34.650729       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0422 16:33:34.650732       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0422 16:33:34.650735       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0422 16:33:34.650738       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0422 16:33:34.650975       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0422 16:33:34.651053       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0422 16:33:36.207383       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:36.208347       1 utils.go:199] ID: 1 GRPC request: {}
I0422 16:33:36.208397       1 utils.go:205] ID: 1 GRPC response: {}
I0422 16:33:36.212446       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 16:33:36.212574       1 utils.go:199] ID: 2 GRPC request: {}
I0422 16:33:36.212591       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0422 16:33:36.212656       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 16:33:36.214158       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:36.214185       1 utils.go:199] ID: 3 GRPC request: {}
I0422 16:33:36.214197       1 utils.go:205] ID: 3 GRPC response: {}
I0422 16:33:36.214565       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0422 16:33:36.214616       1 utils.go:199] ID: 4 GRPC request: {}
I0422 16:33:36.214780       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0422 16:33:36.215719       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 16:33:36.215760       1 utils.go:199] ID: 5 GRPC request: {}
I0422 16:33:36.215768       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0422 16:33:36.215896       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}}}]}
I0422 16:33:41.230154       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:41.230398       1 utils.go:199] ID: 6 GRPC request: {}
I0422 16:33:41.230533       1 utils.go:205] ID: 6 GRPC response: {}
I0422 16:33:41.232085       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 16:33:41.232218       1 utils.go:199] ID: 7 GRPC request: {}
I0422 16:33:41.232338       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0422 16:33:41.232531       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 16:33:41.233852       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:41.233968       1 utils.go:199] ID: 8 GRPC request: {}
I0422 16:33:41.234058       1 utils.go:205] ID: 8 GRPC response: {}
I0422 16:33:41.234751       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0422 16:33:41.234852       1 utils.go:199] ID: 9 GRPC request: {}
I0422 16:33:41.235124       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0422 16:33:41.235945       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 16:33:41.235981       1 utils.go:199] ID: 10 GRPC request: {}
I0422 16:33:41.235989       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0422 16:33:41.236070       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}}}]}
I0422 16:33:41.236469       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 16:33:41.236486       1 utils.go:199] ID: 11 GRPC request: {}
I0422 16:33:41.236492       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0422 16:33:41.236550       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}}}]}
I0422 16:33:41.237100       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 16:33:41.237148       1 utils.go:199] ID: 12 GRPC request: {}
I0422 16:33:41.237247       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0422 16:33:41.237472       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}}}]}
I0422 16:33:42.420540       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 16:33:42.420613       1 utils.go:199] ID: 13 GRPC request: {}
I0422 16:33:42.420625       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0422 16:33:42.420652       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 16:33:42.422789       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:42.422829       1 utils.go:199] ID: 14 GRPC request: {}
I0422 16:33:42.422870       1 utils.go:205] ID: 14 GRPC response: {}
I0422 16:33:42.423470       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 16:33:42.423499       1 utils.go:199] ID: 15 GRPC request: {}
I0422 16:33:42.423509       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0422 16:33:42.423591       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}}}]}
I0422 16:33:43.710398       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:43.710434       1 utils.go:199] ID: 16 GRPC request: {}
I0422 16:33:43.710450       1 utils.go:205] ID: 16 GRPC response: {}
I0422 16:33:43.713491       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 16:33:43.713578       1 utils.go:199] ID: 17 GRPC request: {}
I0422 16:33:43.713594       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0422 16:33:43.713704       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 16:33:43.718445       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0422 16:33:43.718595       1 utils.go:199] ID: 18 GRPC request: {}
I0422 16:33:43.718670       1 utils.go:205] ID: 18 GRPC response: {}
I0422 16:33:43.719389       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0422 16:33:43.719435       1 utils.go:199] ID: 19 GRPC request: {}
I0422 16:33:43.719574       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0422 16:33:43.720823       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 16:33:43.720847       1 utils.go:199] ID: 20 GRPC request: {}
I0422 16:33:43.720859       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0422 16:33:43.720950       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}}}]}
I0422 16:34:44.181451       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0422 16:34:44.181509       1 utils.go:199] ID: 21 GRPC request: {}
I0422 16:34:44.181565       1 utils.go:205] ID: 21 GRPC response: {}
I0422 16:35:44.133883       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0422 16:35:44.133969       1 utils.go:199] ID: 22 GRPC request: {}
I0422 16:35:44.133993       1 utils.go:205] ID: 22 GRPC response: {}
I0422 16:36:44.132147       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0422 16:36:44.132209       1 utils.go:199] ID: 23 GRPC request: {}
I0422 16:36:44.132288       1 utils.go:205] ID: 23 GRPC response: {}
I0422 16:36:49.536958       1 utils.go:198] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d GRPC call: /csi.v1.Controller/CreateVolume
I0422 16:36:49.537830       1 utils.go:199] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d","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}}]}
I0422 16:36:49.538088       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d setting disableInUseChecks: false image features: [layering] mounter: rbd
E0422 16:36:49.553233       1 omap.go:80] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0422 16:36:49.565773       1 omap.go:159] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d:b66afd4d-9fbe-4f24-954a-065c6c582b1a])
I0422 16:36:49.571481       1 omap.go:159] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d set omap keys (pool="kube", namespace="", name="csi.volume.b66afd4d-9fbe-4f24-954a-065c6c582b1a"): map[csi.imagename:csi-vol-b66afd4d-9fbe-4f24-954a-065c6c582b1a csi.volname:pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d csi.volume.owner:default])
I0422 16:36:49.571512       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b66afd4d-9fbe-4f24-954a-065c6c582b1a) and image name (csi-vol-b66afd4d-9fbe-4f24-954a-065c6c582b1a) for request name (pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d)
I0422 16:36:49.571591       1 rbd_util.go:423] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d rbd: create kube/csi-vol-b66afd4d-9fbe-4f24-954a-065c6c582b1a size 5120M (features: [layering]) using mon 199.19.213.20
I0422 16:36:49.571729       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d setting image options on kube/csi-vol-b66afd4d-9fbe-4f24-954a-065c6c582b1a
I0422 16:36:51.556732       1 controllerserver.go:763] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d created image kube/csi-vol-b66afd4d-9fbe-4f24-954a-065c6c582b1a backed for request name pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d
I0422 16:36:51.593242       1 omap.go:159] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d set omap keys (pool="kube", namespace="", name="csi.volume.b66afd4d-9fbe-4f24-954a-065c6c582b1a"): map[csi.imageid:37d9bc4f0b92])
I0422 16:36:51.689968       1 utils.go:205] ID: 24 Req-ID: pvc-2db3210a-b7d8-41c8-a769-04e2106ba22d GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-b66afd4d-9fbe-4f24-954a-065c6c582b1a","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b66afd4d-9fbe-4f24-954a-065c6c582b1a"}}