I0716 00:26:32.702073       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0716 00:26:32.702199       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0716 00:26:32.702210       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0716 00:26:32.702213       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0716 00:26:32.702216       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0716 00:26:32.702218       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0716 00:26:32.702221       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0716 00:26:32.702224       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0716 00:26:32.702226       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0716 00:26:32.702229       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0716 00:26:32.702490       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0716 00:26:32.702556       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0716 00:26:34.790421       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:34.792544       1 utils.go:199] ID: 1 GRPC request: {}
I0716 00:26:34.792669       1 utils.go:205] ID: 1 GRPC response: {}
I0716 00:26:34.794858       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0716 00:26:34.795217       1 utils.go:199] ID: 2 GRPC request: {}
I0716 00:26:34.795253       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0716 00:26:34.795491       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0716 00:26:34.798389       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:34.798433       1 utils.go:199] ID: 3 GRPC request: {}
I0716 00:26:34.798508       1 utils.go:205] ID: 3 GRPC response: {}
I0716 00:26:34.799086       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0716 00:26:34.799269       1 utils.go:199] ID: 4 GRPC request: {}
I0716 00:26:34.799932       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0716 00:26:34.801105       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0716 00:26:34.801181       1 utils.go:199] ID: 5 GRPC request: {}
I0716 00:26:34.801195       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0716 00:26:34.801357       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}}}]}
I0716 00:26:36.121221       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:36.121251       1 utils.go:199] ID: 6 GRPC request: {}
I0716 00:26:36.121262       1 utils.go:205] ID: 6 GRPC response: {}
I0716 00:26:36.122814       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0716 00:26:36.122857       1 utils.go:199] ID: 7 GRPC request: {}
I0716 00:26:36.122864       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0716 00:26:36.122888       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0716 00:26:36.126310       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:36.126334       1 utils.go:199] ID: 8 GRPC request: {}
I0716 00:26:36.126393       1 utils.go:205] ID: 8 GRPC response: {}
I0716 00:26:36.126820       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0716 00:26:36.126840       1 utils.go:199] ID: 9 GRPC request: {}
I0716 00:26:36.126974       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0716 00:26:36.127540       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0716 00:26:36.127560       1 utils.go:199] ID: 10 GRPC request: {}
I0716 00:26:36.127568       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0716 00:26:36.127679       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}}}]}
I0716 00:26:36.128195       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0716 00:26:36.128230       1 utils.go:199] ID: 11 GRPC request: {}
I0716 00:26:36.128253       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0716 00:26:36.128358       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}}}]}
I0716 00:26:36.128633       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0716 00:26:36.128651       1 utils.go:199] ID: 12 GRPC request: {}
I0716 00:26:36.128656       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0716 00:26:36.128775       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}}}]}
I0716 00:26:37.331231       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0716 00:26:37.331267       1 utils.go:199] ID: 13 GRPC request: {}
I0716 00:26:37.331276       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0716 00:26:37.331304       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0716 00:26:37.335592       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:37.335692       1 utils.go:199] ID: 14 GRPC request: {}
I0716 00:26:37.335725       1 utils.go:205] ID: 14 GRPC response: {}
I0716 00:26:37.336553       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0716 00:26:37.336599       1 utils.go:199] ID: 15 GRPC request: {}
I0716 00:26:37.336611       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0716 00:26:37.336760       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}}}]}
I0716 00:26:39.578391       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:39.578428       1 utils.go:199] ID: 16 GRPC request: {}
I0716 00:26:39.578511       1 utils.go:205] ID: 16 GRPC response: {}
I0716 00:26:39.582070       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0716 00:26:39.582094       1 utils.go:199] ID: 17 GRPC request: {}
I0716 00:26:39.582126       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0716 00:26:39.582154       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0716 00:26:39.584011       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0716 00:26:39.586536       1 utils.go:199] ID: 18 GRPC request: {}
I0716 00:26:39.586568       1 utils.go:205] ID: 18 GRPC response: {}
I0716 00:26:39.587643       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0716 00:26:39.587747       1 utils.go:199] ID: 19 GRPC request: {}
I0716 00:26:39.587861       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0716 00:26:39.588726       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0716 00:26:39.588754       1 utils.go:199] ID: 20 GRPC request: {}
I0716 00:26:39.588766       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0716 00:26:39.588889       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}}}]}
I0716 00:27:39.789593       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0716 00:27:39.789644       1 utils.go:199] ID: 21 GRPC request: {}
I0716 00:27:39.789673       1 utils.go:205] ID: 21 GRPC response: {}
I0716 00:28:39.751777       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0716 00:28:39.751867       1 utils.go:199] ID: 22 GRPC request: {}
I0716 00:28:39.751915       1 utils.go:205] ID: 22 GRPC response: {}
I0716 00:29:39.806500       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0716 00:29:39.806558       1 utils.go:199] ID: 23 GRPC request: {}
I0716 00:29:39.806583       1 utils.go:205] ID: 23 GRPC response: {}
I0716 00:30:39.761988       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0716 00:30:39.762097       1 utils.go:199] ID: 24 GRPC request: {}
I0716 00:30:39.762147       1 utils.go:205] ID: 24 GRPC response: {}
I0716 00:30:44.725078       1 utils.go:198] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 GRPC call: /csi.v1.Controller/CreateVolume
I0716 00:30:44.725529       1 utils.go:199] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315","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}}]}
I0716 00:30:44.725836       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0716 00:30:44.746191       1 omap.go:80] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0716 00:30:44.760471       1 omap.go:159] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315:63df5ec6-b88a-4203-8db2-01842ab7e24c])
I0716 00:30:44.768110       1 omap.go:159] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 set omap keys (pool="kube", namespace="", name="csi.volume.63df5ec6-b88a-4203-8db2-01842ab7e24c"): map[csi.imagename:csi-vol-63df5ec6-b88a-4203-8db2-01842ab7e24c csi.volname:pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 csi.volume.owner:default])
I0716 00:30:44.768139       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-63df5ec6-b88a-4203-8db2-01842ab7e24c) and image name (csi-vol-63df5ec6-b88a-4203-8db2-01842ab7e24c) for request name (pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315)
I0716 00:30:44.768202       1 rbd_util.go:423] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 rbd: create kube/csi-vol-63df5ec6-b88a-4203-8db2-01842ab7e24c size 5120M (features: [layering]) using mon 199.204.45.144
I0716 00:30:44.768239       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 setting image options on kube/csi-vol-63df5ec6-b88a-4203-8db2-01842ab7e24c
I0716 00:30:44.797464       1 controllerserver.go:763] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 created image kube/csi-vol-63df5ec6-b88a-4203-8db2-01842ab7e24c backed for request name pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315
I0716 00:30:44.835949       1 omap.go:159] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 set omap keys (pool="kube", namespace="", name="csi.volume.63df5ec6-b88a-4203-8db2-01842ab7e24c"): map[csi.imageid:37f4834b7d9d])
I0716 00:30:44.919828       1 utils.go:205] ID: 25 Req-ID: pvc-fe2ac1a7-a6c0-4828-8e90-eabe6884d315 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-63df5ec6-b88a-4203-8db2-01842ab7e24c","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-63df5ec6-b88a-4203-8db2-01842ab7e24c"}}