I0730 18:57:50.089398       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0730 18:57:50.089553       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0730 18:57:50.089568       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0730 18:57:50.089573       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0730 18:57:50.089577       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0730 18:57:50.089581       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0730 18:57:50.089585       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0730 18:57:50.089589       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0730 18:57:50.089593       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0730 18:57:50.089597       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0730 18:57:50.089952       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0730 18:57:50.089981       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0730 18:57:58.097484       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0730 18:57:58.100433       1 utils.go:199] ID: 1 GRPC request: {}
I0730 18:57:58.100508       1 utils.go:205] ID: 1 GRPC response: {}
I0730 18:57:58.105041       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:57:58.105166       1 utils.go:199] ID: 2 GRPC request: {}
I0730 18:57:58.105192       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0730 18:57:58.105258       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:57:58.107524       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0730 18:57:58.107623       1 utils.go:199] ID: 3 GRPC request: {}
I0730 18:57:58.107687       1 utils.go:205] ID: 3 GRPC response: {}
I0730 18:57:58.108372       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 18:57:58.108496       1 utils.go:199] ID: 4 GRPC request: {}
I0730 18:57:58.108739       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 18:57:58.109712       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:57:58.109778       1 utils.go:199] ID: 5 GRPC request: {}
I0730 18:57:58.109795       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0730 18:57:58.109937       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}}}]}
I0730 18:57:59.787712       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0730 18:57:59.787770       1 utils.go:199] ID: 6 GRPC request: {}
I0730 18:57:59.787797       1 utils.go:205] ID: 6 GRPC response: {}
I0730 18:57:59.793372       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:57:59.793426       1 utils.go:199] ID: 7 GRPC request: {}
I0730 18:57:59.793436       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0730 18:57:59.793466       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:57:59.796971       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0730 18:57:59.797023       1 utils.go:199] ID: 8 GRPC request: {}
I0730 18:57:59.797055       1 utils.go:205] ID: 8 GRPC response: {}
I0730 18:57:59.797715       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 18:57:59.797761       1 utils.go:199] ID: 9 GRPC request: {}
I0730 18:57:59.797906       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 18:57:59.798589       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:57:59.798630       1 utils.go:199] ID: 10 GRPC request: {}
I0730 18:57:59.798643       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0730 18:57:59.798746       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}}}]}
I0730 18:57:59.799333       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:57:59.799392       1 utils.go:199] ID: 11 GRPC request: {}
I0730 18:57:59.799401       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0730 18:57:59.799505       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}}}]}
I0730 18:57:59.799876       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:57:59.799899       1 utils.go:199] ID: 12 GRPC request: {}
I0730 18:57:59.799907       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0730 18:57:59.799997       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}}}]}
I0730 18:58:05.010039       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:58:05.010076       1 utils.go:199] ID: 13 GRPC request: {}
I0730 18:58:05.010085       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0730 18:58:05.010109       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:58:05.012294       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:05.012333       1 utils.go:199] ID: 14 GRPC request: {}
I0730 18:58:05.012352       1 utils.go:205] ID: 14 GRPC response: {}
I0730 18:58:05.012968       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:05.012998       1 utils.go:199] ID: 15 GRPC request: {}
I0730 18:58:05.013010       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0730 18:58:05.013165       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}}}]}
I0730 18:58:09.175483       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:09.175535       1 utils.go:199] ID: 16 GRPC request: {}
I0730 18:58:09.175569       1 utils.go:205] ID: 16 GRPC response: {}
I0730 18:58:09.177494       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:58:09.177537       1 utils.go:199] ID: 17 GRPC request: {}
I0730 18:58:09.177551       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0730 18:58:09.177585       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:58:09.179767       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:09.179807       1 utils.go:199] ID: 18 GRPC request: {}
I0730 18:58:09.179834       1 utils.go:205] ID: 18 GRPC response: {}
I0730 18:58:09.180785       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 18:58:09.180825       1 utils.go:199] ID: 19 GRPC request: {}
I0730 18:58:09.180955       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 18:58:09.182368       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:09.182401       1 utils.go:199] ID: 20 GRPC request: {}
I0730 18:58:09.182415       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0730 18:58:09.182532       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}}}]}
I0730 18:58:53.704183       1 utils.go:198] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb GRPC call: /csi.v1.Controller/CreateVolume
I0730 18:58:53.704694       1 utils.go:199] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb GRPC request: {"capacity_range":{"required_bytes":171798691840},"name":"pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb","csi.storage.k8s.io/pvc/name":"datadir-percona-xtradb-pxc-0","csi.storage.k8s.io/pvc/namespace":"openstack","imageFeatures":"layering","pool":"kube"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0730 18:58:53.705044       1 rbd_util.go:1315] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb setting disableInUseChecks: false image features: [layering] mounter: rbd
E0730 18:58:53.726662       1 omap.go:80] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0730 18:58:53.793034       1 omap.go:159] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb:fb31e41b-0435-45d4-b7cc-c14beb18f36e])
I0730 18:58:53.805208       1 omap.go:159] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb set omap keys (pool="kube", namespace="", name="csi.volume.fb31e41b-0435-45d4-b7cc-c14beb18f36e"): map[csi.imagename:csi-vol-fb31e41b-0435-45d4-b7cc-c14beb18f36e csi.volname:pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb csi.volume.owner:openstack])
I0730 18:58:53.805255       1 rbd_journal.go:491] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-fb31e41b-0435-45d4-b7cc-c14beb18f36e) and image name (csi-vol-fb31e41b-0435-45d4-b7cc-c14beb18f36e) for request name (pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb)
I0730 18:58:53.805423       1 rbd_util.go:423] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb rbd: create kube/csi-vol-fb31e41b-0435-45d4-b7cc-c14beb18f36e size 163840M (features: [layering]) using mon 10.96.240.200
I0730 18:58:53.805580       1 rbd_util.go:1563] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb setting image options on kube/csi-vol-fb31e41b-0435-45d4-b7cc-c14beb18f36e
I0730 18:58:53.873070       1 controllerserver.go:763] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb created image kube/csi-vol-fb31e41b-0435-45d4-b7cc-c14beb18f36e backed for request name pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb
I0730 18:58:53.937842       1 omap.go:159] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb set omap keys (pool="kube", namespace="", name="csi.volume.fb31e41b-0435-45d4-b7cc-c14beb18f36e"): map[csi.imageid:37c62aadc4ee])
I0730 18:58:54.032242       1 utils.go:205] ID: 21 Req-ID: pvc-84bf94e4-5d80-4ad2-8a49-17362f0455fb GRPC response: {"volume":{"capacity_bytes":171798691840,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-fb31e41b-0435-45d4-b7cc-c14beb18f36e","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-fb31e41b-0435-45d4-b7cc-c14beb18f36e"}}
I0730 18:59:09.406470       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0730 18:59:09.406512       1 utils.go:199] ID: 22 GRPC request: {}
I0730 18:59:09.406528       1 utils.go:205] ID: 22 GRPC response: {}
I0730 19:00:09.359700       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0730 19:00:09.359792       1 utils.go:199] ID: 23 GRPC request: {}
I0730 19:00:09.359823       1 utils.go:205] ID: 23 GRPC response: {}
I0730 19:01:09.412941       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0730 19:01:09.412995       1 utils.go:199] ID: 24 GRPC request: {}
I0730 19:01:09.413018       1 utils.go:205] ID: 24 GRPC response: {}
I0730 19:02:09.367668       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0730 19:02:09.368951       1 utils.go:199] ID: 25 GRPC request: {}
I0730 19:02:09.369022       1 utils.go:205] ID: 25 GRPC response: {}
I0730 19:03:09.413862       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe
I0730 19:03:09.413919       1 utils.go:199] ID: 26 GRPC request: {}
I0730 19:03:09.413942       1 utils.go:205] ID: 26 GRPC response: {}
I0730 19:04:09.378000       1 utils.go:198] ID: 27 GRPC call: /csi.v1.Identity/Probe
I0730 19:04:09.378049       1 utils.go:199] ID: 27 GRPC request: {}
I0730 19:04:09.378072       1 utils.go:205] ID: 27 GRPC response: {}
I0730 19:05:09.357941       1 utils.go:198] ID: 28 GRPC call: /csi.v1.Identity/Probe
I0730 19:05:09.358030       1 utils.go:199] ID: 28 GRPC request: {}
I0730 19:05:09.358059       1 utils.go:205] ID: 28 GRPC response: {}
I0730 19:06:09.356908       1 utils.go:198] ID: 29 GRPC call: /csi.v1.Identity/Probe
I0730 19:06:09.356955       1 utils.go:199] ID: 29 GRPC request: {}
I0730 19:06:09.356973       1 utils.go:205] ID: 29 GRPC response: {}
I0730 19:07:09.357629       1 utils.go:198] ID: 30 GRPC call: /csi.v1.Identity/Probe
I0730 19:07:09.357695       1 utils.go:199] ID: 30 GRPC request: {}
I0730 19:07:09.357718       1 utils.go:205] ID: 30 GRPC response: {}
I0730 19:08:09.356972       1 utils.go:198] ID: 31 GRPC call: /csi.v1.Identity/Probe
I0730 19:08:09.357048       1 utils.go:199] ID: 31 GRPC request: {}
I0730 19:08:09.357087       1 utils.go:205] ID: 31 GRPC response: {}
I0730 19:09:09.367807       1 utils.go:198] ID: 32 GRPC call: /csi.v1.Identity/Probe
I0730 19:09:09.367884       1 utils.go:199] ID: 32 GRPC request: {}
I0730 19:09:09.367917       1 utils.go:205] ID: 32 GRPC response: {}
I0730 19:09:40.083085       1 utils.go:198] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:09:40.083332       1 utils.go:199] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-keystone-server-0","csi.storage.k8s.io/pvc/namespace":"openstack","imageFeatures":"layering","pool":"kube"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0730 19:09:40.083538       1 rbd_util.go:1315] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:09:40.085960       1 omap.go:89] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:09:40.106468       1 omap.go:159] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2:9af417cb-d9e9-428f-b0c6-1cefc544f9ab])
I0730 19:09:40.114274       1 omap.go:159] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 set omap keys (pool="kube", namespace="", name="csi.volume.9af417cb-d9e9-428f-b0c6-1cefc544f9ab"): map[csi.imagename:csi-vol-9af417cb-d9e9-428f-b0c6-1cefc544f9ab csi.volname:pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 csi.volume.owner:openstack])
I0730 19:09:40.114302       1 rbd_journal.go:491] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9af417cb-d9e9-428f-b0c6-1cefc544f9ab) and image name (csi-vol-9af417cb-d9e9-428f-b0c6-1cefc544f9ab) for request name (pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2)
I0730 19:09:40.114385       1 rbd_util.go:423] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 rbd: create kube/csi-vol-9af417cb-d9e9-428f-b0c6-1cefc544f9ab size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:09:40.114414       1 rbd_util.go:1563] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 setting image options on kube/csi-vol-9af417cb-d9e9-428f-b0c6-1cefc544f9ab
I0730 19:09:40.143033       1 controllerserver.go:763] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 created image kube/csi-vol-9af417cb-d9e9-428f-b0c6-1cefc544f9ab backed for request name pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2
I0730 19:09:40.173447       1 omap.go:159] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 set omap keys (pool="kube", namespace="", name="csi.volume.9af417cb-d9e9-428f-b0c6-1cefc544f9ab"): map[csi.imageid:37c6221ce48b])
I0730 19:09:40.311423       1 utils.go:205] ID: 33 Req-ID: pvc-7447fece-2d70-4ce2-981b-f3ee66e95de2 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-9af417cb-d9e9-428f-b0c6-1cefc544f9ab","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9af417cb-d9e9-428f-b0c6-1cefc544f9ab"}}
I0730 19:10:09.357012       1 utils.go:198] ID: 34 GRPC call: /csi.v1.Identity/Probe
I0730 19:10:09.357064       1 utils.go:199] ID: 34 GRPC request: {}
I0730 19:10:09.357088       1 utils.go:205] ID: 34 GRPC response: {}
I0730 19:11:09.378353       1 utils.go:198] ID: 35 GRPC call: /csi.v1.Identity/Probe
I0730 19:11:09.378448       1 utils.go:199] ID: 35 GRPC request: {}
I0730 19:11:09.378480       1 utils.go:205] ID: 35 GRPC response: {}
I0730 19:12:09.357063       1 utils.go:198] ID: 36 GRPC call: /csi.v1.Identity/Probe
I0730 19:12:09.357200       1 utils.go:199] ID: 36 GRPC request: {}
I0730 19:12:09.357249       1 utils.go:205] ID: 36 GRPC response: {}
I0730 19:13:09.377912       1 utils.go:198] ID: 37 GRPC call: /csi.v1.Identity/Probe
I0730 19:13:09.378006       1 utils.go:199] ID: 37 GRPC request: {}
I0730 19:13:09.378038       1 utils.go:205] ID: 37 GRPC response: {}
I0730 19:13:39.702988       1 utils.go:198] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:13:39.703554       1 utils.go:199] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-barbican-server-0","csi.storage.k8s.io/pvc/namespace":"openstack","imageFeatures":"layering","pool":"kube"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0730 19:13:39.703773       1 rbd_util.go:1315] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:13:39.705541       1 omap.go:89] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:13:39.719224       1 omap.go:159] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0:adbf227c-4f73-4f27-a3ed-f207e215a387])
I0730 19:13:39.727848       1 omap.go:159] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 set omap keys (pool="kube", namespace="", name="csi.volume.adbf227c-4f73-4f27-a3ed-f207e215a387"): map[csi.imagename:csi-vol-adbf227c-4f73-4f27-a3ed-f207e215a387 csi.volname:pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 csi.volume.owner:openstack])
I0730 19:13:39.727898       1 rbd_journal.go:491] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-adbf227c-4f73-4f27-a3ed-f207e215a387) and image name (csi-vol-adbf227c-4f73-4f27-a3ed-f207e215a387) for request name (pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0)
I0730 19:13:39.728076       1 rbd_util.go:423] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 rbd: create kube/csi-vol-adbf227c-4f73-4f27-a3ed-f207e215a387 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:13:39.728637       1 rbd_util.go:1563] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 setting image options on kube/csi-vol-adbf227c-4f73-4f27-a3ed-f207e215a387
I0730 19:13:39.760689       1 controllerserver.go:763] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 created image kube/csi-vol-adbf227c-4f73-4f27-a3ed-f207e215a387 backed for request name pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0
I0730 19:13:39.793781       1 omap.go:159] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 set omap keys (pool="kube", namespace="", name="csi.volume.adbf227c-4f73-4f27-a3ed-f207e215a387"): map[csi.imageid:37c6a14a71f0])
I0730 19:13:39.900287       1 utils.go:205] ID: 38 Req-ID: pvc-bbec7efe-7146-4c00-b6f5-0224a07e92d0 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-adbf227c-4f73-4f27-a3ed-f207e215a387","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-adbf227c-4f73-4f27-a3ed-f207e215a387"}}
I0730 19:14:09.357524       1 utils.go:198] ID: 39 GRPC call: /csi.v1.Identity/Probe
I0730 19:14:09.357693       1 utils.go:199] ID: 39 GRPC request: {}
I0730 19:14:09.357813       1 utils.go:205] ID: 39 GRPC response: {}
I0730 19:15:09.412754       1 utils.go:198] ID: 40 GRPC call: /csi.v1.Identity/Probe
I0730 19:15:09.412836       1 utils.go:199] ID: 40 GRPC request: {}
I0730 19:15:09.412883       1 utils.go:205] ID: 40 GRPC response: {}
I0730 19:16:09.367887       1 utils.go:198] ID: 41 GRPC call: /csi.v1.Identity/Probe
I0730 19:16:09.367990       1 utils.go:199] ID: 41 GRPC request: {}
I0730 19:16:09.368022       1 utils.go:205] ID: 41 GRPC response: {}
I0730 19:17:09.356918       1 utils.go:198] ID: 42 GRPC call: /csi.v1.Identity/Probe
I0730 19:17:09.356978       1 utils.go:199] ID: 42 GRPC request: {}
I0730 19:17:09.356999       1 utils.go:205] ID: 42 GRPC response: {}
I0730 19:18:09.377732       1 utils.go:198] ID: 43 GRPC call: /csi.v1.Identity/Probe
I0730 19:18:09.377792       1 utils.go:199] ID: 43 GRPC request: {}
I0730 19:18:09.377818       1 utils.go:205] ID: 43 GRPC response: {}
I0730 19:19:09.412503       1 utils.go:198] ID: 44 GRPC call: /csi.v1.Identity/Probe
I0730 19:19:09.412558       1 utils.go:199] ID: 44 GRPC request: {}
I0730 19:19:09.412582       1 utils.go:205] ID: 44 GRPC response: {}
I0730 19:20:09.389215       1 utils.go:198] ID: 45 GRPC call: /csi.v1.Identity/Probe
I0730 19:20:09.389304       1 utils.go:199] ID: 45 GRPC request: {}
I0730 19:20:09.389343       1 utils.go:205] ID: 45 GRPC response: {}
I0730 19:21:09.408915       1 utils.go:198] ID: 46 GRPC call: /csi.v1.Identity/Probe
I0730 19:21:09.408995       1 utils.go:199] ID: 46 GRPC request: {}
I0730 19:21:09.409044       1 utils.go:205] ID: 46 GRPC response: {}
I0730 19:22:09.399511       1 utils.go:198] ID: 47 GRPC call: /csi.v1.Identity/Probe
I0730 19:22:09.399596       1 utils.go:199] ID: 47 GRPC request: {}
I0730 19:22:09.399619       1 utils.go:205] ID: 47 GRPC response: {}
I0730 19:23:09.357180       1 utils.go:198] ID: 48 GRPC call: /csi.v1.Identity/Probe
I0730 19:23:09.357254       1 utils.go:199] ID: 48 GRPC request: {}
I0730 19:23:09.357283       1 utils.go:205] ID: 48 GRPC response: {}
I0730 19:24:09.408955       1 utils.go:198] ID: 49 GRPC call: /csi.v1.Identity/Probe
I0730 19:24:09.409010       1 utils.go:199] ID: 49 GRPC request: {}
I0730 19:24:09.409030       1 utils.go:205] ID: 49 GRPC response: {}