I0505 16:58:28.661835       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0505 16:58:28.661953       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 16:58:28.661966       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 16:58:28.661970       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 16:58:28.661973       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0505 16:58:28.661976       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0505 16:58:28.661980       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 16:58:28.661985       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 16:58:28.661988       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 16:58:28.661991       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 16:58:28.662297       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 16:58:28.662345       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 16:58:30.936697       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:30.938778       1 utils.go:199] ID: 1 GRPC request: {}
I0505 16:58:30.938901       1 utils.go:205] ID: 1 GRPC response: {}
I0505 16:58:30.940690       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 16:58:30.940874       1 utils.go:199] ID: 2 GRPC request: {}
I0505 16:58:30.940953       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0505 16:58:30.941068       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 16:58:30.943524       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:30.943540       1 utils.go:199] ID: 3 GRPC request: {}
I0505 16:58:30.943551       1 utils.go:205] ID: 3 GRPC response: {}
I0505 16:58:30.943980       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 16:58:30.944026       1 utils.go:199] ID: 4 GRPC request: {}
I0505 16:58:30.944141       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 16:58:30.944714       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 16:58:30.944978       1 utils.go:199] ID: 5 GRPC request: {}
I0505 16:58:30.944994       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0505 16:58:30.945095       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}}}]}
I0505 16:58:33.070691       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:33.070782       1 utils.go:199] ID: 6 GRPC request: {}
I0505 16:58:33.070869       1 utils.go:205] ID: 6 GRPC response: {}
I0505 16:58:33.073156       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 16:58:33.073194       1 utils.go:199] ID: 7 GRPC request: {}
I0505 16:58:33.073202       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0505 16:58:33.073226       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 16:58:33.074912       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:33.074998       1 utils.go:199] ID: 8 GRPC request: {}
I0505 16:58:33.075038       1 utils.go:205] ID: 8 GRPC response: {}
I0505 16:58:33.075594       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 16:58:33.075673       1 utils.go:199] ID: 9 GRPC request: {}
I0505 16:58:33.075806       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 16:58:33.076774       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 16:58:33.076858       1 utils.go:199] ID: 10 GRPC request: {}
I0505 16:58:33.076929       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0505 16:58:33.077065       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}}}]}
I0505 16:58:33.078653       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 16:58:33.078677       1 utils.go:199] ID: 11 GRPC request: {}
I0505 16:58:33.078685       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0505 16:58:33.078787       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}}}]}
I0505 16:58:33.079166       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 16:58:33.079247       1 utils.go:199] ID: 12 GRPC request: {}
I0505 16:58:33.079277       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0505 16:58:33.079418       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}}}]}
I0505 16:58:34.284838       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 16:58:34.284894       1 utils.go:199] ID: 13 GRPC request: {}
I0505 16:58:34.284908       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0505 16:58:34.284975       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 16:58:34.288041       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:34.288073       1 utils.go:199] ID: 14 GRPC request: {}
I0505 16:58:34.288086       1 utils.go:205] ID: 14 GRPC response: {}
I0505 16:58:34.288740       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 16:58:34.288755       1 utils.go:199] ID: 15 GRPC request: {}
I0505 16:58:34.288762       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0505 16:58:34.288822       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}}}]}
I0505 16:58:39.715627       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:39.715656       1 utils.go:199] ID: 16 GRPC request: {}
I0505 16:58:39.715668       1 utils.go:205] ID: 16 GRPC response: {}
I0505 16:58:39.721076       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 16:58:39.721096       1 utils.go:199] ID: 17 GRPC request: {}
I0505 16:58:39.721102       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0505 16:58:39.721120       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 16:58:39.724443       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0505 16:58:39.724460       1 utils.go:199] ID: 18 GRPC request: {}
I0505 16:58:39.724470       1 utils.go:205] ID: 18 GRPC response: {}
I0505 16:58:39.724967       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 16:58:39.725001       1 utils.go:199] ID: 19 GRPC request: {}
I0505 16:58:39.725120       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 16:58:39.726187       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 16:58:39.726241       1 utils.go:199] ID: 20 GRPC request: {}
I0505 16:58:39.726288       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0505 16:58:39.726384       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}}}]}
I0505 16:59:39.996786       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0505 16:59:39.996899       1 utils.go:199] ID: 21 GRPC request: {}
I0505 16:59:39.996923       1 utils.go:205] ID: 21 GRPC response: {}
I0505 17:00:39.952354       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 17:00:39.952407       1 utils.go:199] ID: 22 GRPC request: {}
I0505 17:00:39.952424       1 utils.go:205] ID: 22 GRPC response: {}
I0505 17:01:39.689318       1 utils.go:198] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 GRPC call: /csi.v1.Controller/CreateVolume
I0505 17:01:39.690100       1 utils.go:199] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670","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}}]}
I0505 17:01:39.690618       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0505 17:01:39.710426       1 omap.go:80] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 17:01:39.726753       1 omap.go:159] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670:31559547-fb3b-45ce-ba4a-cf30176b5618])
I0505 17:01:39.733942       1 omap.go:159] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 set omap keys (pool="kube", namespace="", name="csi.volume.31559547-fb3b-45ce-ba4a-cf30176b5618"): map[csi.imagename:csi-vol-31559547-fb3b-45ce-ba4a-cf30176b5618 csi.volname:pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 csi.volume.owner:default])
I0505 17:01:39.733982       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-31559547-fb3b-45ce-ba4a-cf30176b5618) and image name (csi-vol-31559547-fb3b-45ce-ba4a-cf30176b5618) for request name (pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670)
I0505 17:01:39.734097       1 rbd_util.go:423] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 rbd: create kube/csi-vol-31559547-fb3b-45ce-ba4a-cf30176b5618 size 5120M (features: [layering]) using mon 199.204.45.35
I0505 17:01:39.734327       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 setting image options on kube/csi-vol-31559547-fb3b-45ce-ba4a-cf30176b5618
I0505 17:01:40.006532       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0505 17:01:40.006590       1 utils.go:199] ID: 24 GRPC request: {}
I0505 17:01:40.006613       1 utils.go:205] ID: 24 GRPC response: {}
I0505 17:01:41.249049       1 controllerserver.go:763] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 created image kube/csi-vol-31559547-fb3b-45ce-ba4a-cf30176b5618 backed for request name pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670
I0505 17:01:41.294471       1 omap.go:159] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 set omap keys (pool="kube", namespace="", name="csi.volume.31559547-fb3b-45ce-ba4a-cf30176b5618"): map[csi.imageid:37d91e2fa893])
I0505 17:01:41.395746       1 utils.go:205] ID: 23 Req-ID: pvc-fe2c866e-1470-4135-81a2-7f49e9e6c670 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-31559547-fb3b-45ce-ba4a-cf30176b5618","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-31559547-fb3b-45ce-ba4a-cf30176b5618"}}