I0507 00:48:51.407113       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0507 00:48:51.407249       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0507 00:48:51.407264       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0507 00:48:51.407268       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0507 00:48:51.407271       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0507 00:48:51.407274       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0507 00:48:51.407278       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0507 00:48:51.407281       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0507 00:48:51.407284       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0507 00:48:51.407287       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0507 00:48:51.407554       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0507 00:48:51.407610       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0507 00:48:52.917173       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:52.918655       1 utils.go:199] ID: 1 GRPC request: {}
I0507 00:48:52.918717       1 utils.go:205] ID: 1 GRPC response: {}
I0507 00:48:52.921781       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 00:48:52.921840       1 utils.go:199] ID: 2 GRPC request: {}
I0507 00:48:52.921857       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0507 00:48:52.921899       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 00:48:52.922795       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:52.922816       1 utils.go:199] ID: 3 GRPC request: {}
I0507 00:48:52.922827       1 utils.go:205] ID: 3 GRPC response: {}
I0507 00:48:52.923205       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0507 00:48:52.923291       1 utils.go:199] ID: 4 GRPC request: {}
I0507 00:48:52.923452       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0507 00:48:52.924470       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 00:48:52.924601       1 utils.go:199] ID: 5 GRPC request: {}
I0507 00:48:52.924634       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0507 00:48:52.924907       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}}}]}
I0507 00:48:54.942959       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:54.943015       1 utils.go:199] ID: 6 GRPC request: {}
I0507 00:48:54.943045       1 utils.go:205] ID: 6 GRPC response: {}
I0507 00:48:54.944598       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 00:48:54.944649       1 utils.go:199] ID: 7 GRPC request: {}
I0507 00:48:54.944661       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0507 00:48:54.944722       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 00:48:54.947205       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:54.947226       1 utils.go:199] ID: 8 GRPC request: {}
I0507 00:48:54.947236       1 utils.go:205] ID: 8 GRPC response: {}
I0507 00:48:54.947774       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0507 00:48:54.947792       1 utils.go:199] ID: 9 GRPC request: {}
I0507 00:48:54.947912       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0507 00:48:54.948479       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 00:48:54.948497       1 utils.go:199] ID: 10 GRPC request: {}
I0507 00:48:54.948504       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0507 00:48:54.948608       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}}}]}
I0507 00:48:54.949228       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 00:48:54.949250       1 utils.go:199] ID: 11 GRPC request: {}
I0507 00:48:54.949258       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0507 00:48:54.949385       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}}}]}
I0507 00:48:54.949756       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 00:48:54.949779       1 utils.go:199] ID: 12 GRPC request: {}
I0507 00:48:54.949797       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0507 00:48:54.949872       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}}}]}
I0507 00:48:56.079006       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 00:48:56.079081       1 utils.go:199] ID: 13 GRPC request: {}
I0507 00:48:56.079098       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0507 00:48:56.079152       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 00:48:56.083258       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:56.083311       1 utils.go:199] ID: 14 GRPC request: {}
I0507 00:48:56.083336       1 utils.go:205] ID: 14 GRPC response: {}
I0507 00:48:56.084051       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 00:48:56.084085       1 utils.go:199] ID: 15 GRPC request: {}
I0507 00:48:56.084093       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0507 00:48:56.084238       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}}}]}
I0507 00:48:58.271553       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:58.271583       1 utils.go:199] ID: 16 GRPC request: {}
I0507 00:48:58.271607       1 utils.go:205] ID: 16 GRPC response: {}
I0507 00:48:58.273331       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 00:48:58.273376       1 utils.go:199] ID: 17 GRPC request: {}
I0507 00:48:58.273434       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0507 00:48:58.273464       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 00:48:58.274868       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0507 00:48:58.274916       1 utils.go:199] ID: 18 GRPC request: {}
I0507 00:48:58.274933       1 utils.go:205] ID: 18 GRPC response: {}
I0507 00:48:58.275467       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0507 00:48:58.275487       1 utils.go:199] ID: 19 GRPC request: {}
I0507 00:48:58.275600       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0507 00:48:58.276155       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 00:48:58.276174       1 utils.go:199] ID: 20 GRPC request: {}
I0507 00:48:58.276197       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0507 00:48:58.276287       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}}}]}
I0507 00:49:58.818118       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0507 00:49:58.818364       1 utils.go:199] ID: 21 GRPC request: {}
I0507 00:49:58.818470       1 utils.go:205] ID: 21 GRPC response: {}
I0507 00:50:58.776677       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0507 00:50:58.776757       1 utils.go:199] ID: 22 GRPC request: {}
I0507 00:50:58.776798       1 utils.go:205] ID: 22 GRPC response: {}
I0507 00:51:58.426771       1 utils.go:198] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba GRPC call: /csi.v1.Controller/CreateVolume
I0507 00:51:58.427253       1 utils.go:199] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba","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}}]}
I0507 00:51:58.427532       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba setting disableInUseChecks: false image features: [layering] mounter: rbd
E0507 00:51:58.444976       1 omap.go:80] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0507 00:51:58.459264       1 omap.go:159] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba:5cc28048-6d57-4be9-8184-c732ae934b28])
I0507 00:51:58.465771       1 omap.go:159] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba set omap keys (pool="kube", namespace="", name="csi.volume.5cc28048-6d57-4be9-8184-c732ae934b28"): map[csi.imagename:csi-vol-5cc28048-6d57-4be9-8184-c732ae934b28 csi.volname:pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba csi.volume.owner:default])
I0507 00:51:58.465896       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5cc28048-6d57-4be9-8184-c732ae934b28) and image name (csi-vol-5cc28048-6d57-4be9-8184-c732ae934b28) for request name (pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba)
I0507 00:51:58.466039       1 rbd_util.go:423] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba rbd: create kube/csi-vol-5cc28048-6d57-4be9-8184-c732ae934b28 size 5120M (features: [layering]) using mon 199.204.45.248
I0507 00:51:58.466160       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba setting image options on kube/csi-vol-5cc28048-6d57-4be9-8184-c732ae934b28
I0507 00:51:58.769417       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0507 00:51:58.769486       1 utils.go:199] ID: 24 GRPC request: {}
I0507 00:51:58.769547       1 utils.go:205] ID: 24 GRPC response: {}
I0507 00:51:59.868223       1 controllerserver.go:763] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba created image kube/csi-vol-5cc28048-6d57-4be9-8184-c732ae934b28 backed for request name pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba
I0507 00:51:59.898140       1 omap.go:159] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba set omap keys (pool="kube", namespace="", name="csi.volume.5cc28048-6d57-4be9-8184-c732ae934b28"): map[csi.imageid:37d9c82ad522])
I0507 00:51:59.996960       1 utils.go:205] ID: 23 Req-ID: pvc-3b50138c-e04e-44fa-bc36-668f4a95e8ba GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-5cc28048-6d57-4be9-8184-c732ae934b28","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5cc28048-6d57-4be9-8184-c732ae934b28"}}