I0501 00:58:36.259148       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0501 00:58:36.259312       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0501 00:58:36.259336       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0501 00:58:36.259348       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0501 00:58:36.259378       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0501 00:58:36.259390       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0501 00:58:36.259403       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0501 00:58:36.259415       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0501 00:58:36.259426       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0501 00:58:36.259438       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0501 00:58:36.259717       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0501 00:58:36.259728       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0501 00:58:38.891803       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:38.893251       1 utils.go:199] ID: 1 GRPC request: {}
I0501 00:58:38.893310       1 utils.go:205] ID: 1 GRPC response: {}
I0501 00:58:38.897694       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0501 00:58:38.897739       1 utils.go:199] ID: 2 GRPC request: {}
I0501 00:58:38.897748       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0501 00:58:38.897783       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0501 00:58:38.899358       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:38.901806       1 utils.go:199] ID: 3 GRPC request: {}
I0501 00:58:38.901830       1 utils.go:205] ID: 3 GRPC response: {}
I0501 00:58:38.902304       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0501 00:58:38.902389       1 utils.go:199] ID: 4 GRPC request: {}
I0501 00:58:38.902531       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0501 00:58:38.903654       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0501 00:58:38.903895       1 utils.go:199] ID: 5 GRPC request: {}
I0501 00:58:38.903981       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0501 00:58:38.904257       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}}}]}
I0501 00:58:41.130921       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:41.130979       1 utils.go:199] ID: 6 GRPC request: {}
I0501 00:58:41.131008       1 utils.go:205] ID: 6 GRPC response: {}
I0501 00:58:41.133147       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0501 00:58:41.133184       1 utils.go:199] ID: 7 GRPC request: {}
I0501 00:58:41.133208       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0501 00:58:41.133240       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0501 00:58:41.134800       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:41.134851       1 utils.go:199] ID: 8 GRPC request: {}
I0501 00:58:41.134871       1 utils.go:205] ID: 8 GRPC response: {}
I0501 00:58:41.136459       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0501 00:58:41.136480       1 utils.go:199] ID: 9 GRPC request: {}
I0501 00:58:41.136561       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0501 00:58:41.137418       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0501 00:58:41.137457       1 utils.go:199] ID: 10 GRPC request: {}
I0501 00:58:41.137473       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0501 00:58:41.137565       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}}}]}
I0501 00:58:41.138041       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0501 00:58:41.138059       1 utils.go:199] ID: 11 GRPC request: {}
I0501 00:58:41.138067       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0501 00:58:41.138158       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}}}]}
I0501 00:58:41.138508       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0501 00:58:41.138534       1 utils.go:199] ID: 12 GRPC request: {}
I0501 00:58:41.138543       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0501 00:58:41.138720       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}}}]}
I0501 00:58:42.362562       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0501 00:58:42.362631       1 utils.go:199] ID: 13 GRPC request: {}
I0501 00:58:42.362642       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0501 00:58:42.362666       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0501 00:58:42.366097       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:42.366136       1 utils.go:199] ID: 14 GRPC request: {}
I0501 00:58:42.366150       1 utils.go:205] ID: 14 GRPC response: {}
I0501 00:58:42.366773       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0501 00:58:42.366816       1 utils.go:199] ID: 15 GRPC request: {}
I0501 00:58:42.366827       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0501 00:58:42.366914       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}}}]}
I0501 00:58:43.726179       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:43.726313       1 utils.go:199] ID: 16 GRPC request: {}
I0501 00:58:43.726351       1 utils.go:205] ID: 16 GRPC response: {}
I0501 00:58:43.730059       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0501 00:58:43.730123       1 utils.go:199] ID: 17 GRPC request: {}
I0501 00:58:43.730141       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0501 00:58:43.730175       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0501 00:58:43.731576       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0501 00:58:43.731621       1 utils.go:199] ID: 18 GRPC request: {}
I0501 00:58:43.731652       1 utils.go:205] ID: 18 GRPC response: {}
I0501 00:58:43.732066       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0501 00:58:43.732154       1 utils.go:199] ID: 19 GRPC request: {}
I0501 00:58:43.732282       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0501 00:58:43.732915       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0501 00:58:43.732965       1 utils.go:199] ID: 20 GRPC request: {}
I0501 00:58:43.732993       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0501 00:58:43.733128       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}}}]}
I0501 00:59:43.952285       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0501 00:59:43.952334       1 utils.go:199] ID: 21 GRPC request: {}
I0501 00:59:43.952369       1 utils.go:205] ID: 21 GRPC response: {}
I0501 01:00:43.906409       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0501 01:00:43.906468       1 utils.go:199] ID: 22 GRPC request: {}
I0501 01:00:43.906492       1 utils.go:205] ID: 22 GRPC response: {}
I0501 01:01:43.904946       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0501 01:01:43.904999       1 utils.go:199] ID: 23 GRPC request: {}
I0501 01:01:43.905022       1 utils.go:205] ID: 23 GRPC response: {}
I0501 01:01:48.069165       1 utils.go:198] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 GRPC call: /csi.v1.Controller/CreateVolume
I0501 01:01:48.069547       1 utils.go:199] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78","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}}]}
I0501 01:01:48.069922       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0501 01:01:48.088413       1 omap.go:80] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0501 01:01:48.100937       1 omap.go:159] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78:234e5430-053e-4481-92f5-c20a81eaf630])
I0501 01:01:48.107955       1 omap.go:159] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 set omap keys (pool="kube", namespace="", name="csi.volume.234e5430-053e-4481-92f5-c20a81eaf630"): map[csi.imagename:csi-vol-234e5430-053e-4481-92f5-c20a81eaf630 csi.volname:pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 csi.volume.owner:default])
I0501 01:01:48.107984       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-234e5430-053e-4481-92f5-c20a81eaf630) and image name (csi-vol-234e5430-053e-4481-92f5-c20a81eaf630) for request name (pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78)
I0501 01:01:48.108051       1 rbd_util.go:423] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 rbd: create kube/csi-vol-234e5430-053e-4481-92f5-c20a81eaf630 size 5120M (features: [layering]) using mon 199.204.45.30
I0501 01:01:48.108097       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 setting image options on kube/csi-vol-234e5430-053e-4481-92f5-c20a81eaf630
I0501 01:01:49.685228       1 controllerserver.go:763] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 created image kube/csi-vol-234e5430-053e-4481-92f5-c20a81eaf630 backed for request name pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78
I0501 01:01:49.718840       1 omap.go:159] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 set omap keys (pool="kube", namespace="", name="csi.volume.234e5430-053e-4481-92f5-c20a81eaf630"): map[csi.imageid:37d996bc6620])
I0501 01:01:49.815170       1 utils.go:205] ID: 24 Req-ID: pvc-0aa6cf8c-8c60-42e5-a804-6981c7943e78 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-234e5430-053e-4481-92f5-c20a81eaf630","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-234e5430-053e-4481-92f5-c20a81eaf630"}}