I0824 15:47:12.266225       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0824 15:47:12.266373       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0824 15:47:12.266391       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0824 15:47:12.266394       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0824 15:47:12.266397       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0824 15:47:12.266409       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0824 15:47:12.266413       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0824 15:47:12.266418       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0824 15:47:12.266421       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0824 15:47:12.266424       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0824 15:47:12.266666       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0824 15:47:12.266735       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0824 15:47:16.089493       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:16.090874       1 utils.go:199] ID: 1 GRPC request: {}
I0824 15:47:16.090973       1 utils.go:205] ID: 1 GRPC response: {}
I0824 15:47:16.093234       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0824 15:47:16.093318       1 utils.go:199] ID: 2 GRPC request: {}
I0824 15:47:16.093336       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0824 15:47:16.093385       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0824 15:47:16.096950       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:16.096973       1 utils.go:199] ID: 3 GRPC request: {}
I0824 15:47:16.096989       1 utils.go:205] ID: 3 GRPC response: {}
I0824 15:47:16.097421       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0824 15:47:16.097501       1 utils.go:199] ID: 4 GRPC request: {}
I0824 15:47:16.097621       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0824 15:47:16.098253       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0824 15:47:16.098321       1 utils.go:199] ID: 5 GRPC request: {}
I0824 15:47:16.098348       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0824 15:47:16.098437       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}}}]}
I0824 15:47:18.606786       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:18.606822       1 utils.go:199] ID: 6 GRPC request: {}
I0824 15:47:18.606837       1 utils.go:205] ID: 6 GRPC response: {}
I0824 15:47:18.611888       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0824 15:47:18.611928       1 utils.go:199] ID: 7 GRPC request: {}
I0824 15:47:18.611941       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0824 15:47:18.611980       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0824 15:47:18.614011       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:18.614041       1 utils.go:199] ID: 8 GRPC request: {}
I0824 15:47:18.614056       1 utils.go:205] ID: 8 GRPC response: {}
I0824 15:47:18.614547       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0824 15:47:18.614567       1 utils.go:199] ID: 9 GRPC request: {}
I0824 15:47:18.614696       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0824 15:47:18.615472       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0824 15:47:18.615498       1 utils.go:199] ID: 10 GRPC request: {}
I0824 15:47:18.615506       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0824 15:47:18.615625       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}}}]}
I0824 15:47:18.617714       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0824 15:47:18.617738       1 utils.go:199] ID: 11 GRPC request: {}
I0824 15:47:18.617747       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0824 15:47:18.617818       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}}}]}
I0824 15:47:18.624882       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0824 15:47:18.624904       1 utils.go:199] ID: 12 GRPC request: {}
I0824 15:47:18.624910       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0824 15:47:18.624985       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}}}]}
I0824 15:47:20.366054       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0824 15:47:20.366083       1 utils.go:199] ID: 13 GRPC request: {}
I0824 15:47:20.366090       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0824 15:47:20.366107       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0824 15:47:20.369577       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:20.369594       1 utils.go:199] ID: 14 GRPC request: {}
I0824 15:47:20.369607       1 utils.go:205] ID: 14 GRPC response: {}
I0824 15:47:20.370070       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0824 15:47:20.370085       1 utils.go:199] ID: 15 GRPC request: {}
I0824 15:47:20.370091       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0824 15:47:20.370154       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}}}]}
I0824 15:47:22.857026       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:22.857058       1 utils.go:199] ID: 16 GRPC request: {}
I0824 15:47:22.857071       1 utils.go:205] ID: 16 GRPC response: {}
I0824 15:47:22.860232       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0824 15:47:22.860248       1 utils.go:199] ID: 17 GRPC request: {}
I0824 15:47:22.860254       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0824 15:47:22.860270       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0824 15:47:22.866887       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0824 15:47:22.866933       1 utils.go:199] ID: 18 GRPC request: {}
I0824 15:47:22.866981       1 utils.go:205] ID: 18 GRPC response: {}
I0824 15:47:22.871220       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0824 15:47:22.871267       1 utils.go:199] ID: 19 GRPC request: {}
I0824 15:47:22.871353       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0824 15:47:22.873692       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0824 15:47:22.873725       1 utils.go:199] ID: 20 GRPC request: {}
I0824 15:47:22.873744       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0824 15:47:22.873865       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}}}]}
I0824 15:48:23.113234       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0824 15:48:23.113290       1 utils.go:199] ID: 21 GRPC request: {}
I0824 15:48:23.113301       1 utils.go:205] ID: 21 GRPC response: {}
I0824 15:49:23.073170       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0824 15:49:23.073229       1 utils.go:199] ID: 22 GRPC request: {}
I0824 15:49:23.073244       1 utils.go:205] ID: 22 GRPC response: {}
I0824 15:50:06.789223       1 utils.go:198] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 GRPC call: /csi.v1.Controller/CreateVolume
I0824 15:50:06.789666       1 utils.go:199] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-786a0085-e9fc-456e-9010-7f62d06f5951","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-786a0085-e9fc-456e-9010-7f62d06f5951","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}}]}
I0824 15:50:06.789981       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0824 15:50:06.808884       1 omap.go:80] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0824 15:50:06.820766       1 omap.go:159] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-786a0085-e9fc-456e-9010-7f62d06f5951:700891e7-e103-4c22-a6af-2090b83761ce])
I0824 15:50:06.826127       1 omap.go:159] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 set omap keys (pool="kube", namespace="", name="csi.volume.700891e7-e103-4c22-a6af-2090b83761ce"): map[csi.imagename:csi-vol-700891e7-e103-4c22-a6af-2090b83761ce csi.volname:pvc-786a0085-e9fc-456e-9010-7f62d06f5951 csi.volume.owner:default])
I0824 15:50:06.826198       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-700891e7-e103-4c22-a6af-2090b83761ce) and image name (csi-vol-700891e7-e103-4c22-a6af-2090b83761ce) for request name (pvc-786a0085-e9fc-456e-9010-7f62d06f5951)
I0824 15:50:06.826289       1 rbd_util.go:423] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 rbd: create kube/csi-vol-700891e7-e103-4c22-a6af-2090b83761ce size 5120M (features: [layering]) using mon 199.19.213.81
I0824 15:50:06.826354       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 setting image options on kube/csi-vol-700891e7-e103-4c22-a6af-2090b83761ce
I0824 15:50:06.848865       1 controllerserver.go:763] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 created image kube/csi-vol-700891e7-e103-4c22-a6af-2090b83761ce backed for request name pvc-786a0085-e9fc-456e-9010-7f62d06f5951
I0824 15:50:06.871991       1 omap.go:159] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 set omap keys (pool="kube", namespace="", name="csi.volume.700891e7-e103-4c22-a6af-2090b83761ce"): map[csi.imageid:38044aa14a82])
I0824 15:50:06.945796       1 utils.go:205] ID: 23 Req-ID: pvc-786a0085-e9fc-456e-9010-7f62d06f5951 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-700891e7-e103-4c22-a6af-2090b83761ce","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-700891e7-e103-4c22-a6af-2090b83761ce"}}
I0824 15:50:23.116450       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0824 15:50:23.116493       1 utils.go:199] ID: 24 GRPC request: {}
I0824 15:50:23.116529       1 utils.go:205] ID: 24 GRPC response: {}