I0513 00:55:17.657128       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0513 00:55:17.657262       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0513 00:55:17.657275       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0513 00:55:17.657279       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0513 00:55:17.657282       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0513 00:55:17.657285       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0513 00:55:17.657290       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0513 00:55:17.657293       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0513 00:55:17.657296       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0513 00:55:17.657299       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0513 00:55:17.657612       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0513 00:55:17.657732       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0513 00:55:19.509269       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:19.510538       1 utils.go:199] ID: 1 GRPC request: {}
I0513 00:55:19.510580       1 utils.go:205] ID: 1 GRPC response: {}
I0513 00:55:19.514731       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0513 00:55:19.514829       1 utils.go:199] ID: 2 GRPC request: {}
I0513 00:55:19.514847       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0513 00:55:19.514906       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0513 00:55:19.516379       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:19.516525       1 utils.go:199] ID: 3 GRPC request: {}
I0513 00:55:19.516646       1 utils.go:205] ID: 3 GRPC response: {}
I0513 00:55:19.517625       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0513 00:55:19.517681       1 utils.go:199] ID: 4 GRPC request: {}
I0513 00:55:19.517826       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0513 00:55:19.518667       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0513 00:55:19.518758       1 utils.go:199] ID: 5 GRPC request: {}
I0513 00:55:19.518816       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0513 00:55:19.518984       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}}}]}
I0513 00:55:20.712275       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:20.712329       1 utils.go:199] ID: 6 GRPC request: {}
I0513 00:55:20.712352       1 utils.go:205] ID: 6 GRPC response: {}
I0513 00:55:20.715642       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0513 00:55:20.715692       1 utils.go:199] ID: 7 GRPC request: {}
I0513 00:55:20.715705       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0513 00:55:20.715741       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0513 00:55:20.718118       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:20.718152       1 utils.go:199] ID: 8 GRPC request: {}
I0513 00:55:20.718169       1 utils.go:205] ID: 8 GRPC response: {}
I0513 00:55:20.718806       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0513 00:55:20.718836       1 utils.go:199] ID: 9 GRPC request: {}
I0513 00:55:20.718994       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0513 00:55:20.719672       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0513 00:55:20.719704       1 utils.go:199] ID: 10 GRPC request: {}
I0513 00:55:20.719716       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0513 00:55:20.719811       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}}}]}
I0513 00:55:20.726644       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0513 00:55:20.726767       1 utils.go:199] ID: 11 GRPC request: {}
I0513 00:55:20.726793       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0513 00:55:20.726935       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}}}]}
I0513 00:55:20.727485       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0513 00:55:20.727513       1 utils.go:199] ID: 12 GRPC request: {}
I0513 00:55:20.727519       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0513 00:55:20.727580       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}}}]}
I0513 00:55:20.817880       1 utils.go:198] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf GRPC call: /csi.v1.Controller/CreateVolume
I0513 00:55:20.818746       1 utils.go:199] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf","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}}]}
I0513 00:55:20.819208       1 rbd_util.go:1315] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf setting disableInUseChecks: false image features: [layering] mounter: rbd
E0513 00:55:20.841454       1 omap.go:80] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0513 00:55:20.857281       1 omap.go:159] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf:5ea2023d-20ab-4e90-95ab-2547379ead05])
I0513 00:55:20.863735       1 omap.go:159] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf set omap keys (pool="kube", namespace="", name="csi.volume.5ea2023d-20ab-4e90-95ab-2547379ead05"): map[csi.imagename:csi-vol-5ea2023d-20ab-4e90-95ab-2547379ead05 csi.volname:pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf csi.volume.owner:default])
I0513 00:55:20.863773       1 rbd_journal.go:491] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5ea2023d-20ab-4e90-95ab-2547379ead05) and image name (csi-vol-5ea2023d-20ab-4e90-95ab-2547379ead05) for request name (pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf)
I0513 00:55:20.863961       1 rbd_util.go:423] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf rbd: create kube/csi-vol-5ea2023d-20ab-4e90-95ab-2547379ead05 size 5120M (features: [layering]) using mon 199.204.45.115
I0513 00:55:20.864070       1 rbd_util.go:1563] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf setting image options on kube/csi-vol-5ea2023d-20ab-4e90-95ab-2547379ead05
I0513 00:55:22.602992       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/GetPluginInfo
I0513 00:55:22.603041       1 utils.go:199] ID: 14 GRPC request: {}
I0513 00:55:22.603052       1 identityserver-default.go:40] ID: 14 Using default GetPluginInfo
I0513 00:55:22.603091       1 utils.go:205] ID: 14 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0513 00:55:22.605910       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:22.605945       1 utils.go:199] ID: 15 GRPC request: {}
I0513 00:55:22.605958       1 utils.go:205] ID: 15 GRPC response: {}
I0513 00:55:22.606579       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0513 00:55:22.606612       1 utils.go:199] ID: 16 GRPC request: {}
I0513 00:55:22.606624       1 controllerserver-default.go:42] ID: 16 Using default ControllerGetCapabilities
I0513 00:55:22.606913       1 utils.go:205] ID: 16 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0513 00:55:23.307897       1 controllerserver.go:763] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf created image kube/csi-vol-5ea2023d-20ab-4e90-95ab-2547379ead05 backed for request name pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf
I0513 00:55:23.332473       1 omap.go:159] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf set omap keys (pool="kube", namespace="", name="csi.volume.5ea2023d-20ab-4e90-95ab-2547379ead05"): map[csi.imageid:37b4b6e3fa86])
I0513 00:55:23.426532       1 utils.go:205] ID: 13 Req-ID: pvc-c860a07e-b3ef-4a72-bbb1-8cd21f82f8bf GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-5ea2023d-20ab-4e90-95ab-2547379ead05","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5ea2023d-20ab-4e90-95ab-2547379ead05"}}
I0513 00:55:24.281814       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:24.281855       1 utils.go:199] ID: 17 GRPC request: {}
I0513 00:55:24.281882       1 utils.go:205] ID: 17 GRPC response: {}
I0513 00:55:24.284084       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/GetPluginInfo
I0513 00:55:24.284144       1 utils.go:199] ID: 18 GRPC request: {}
I0513 00:55:24.284152       1 identityserver-default.go:40] ID: 18 Using default GetPluginInfo
I0513 00:55:24.284183       1 utils.go:205] ID: 18 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0513 00:55:24.287086       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/Probe
I0513 00:55:24.287137       1 utils.go:199] ID: 19 GRPC request: {}
I0513 00:55:24.287162       1 utils.go:205] ID: 19 GRPC response: {}
I0513 00:55:24.288500       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0513 00:55:24.288537       1 utils.go:199] ID: 20 GRPC request: {}
I0513 00:55:24.288666       1 utils.go:205] ID: 20 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0513 00:55:24.289633       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0513 00:55:24.289676       1 utils.go:199] ID: 21 GRPC request: {}
I0513 00:55:24.289686       1 controllerserver-default.go:42] ID: 21 Using default ControllerGetCapabilities
I0513 00:55:24.289769       1 utils.go:205] ID: 21 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}