I0417 00:55:04.380837       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0417 00:55:04.380944       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0417 00:55:04.380958       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0417 00:55:04.380961       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0417 00:55:04.380966       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0417 00:55:04.380969       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0417 00:55:04.380973       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0417 00:55:04.380975       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0417 00:55:04.380978       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0417 00:55:04.380981       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0417 00:55:04.381276       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0417 00:55:04.381367       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0417 00:55:10.454702       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:10.455608       1 utils.go:199] ID: 1 GRPC request: {}
I0417 00:55:10.455650       1 utils.go:205] ID: 1 GRPC response: {}
I0417 00:55:10.457954       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0417 00:55:10.458032       1 utils.go:199] ID: 2 GRPC request: {}
I0417 00:55:10.458049       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0417 00:55:10.458085       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0417 00:55:10.460965       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:10.461016       1 utils.go:199] ID: 3 GRPC request: {}
I0417 00:55:10.461044       1 utils.go:205] ID: 3 GRPC response: {}
I0417 00:55:10.461517       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0417 00:55:10.461630       1 utils.go:199] ID: 4 GRPC request: {}
I0417 00:55:10.461833       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0417 00:55:10.462628       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:55:10.462757       1 utils.go:199] ID: 5 GRPC request: {}
I0417 00:55:10.462776       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0417 00:55:10.462943       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}}}]}
I0417 00:55:10.757125       1 utils.go:198] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 GRPC call: /csi.v1.Controller/CreateVolume
I0417 00:55:10.757451       1 utils.go:199] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4","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}}]}
I0417 00:55:10.757649       1 rbd_util.go:1315] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0417 00:55:10.773670       1 omap.go:80] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0417 00:55:11.073191       1 omap.go:159] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4:74eed3ee-3812-4292-b172-039c400df4df])
I0417 00:55:11.149802       1 omap.go:159] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 set omap keys (pool="kube", namespace="", name="csi.volume.74eed3ee-3812-4292-b172-039c400df4df"): map[csi.imagename:csi-vol-74eed3ee-3812-4292-b172-039c400df4df csi.volname:pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 csi.volume.owner:default])
I0417 00:55:11.149849       1 rbd_journal.go:491] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-74eed3ee-3812-4292-b172-039c400df4df) and image name (csi-vol-74eed3ee-3812-4292-b172-039c400df4df) for request name (pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4)
I0417 00:55:11.149940       1 rbd_util.go:423] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 rbd: create kube/csi-vol-74eed3ee-3812-4292-b172-039c400df4df size 5120M (features: [layering]) using mon 199.204.45.129
I0417 00:55:11.149996       1 rbd_util.go:1563] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 setting image options on kube/csi-vol-74eed3ee-3812-4292-b172-039c400df4df
I0417 00:55:16.046549       1 controllerserver.go:763] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 created image kube/csi-vol-74eed3ee-3812-4292-b172-039c400df4df backed for request name pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4
I0417 00:55:16.085612       1 omap.go:159] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 set omap keys (pool="kube", namespace="", name="csi.volume.74eed3ee-3812-4292-b172-039c400df4df"): map[csi.imageid:37b388d381d2])
I0417 00:55:16.384231       1 utils.go:205] ID: 6 Req-ID: pvc-555f91e9-c4a5-4b7f-98fb-3d59a9346cc4 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-74eed3ee-3812-4292-b172-039c400df4df","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-74eed3ee-3812-4292-b172-039c400df4df"}}
I0417 00:55:17.030381       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:17.030420       1 utils.go:199] ID: 7 GRPC request: {}
I0417 00:55:17.030529       1 utils.go:205] ID: 7 GRPC response: {}
I0417 00:55:17.032556       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/GetPluginInfo
I0417 00:55:17.032575       1 utils.go:199] ID: 8 GRPC request: {}
I0417 00:55:17.032582       1 identityserver-default.go:40] ID: 8 Using default GetPluginInfo
I0417 00:55:17.032605       1 utils.go:205] ID: 8 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0417 00:55:17.034914       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:17.034944       1 utils.go:199] ID: 9 GRPC request: {}
I0417 00:55:17.034956       1 utils.go:205] ID: 9 GRPC response: {}
I0417 00:55:17.035878       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0417 00:55:17.035902       1 utils.go:199] ID: 10 GRPC request: {}
I0417 00:55:17.036019       1 utils.go:205] ID: 10 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0417 00:55:17.036569       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:55:17.036592       1 utils.go:199] ID: 11 GRPC request: {}
I0417 00:55:17.036695       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0417 00:55:17.036884       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}}}]}
I0417 00:55:17.037470       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:55:17.037508       1 utils.go:199] ID: 12 GRPC request: {}
I0417 00:55:17.037517       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0417 00:55:17.037608       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}}}]}
I0417 00:55:17.037968       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:55:17.037995       1 utils.go:199] ID: 13 GRPC request: {}
I0417 00:55:17.038006       1 controllerserver-default.go:42] ID: 13 Using default ControllerGetCapabilities
I0417 00:55:17.038096       1 utils.go:205] ID: 13 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0417 00:55:18.457086       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/GetPluginInfo
I0417 00:55:18.457121       1 utils.go:199] ID: 14 GRPC request: {}
I0417 00:55:18.457130       1 identityserver-default.go:40] ID: 14 Using default GetPluginInfo
I0417 00:55:18.457155       1 utils.go:205] ID: 14 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0417 00:55:18.460336       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:18.460372       1 utils.go:199] ID: 15 GRPC request: {}
I0417 00:55:18.460500       1 utils.go:205] ID: 15 GRPC response: {}
I0417 00:55:18.461208       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:55:18.461257       1 utils.go:199] ID: 16 GRPC request: {}
I0417 00:55:18.461270       1 controllerserver-default.go:42] ID: 16 Using default ControllerGetCapabilities
I0417 00:55:18.461350       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}}}]}
I0417 00:55:19.824330       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:19.824366       1 utils.go:199] ID: 17 GRPC request: {}
I0417 00:55:19.824485       1 utils.go:205] ID: 17 GRPC response: {}
I0417 00:55:19.826609       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/GetPluginInfo
I0417 00:55:19.826644       1 utils.go:199] ID: 18 GRPC request: {}
I0417 00:55:19.826835       1 identityserver-default.go:40] ID: 18 Using default GetPluginInfo
I0417 00:55:19.826992       1 utils.go:205] ID: 18 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0417 00:55:19.833231       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/Probe
I0417 00:55:19.833271       1 utils.go:199] ID: 19 GRPC request: {}
I0417 00:55:19.833286       1 utils.go:205] ID: 19 GRPC response: {}
I0417 00:55:19.834056       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0417 00:55:19.834087       1 utils.go:199] ID: 20 GRPC request: {}
I0417 00:55:19.834201       1 utils.go:205] ID: 20 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0417 00:55:19.834841       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:55:19.834863       1 utils.go:199] ID: 21 GRPC request: {}
I0417 00:55:19.834909       1 controllerserver-default.go:42] ID: 21 Using default ControllerGetCapabilities
I0417 00:55:19.835011       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}}}]}