I0727 21:49:54.265234       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0727 21:49:54.265366       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0727 21:49:54.265380       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0727 21:49:54.265388       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0727 21:49:54.265391       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0727 21:49:54.265394       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0727 21:49:54.265433       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0727 21:49:54.265438       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0727 21:49:54.265441       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0727 21:49:54.265443       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0727 21:49:54.265846       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0727 21:49:54.265863       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0727 21:49:56.203567       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0727 21:49:56.204511       1 utils.go:199] ID: 1 GRPC request: {}
I0727 21:49:56.204556       1 utils.go:205] ID: 1 GRPC response: {}
I0727 21:49:56.207790       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0727 21:49:56.207822       1 utils.go:199] ID: 2 GRPC request: {}
I0727 21:49:56.207832       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0727 21:49:56.207863       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0727 21:49:56.209702       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0727 21:49:56.209719       1 utils.go:199] ID: 3 GRPC request: {}
I0727 21:49:56.209726       1 utils.go:205] ID: 3 GRPC response: {}
I0727 21:49:56.210074       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0727 21:49:56.210109       1 utils.go:199] ID: 4 GRPC request: {}
I0727 21:49:56.210206       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0727 21:49:56.210893       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0727 21:49:56.210922       1 utils.go:199] ID: 5 GRPC request: {}
I0727 21:49:56.210929       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0727 21:49:56.210994       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}}}]}
I0727 21:49:57.391892       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0727 21:49:57.391933       1 utils.go:199] ID: 6 GRPC request: {}
I0727 21:49:57.391990       1 utils.go:205] ID: 6 GRPC response: {}
I0727 21:49:57.394912       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0727 21:49:57.394942       1 utils.go:199] ID: 7 GRPC request: {}
I0727 21:49:57.394949       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0727 21:49:57.394979       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0727 21:49:57.398655       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0727 21:49:57.398715       1 utils.go:199] ID: 8 GRPC request: {}
I0727 21:49:57.398746       1 utils.go:205] ID: 8 GRPC response: {}
I0727 21:49:57.399505       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0727 21:49:57.399545       1 utils.go:199] ID: 9 GRPC request: {}
I0727 21:49:57.399752       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0727 21:49:57.400497       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0727 21:49:57.400529       1 utils.go:199] ID: 10 GRPC request: {}
I0727 21:49:57.400540       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0727 21:49:57.400646       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}}}]}
I0727 21:49:57.401823       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0727 21:49:57.401846       1 utils.go:199] ID: 11 GRPC request: {}
I0727 21:49:57.401875       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0727 21:49:57.401957       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}}}]}
I0727 21:49:57.402376       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0727 21:49:57.402398       1 utils.go:199] ID: 12 GRPC request: {}
I0727 21:49:57.402406       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0727 21:49:57.402503       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}}}]}
I0727 21:49:59.248897       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0727 21:49:59.248943       1 utils.go:199] ID: 13 GRPC request: {}
I0727 21:49:59.248956       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0727 21:49:59.249152       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0727 21:49:59.253097       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0727 21:49:59.253127       1 utils.go:199] ID: 14 GRPC request: {}
I0727 21:49:59.253146       1 utils.go:205] ID: 14 GRPC response: {}
I0727 21:49:59.253842       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0727 21:49:59.253865       1 utils.go:199] ID: 15 GRPC request: {}
I0727 21:49:59.253875       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0727 21:49:59.254118       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}}}]}
I0727 21:50:01.082625       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0727 21:50:01.082730       1 utils.go:199] ID: 16 GRPC request: {}
I0727 21:50:01.082771       1 utils.go:205] ID: 16 GRPC response: {}
I0727 21:50:01.085223       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0727 21:50:01.085270       1 utils.go:199] ID: 17 GRPC request: {}
I0727 21:50:01.085293       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0727 21:50:01.085334       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0727 21:50:01.086770       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0727 21:50:01.086805       1 utils.go:199] ID: 18 GRPC request: {}
I0727 21:50:01.086834       1 utils.go:205] ID: 18 GRPC response: {}
I0727 21:50:01.087166       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0727 21:50:01.087227       1 utils.go:199] ID: 19 GRPC request: {}
I0727 21:50:01.087360       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0727 21:50:01.088150       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0727 21:50:01.088192       1 utils.go:199] ID: 20 GRPC request: {}
I0727 21:50:01.088209       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0727 21:50:01.088320       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}}}]}
I0727 21:51:01.287578       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0727 21:51:01.287655       1 utils.go:199] ID: 21 GRPC request: {}
I0727 21:51:01.287687       1 utils.go:205] ID: 21 GRPC response: {}
I0727 21:52:01.288445       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0727 21:52:01.288612       1 utils.go:199] ID: 22 GRPC request: {}
I0727 21:52:01.288757       1 utils.go:205] ID: 22 GRPC response: {}
I0727 21:53:01.286742       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0727 21:53:01.286790       1 utils.go:199] ID: 23 GRPC request: {}
I0727 21:53:01.286823       1 utils.go:205] ID: 23 GRPC response: {}
I0727 21:54:01.297769       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0727 21:54:01.297821       1 utils.go:199] ID: 24 GRPC request: {}
I0727 21:54:01.297844       1 utils.go:205] ID: 24 GRPC response: {}
I0727 21:54:10.761063       1 utils.go:198] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 GRPC call: /csi.v1.Controller/CreateVolume
I0727 21:54:10.761464       1 utils.go:199] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-752210cf-15c0-432b-96c0-03bcb68fb646","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-752210cf-15c0-432b-96c0-03bcb68fb646","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}}]}
I0727 21:54:10.761721       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0727 21:54:10.779338       1 omap.go:80] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0727 21:54:10.794537       1 omap.go:159] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-752210cf-15c0-432b-96c0-03bcb68fb646:b781cab4-7905-4104-9396-2674ab52e475])
I0727 21:54:10.801957       1 omap.go:159] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 set omap keys (pool="kube", namespace="", name="csi.volume.b781cab4-7905-4104-9396-2674ab52e475"): map[csi.imagename:csi-vol-b781cab4-7905-4104-9396-2674ab52e475 csi.volname:pvc-752210cf-15c0-432b-96c0-03bcb68fb646 csi.volume.owner:default])
I0727 21:54:10.801990       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b781cab4-7905-4104-9396-2674ab52e475) and image name (csi-vol-b781cab4-7905-4104-9396-2674ab52e475) for request name (pvc-752210cf-15c0-432b-96c0-03bcb68fb646)
I0727 21:54:10.802057       1 rbd_util.go:423] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 rbd: create kube/csi-vol-b781cab4-7905-4104-9396-2674ab52e475 size 5120M (features: [layering]) using mon 199.204.45.177
I0727 21:54:10.802119       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 setting image options on kube/csi-vol-b781cab4-7905-4104-9396-2674ab52e475
I0727 21:54:10.832771       1 controllerserver.go:763] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 created image kube/csi-vol-b781cab4-7905-4104-9396-2674ab52e475 backed for request name pvc-752210cf-15c0-432b-96c0-03bcb68fb646
I0727 21:54:10.861639       1 omap.go:159] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 set omap keys (pool="kube", namespace="", name="csi.volume.b781cab4-7905-4104-9396-2674ab52e475"): map[csi.imageid:37f4acce0c3d])
I0727 21:54:10.951614       1 utils.go:205] ID: 25 Req-ID: pvc-752210cf-15c0-432b-96c0-03bcb68fb646 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-b781cab4-7905-4104-9396-2674ab52e475","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b781cab4-7905-4104-9396-2674ab52e475"}}