I0415 15:14:02.386844       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0415 15:14:02.386948       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0415 15:14:02.386958       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0415 15:14:02.386961       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0415 15:14:02.386985       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0415 15:14:02.386989       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0415 15:14:02.386993       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0415 15:14:02.386996       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0415 15:14:02.386999       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0415 15:14:02.387002       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0415 15:14:02.388155       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0415 15:14:02.388213       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0415 15:14:04.401634       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:04.403704       1 utils.go:199] ID: 1 GRPC request: {}
I0415 15:14:04.403890       1 utils.go:205] ID: 1 GRPC response: {}
I0415 15:14:04.406406       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0415 15:14:04.406530       1 utils.go:199] ID: 2 GRPC request: {}
I0415 15:14:04.406544       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0415 15:14:04.406647       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0415 15:14:04.408227       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:04.408289       1 utils.go:199] ID: 3 GRPC request: {}
I0415 15:14:04.408313       1 utils.go:205] ID: 3 GRPC response: {}
I0415 15:14:04.408754       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0415 15:14:04.408862       1 utils.go:199] ID: 4 GRPC request: {}
I0415 15:14:04.409071       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0415 15:14:04.410452       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0415 15:14:04.410527       1 utils.go:199] ID: 5 GRPC request: {}
I0415 15:14:04.410541       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0415 15:14:04.410692       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}}}]}
I0415 15:14:06.544106       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:06.544136       1 utils.go:199] ID: 6 GRPC request: {}
I0415 15:14:06.544147       1 utils.go:205] ID: 6 GRPC response: {}
I0415 15:14:06.547007       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0415 15:14:06.547067       1 utils.go:199] ID: 7 GRPC request: {}
I0415 15:14:06.547083       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0415 15:14:06.547223       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0415 15:14:06.551189       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:06.551215       1 utils.go:199] ID: 8 GRPC request: {}
I0415 15:14:06.551323       1 utils.go:205] ID: 8 GRPC response: {}
I0415 15:14:06.551870       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0415 15:14:06.551893       1 utils.go:199] ID: 9 GRPC request: {}
I0415 15:14:06.552021       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0415 15:14:06.552860       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0415 15:14:06.552903       1 utils.go:199] ID: 10 GRPC request: {}
I0415 15:14:06.552921       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0415 15:14:06.553166       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}}}]}
I0415 15:14:06.554057       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0415 15:14:06.554085       1 utils.go:199] ID: 11 GRPC request: {}
I0415 15:14:06.554093       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0415 15:14:06.554265       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}}}]}
I0415 15:14:06.554831       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0415 15:14:06.554853       1 utils.go:199] ID: 12 GRPC request: {}
I0415 15:14:06.554859       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0415 15:14:06.555020       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}}}]}
I0415 15:14:08.126945       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0415 15:14:08.127002       1 utils.go:199] ID: 13 GRPC request: {}
I0415 15:14:08.127015       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0415 15:14:08.127039       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0415 15:14:08.129693       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:08.129714       1 utils.go:199] ID: 14 GRPC request: {}
I0415 15:14:08.129794       1 utils.go:205] ID: 14 GRPC response: {}
I0415 15:14:08.130069       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0415 15:14:08.130121       1 utils.go:199] ID: 15 GRPC request: {}
I0415 15:14:08.130135       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0415 15:14:08.130253       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}}}]}
I0415 15:14:09.352779       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:09.352815       1 utils.go:199] ID: 16 GRPC request: {}
I0415 15:14:09.352828       1 utils.go:205] ID: 16 GRPC response: {}
I0415 15:14:09.361385       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0415 15:14:09.361408       1 utils.go:199] ID: 17 GRPC request: {}
I0415 15:14:09.361424       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0415 15:14:09.361455       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0415 15:14:09.363388       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0415 15:14:09.363409       1 utils.go:199] ID: 18 GRPC request: {}
I0415 15:14:09.363423       1 utils.go:205] ID: 18 GRPC response: {}
I0415 15:14:09.363774       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0415 15:14:09.363791       1 utils.go:199] ID: 19 GRPC request: {}
I0415 15:14:09.363926       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0415 15:14:09.364721       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0415 15:14:09.364742       1 utils.go:199] ID: 20 GRPC request: {}
I0415 15:14:09.364850       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0415 15:14:09.364978       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}}}]}
I0415 15:15:09.554301       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0415 15:15:09.554444       1 utils.go:199] ID: 21 GRPC request: {}
I0415 15:15:09.554491       1 utils.go:205] ID: 21 GRPC response: {}
I0415 15:16:09.554769       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0415 15:16:09.554804       1 utils.go:199] ID: 22 GRPC request: {}
I0415 15:16:09.554835       1 utils.go:205] ID: 22 GRPC response: {}
I0415 15:17:09.610269       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0415 15:17:09.610320       1 utils.go:199] ID: 23 GRPC request: {}
I0415 15:17:09.610336       1 utils.go:205] ID: 23 GRPC response: {}
I0415 15:17:10.405922       1 utils.go:198] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 GRPC call: /csi.v1.Controller/CreateVolume
I0415 15:17:10.406225       1 utils.go:199] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-bede0d5c-7317-46da-adee-12b45b575f45","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-bede0d5c-7317-46da-adee-12b45b575f45","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}}]}
I0415 15:17:10.406455       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0415 15:17:10.423511       1 omap.go:80] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0415 15:17:10.435324       1 omap.go:159] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-bede0d5c-7317-46da-adee-12b45b575f45:ddddaa79-8334-4a90-ad2a-27f0cf1c1242])
I0415 15:17:10.439798       1 omap.go:159] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 set omap keys (pool="kube", namespace="", name="csi.volume.ddddaa79-8334-4a90-ad2a-27f0cf1c1242"): map[csi.imagename:csi-vol-ddddaa79-8334-4a90-ad2a-27f0cf1c1242 csi.volname:pvc-bede0d5c-7317-46da-adee-12b45b575f45 csi.volume.owner:default])
I0415 15:17:10.439838       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ddddaa79-8334-4a90-ad2a-27f0cf1c1242) and image name (csi-vol-ddddaa79-8334-4a90-ad2a-27f0cf1c1242) for request name (pvc-bede0d5c-7317-46da-adee-12b45b575f45)
I0415 15:17:10.439924       1 rbd_util.go:423] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 rbd: create kube/csi-vol-ddddaa79-8334-4a90-ad2a-27f0cf1c1242 size 5120M (features: [layering]) using mon 199.19.213.224
I0415 15:17:10.439975       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 setting image options on kube/csi-vol-ddddaa79-8334-4a90-ad2a-27f0cf1c1242
I0415 15:17:12.195186       1 controllerserver.go:763] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 created image kube/csi-vol-ddddaa79-8334-4a90-ad2a-27f0cf1c1242 backed for request name pvc-bede0d5c-7317-46da-adee-12b45b575f45
I0415 15:17:12.226944       1 omap.go:159] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 set omap keys (pool="kube", namespace="", name="csi.volume.ddddaa79-8334-4a90-ad2a-27f0cf1c1242"): map[csi.imageid:37d9b48c96c8])
I0415 15:17:12.316475       1 utils.go:205] ID: 24 Req-ID: pvc-bede0d5c-7317-46da-adee-12b45b575f45 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-ddddaa79-8334-4a90-ad2a-27f0cf1c1242","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ddddaa79-8334-4a90-ad2a-27f0cf1c1242"}}