I0715 05:03:27.254629       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0715 05:03:27.254775       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0715 05:03:27.254793       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0715 05:03:27.254797       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0715 05:03:27.254802       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0715 05:03:27.254806       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0715 05:03:27.254810       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0715 05:03:27.254812       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0715 05:03:27.254815       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0715 05:03:27.254818       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0715 05:03:27.255106       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0715 05:03:27.255227       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0715 05:03:29.552312       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:29.553800       1 utils.go:199] ID: 1 GRPC request: {}
I0715 05:03:29.553867       1 utils.go:205] ID: 1 GRPC response: {}
I0715 05:03:29.559439       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0715 05:03:29.559514       1 utils.go:199] ID: 2 GRPC request: {}
I0715 05:03:29.559528       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0715 05:03:29.559564       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0715 05:03:29.561489       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:29.561658       1 utils.go:199] ID: 3 GRPC request: {}
I0715 05:03:29.561692       1 utils.go:205] ID: 3 GRPC response: {}
I0715 05:03:29.562092       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0715 05:03:29.562163       1 utils.go:199] ID: 4 GRPC request: {}
I0715 05:03:29.562370       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0715 05:03:29.563604       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0715 05:03:29.563788       1 utils.go:199] ID: 5 GRPC request: {}
I0715 05:03:29.563811       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0715 05:03:29.564096       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}}}]}
I0715 05:03:32.305773       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:32.305808       1 utils.go:199] ID: 6 GRPC request: {}
I0715 05:03:32.305825       1 utils.go:205] ID: 6 GRPC response: {}
I0715 05:03:32.307290       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0715 05:03:32.307310       1 utils.go:199] ID: 7 GRPC request: {}
I0715 05:03:32.307318       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0715 05:03:32.307348       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0715 05:03:32.308857       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:32.308910       1 utils.go:199] ID: 8 GRPC request: {}
I0715 05:03:32.308934       1 utils.go:205] ID: 8 GRPC response: {}
I0715 05:03:32.309593       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0715 05:03:32.309635       1 utils.go:199] ID: 9 GRPC request: {}
I0715 05:03:32.309806       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0715 05:03:32.310880       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0715 05:03:32.310901       1 utils.go:199] ID: 10 GRPC request: {}
I0715 05:03:32.310909       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0715 05:03:32.310975       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}}}]}
I0715 05:03:32.312061       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0715 05:03:32.312079       1 utils.go:199] ID: 11 GRPC request: {}
I0715 05:03:32.312085       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0715 05:03:32.312147       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}}}]}
I0715 05:03:32.312515       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0715 05:03:32.312537       1 utils.go:199] ID: 12 GRPC request: {}
I0715 05:03:32.312559       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0715 05:03:32.312683       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}}}]}
I0715 05:03:33.759420       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0715 05:03:33.759461       1 utils.go:199] ID: 13 GRPC request: {}
I0715 05:03:33.759470       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0715 05:03:33.759494       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0715 05:03:33.761326       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:33.761346       1 utils.go:199] ID: 14 GRPC request: {}
I0715 05:03:33.761373       1 utils.go:205] ID: 14 GRPC response: {}
I0715 05:03:33.761937       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0715 05:03:33.762004       1 utils.go:199] ID: 15 GRPC request: {}
I0715 05:03:33.762021       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0715 05:03:33.762175       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}}}]}
I0715 05:03:35.121920       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:35.121987       1 utils.go:199] ID: 16 GRPC request: {}
I0715 05:03:35.122056       1 utils.go:205] ID: 16 GRPC response: {}
I0715 05:03:35.123971       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0715 05:03:35.124043       1 utils.go:199] ID: 17 GRPC request: {}
I0715 05:03:35.124060       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0715 05:03:35.124125       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0715 05:03:35.128435       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0715 05:03:35.128456       1 utils.go:199] ID: 18 GRPC request: {}
I0715 05:03:35.128468       1 utils.go:205] ID: 18 GRPC response: {}
I0715 05:03:35.134119       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0715 05:03:35.134169       1 utils.go:199] ID: 19 GRPC request: {}
I0715 05:03:35.134265       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0715 05:03:35.135166       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0715 05:03:35.135193       1 utils.go:199] ID: 20 GRPC request: {}
I0715 05:03:35.135205       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0715 05:03:35.135386       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}}}]}
I0715 05:04:35.312139       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0715 05:04:35.312319       1 utils.go:199] ID: 21 GRPC request: {}
I0715 05:04:35.312405       1 utils.go:205] ID: 21 GRPC response: {}
I0715 05:05:35.312583       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0715 05:05:35.312684       1 utils.go:199] ID: 22 GRPC request: {}
I0715 05:05:35.312746       1 utils.go:205] ID: 22 GRPC response: {}
I0715 05:06:35.310988       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0715 05:06:35.311054       1 utils.go:199] ID: 23 GRPC request: {}
I0715 05:06:35.311086       1 utils.go:205] ID: 23 GRPC response: {}
I0715 05:07:35.322400       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0715 05:07:35.322508       1 utils.go:199] ID: 24 GRPC request: {}
I0715 05:07:35.322542       1 utils.go:205] ID: 24 GRPC response: {}
I0715 05:07:42.132540       1 utils.go:198] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 GRPC call: /csi.v1.Controller/CreateVolume
I0715 05:07:42.133164       1 utils.go:199] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-30f722fc-5653-40a2-895c-40f9b53fa910","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-30f722fc-5653-40a2-895c-40f9b53fa910","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}}]}
I0715 05:07:42.133483       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0715 05:07:42.151651       1 omap.go:80] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0715 05:07:42.167310       1 omap.go:159] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-30f722fc-5653-40a2-895c-40f9b53fa910:340c4ccf-620c-410c-9ced-870ebe97c7cf])
I0715 05:07:42.173316       1 omap.go:159] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 set omap keys (pool="kube", namespace="", name="csi.volume.340c4ccf-620c-410c-9ced-870ebe97c7cf"): map[csi.imagename:csi-vol-340c4ccf-620c-410c-9ced-870ebe97c7cf csi.volname:pvc-30f722fc-5653-40a2-895c-40f9b53fa910 csi.volume.owner:default])
I0715 05:07:42.173344       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-340c4ccf-620c-410c-9ced-870ebe97c7cf) and image name (csi-vol-340c4ccf-620c-410c-9ced-870ebe97c7cf) for request name (pvc-30f722fc-5653-40a2-895c-40f9b53fa910)
I0715 05:07:42.173396       1 rbd_util.go:423] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 rbd: create kube/csi-vol-340c4ccf-620c-410c-9ced-870ebe97c7cf size 5120M (features: [layering]) using mon 199.19.213.228
I0715 05:07:42.173435       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 setting image options on kube/csi-vol-340c4ccf-620c-410c-9ced-870ebe97c7cf
I0715 05:07:42.204055       1 controllerserver.go:763] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 created image kube/csi-vol-340c4ccf-620c-410c-9ced-870ebe97c7cf backed for request name pvc-30f722fc-5653-40a2-895c-40f9b53fa910
I0715 05:07:42.233108       1 omap.go:159] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 set omap keys (pool="kube", namespace="", name="csi.volume.340c4ccf-620c-410c-9ced-870ebe97c7cf"): map[csi.imageid:37f4867aceba])
I0715 05:07:42.316611       1 utils.go:205] ID: 25 Req-ID: pvc-30f722fc-5653-40a2-895c-40f9b53fa910 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-340c4ccf-620c-410c-9ced-870ebe97c7cf","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-340c4ccf-620c-410c-9ced-870ebe97c7cf"}}