I0730 21:10:39.453574       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0730 21:10:39.453746       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0730 21:10:39.453760       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0730 21:10:39.453764       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0730 21:10:39.453768       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0730 21:10:39.453772       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0730 21:10:39.453777       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0730 21:10:39.453781       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0730 21:10:39.453784       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0730 21:10:39.453788       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0730 21:10:39.454379       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0730 21:10:39.454446       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0730 21:10:46.931263       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:46.933168       1 utils.go:199] ID: 1 GRPC request: {}
I0730 21:10:46.933249       1 utils.go:205] ID: 1 GRPC response: {}
I0730 21:10:46.936075       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 21:10:46.936177       1 utils.go:199] ID: 2 GRPC request: {}
I0730 21:10:46.936206       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0730 21:10:46.936289       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 21:10:46.938048       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:46.938140       1 utils.go:199] ID: 3 GRPC request: {}
I0730 21:10:46.938175       1 utils.go:205] ID: 3 GRPC response: {}
I0730 21:10:46.938867       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 21:10:46.939028       1 utils.go:199] ID: 4 GRPC request: {}
I0730 21:10:46.939294       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 21:10:46.940398       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 21:10:46.940573       1 utils.go:199] ID: 5 GRPC request: {}
I0730 21:10:46.940587       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0730 21:10:46.940701       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 21:10:48.549994       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:48.550042       1 utils.go:199] ID: 6 GRPC request: {}
I0730 21:10:48.550081       1 utils.go:205] ID: 6 GRPC response: {}
I0730 21:10:48.554427       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 21:10:48.554463       1 utils.go:199] ID: 7 GRPC request: {}
I0730 21:10:48.554475       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0730 21:10:48.554510       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 21:10:48.557691       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:48.557740       1 utils.go:199] ID: 8 GRPC request: {}
I0730 21:10:48.557773       1 utils.go:205] ID: 8 GRPC response: {}
I0730 21:10:48.558493       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 21:10:48.558534       1 utils.go:199] ID: 9 GRPC request: {}
I0730 21:10:48.558857       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 21:10:48.559722       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 21:10:48.559741       1 utils.go:199] ID: 10 GRPC request: {}
I0730 21:10:48.559749       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0730 21:10:48.559820       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 21:10:48.560454       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 21:10:48.560472       1 utils.go:199] ID: 11 GRPC request: {}
I0730 21:10:48.560481       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0730 21:10:48.560530       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 21:10:48.560907       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 21:10:48.560935       1 utils.go:199] ID: 12 GRPC request: {}
I0730 21:10:48.560943       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0730 21:10:48.561064       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 21:10:56.917402       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 21:10:56.917458       1 utils.go:199] ID: 13 GRPC request: {}
I0730 21:10:56.917470       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0730 21:10:56.917505       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 21:10:56.921333       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:56.921371       1 utils.go:199] ID: 14 GRPC request: {}
I0730 21:10:56.921388       1 utils.go:205] ID: 14 GRPC response: {}
I0730 21:10:56.922192       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 21:10:56.922225       1 utils.go:199] ID: 15 GRPC request: {}
I0730 21:10:56.922237       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0730 21:10:56.922365       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 21:10:58.656464       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:58.656560       1 utils.go:199] ID: 16 GRPC request: {}
I0730 21:10:58.656589       1 utils.go:205] ID: 16 GRPC response: {}
I0730 21:10:58.660019       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 21:10:58.660072       1 utils.go:199] ID: 17 GRPC request: {}
I0730 21:10:58.660092       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0730 21:10:58.660124       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 21:10:58.663993       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0730 21:10:58.664036       1 utils.go:199] ID: 18 GRPC request: {}
I0730 21:10:58.664065       1 utils.go:205] ID: 18 GRPC response: {}
I0730 21:10:58.664607       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 21:10:58.664638       1 utils.go:199] ID: 19 GRPC request: {}
I0730 21:10:58.664759       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 21:10:58.665563       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 21:10:58.665628       1 utils.go:199] ID: 20 GRPC request: {}
I0730 21:10:58.665642       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0730 21:10:58.665793       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 21:11:40.927917       1 utils.go:198] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:11:40.928261       1 utils.go:199] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e GRPC request: {"capacity_range":{"required_bytes":171798691840},"name":"pvc-22085d38-b7b6-4591-98bf-7b0fa069133e","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-22085d38-b7b6-4591-98bf-7b0fa069133e","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 21:11:40.928474       1 rbd_util.go:1315] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e setting disableInUseChecks: false image features: [layering] mounter: rbd
E0730 21:11:40.945300       1 omap.go:80] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0730 21:11:40.960981       1 omap.go:159] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-22085d38-b7b6-4591-98bf-7b0fa069133e:242e4cb4-fb58-4e5a-b320-3e4461b0c81f])
I0730 21:11:40.969007       1 omap.go:159] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e set omap keys (pool="kube", namespace="", name="csi.volume.242e4cb4-fb58-4e5a-b320-3e4461b0c81f"): map[csi.imagename:csi-vol-242e4cb4-fb58-4e5a-b320-3e4461b0c81f csi.volname:pvc-22085d38-b7b6-4591-98bf-7b0fa069133e csi.volume.owner:openstack])
I0730 21:11:40.969047       1 rbd_journal.go:491] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-242e4cb4-fb58-4e5a-b320-3e4461b0c81f) and image name (csi-vol-242e4cb4-fb58-4e5a-b320-3e4461b0c81f) for request name (pvc-22085d38-b7b6-4591-98bf-7b0fa069133e)
I0730 21:11:40.969121       1 rbd_util.go:423] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e rbd: create kube/csi-vol-242e4cb4-fb58-4e5a-b320-3e4461b0c81f size 163840M (features: [layering]) using mon 10.96.240.200
I0730 21:11:40.969174       1 rbd_util.go:1563] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e setting image options on kube/csi-vol-242e4cb4-fb58-4e5a-b320-3e4461b0c81f
I0730 21:11:41.007383       1 controllerserver.go:763] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e created image kube/csi-vol-242e4cb4-fb58-4e5a-b320-3e4461b0c81f backed for request name pvc-22085d38-b7b6-4591-98bf-7b0fa069133e
I0730 21:11:41.039253       1 omap.go:159] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e set omap keys (pool="kube", namespace="", name="csi.volume.242e4cb4-fb58-4e5a-b320-3e4461b0c81f"): map[csi.imageid:37c6d63d8add])
I0730 21:11:41.137084       1 utils.go:205] ID: 21 Req-ID: pvc-22085d38-b7b6-4591-98bf-7b0fa069133e GRPC response: {"volume":{"capacity_bytes":171798691840,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-242e4cb4-fb58-4e5a-b320-3e4461b0c81f","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-242e4cb4-fb58-4e5a-b320-3e4461b0c81f"}}
I0730 21:11:59.395595       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0730 21:11:59.395652       1 utils.go:199] ID: 22 GRPC request: {}
I0730 21:11:59.395680       1 utils.go:205] ID: 22 GRPC response: {}
I0730 21:12:59.338103       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0730 21:12:59.338216       1 utils.go:199] ID: 23 GRPC request: {}
I0730 21:12:59.338267       1 utils.go:205] ID: 23 GRPC response: {}
I0730 21:13:59.338546       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0730 21:13:59.338623       1 utils.go:199] ID: 24 GRPC request: {}
I0730 21:13:59.338646       1 utils.go:205] ID: 24 GRPC response: {}
I0730 21:14:59.350618       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0730 21:14:59.350661       1 utils.go:199] ID: 25 GRPC request: {}
I0730 21:14:59.350677       1 utils.go:205] ID: 25 GRPC response: {}
I0730 21:15:59.336876       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe
I0730 21:15:59.336917       1 utils.go:199] ID: 26 GRPC request: {}
I0730 21:15:59.336934       1 utils.go:205] ID: 26 GRPC response: {}
I0730 21:16:59.358377       1 utils.go:198] ID: 27 GRPC call: /csi.v1.Identity/Probe
I0730 21:16:59.358455       1 utils.go:199] ID: 27 GRPC request: {}
I0730 21:16:59.358505       1 utils.go:205] ID: 27 GRPC response: {}
I0730 21:17:59.336722       1 utils.go:198] ID: 28 GRPC call: /csi.v1.Identity/Probe
I0730 21:17:59.336779       1 utils.go:199] ID: 28 GRPC request: {}
I0730 21:17:59.336802       1 utils.go:205] ID: 28 GRPC response: {}
I0730 21:18:59.371303       1 utils.go:198] ID: 29 GRPC call: /csi.v1.Identity/Probe
I0730 21:18:59.371354       1 utils.go:199] ID: 29 GRPC request: {}
I0730 21:18:59.371380       1 utils.go:205] ID: 29 GRPC response: {}
I0730 21:19:59.336596       1 utils.go:198] ID: 30 GRPC call: /csi.v1.Identity/Probe
I0730 21:19:59.336649       1 utils.go:199] ID: 30 GRPC request: {}
I0730 21:19:59.336670       1 utils.go:205] ID: 30 GRPC response: {}
I0730 21:20:59.378534       1 utils.go:198] ID: 31 GRPC call: /csi.v1.Identity/Probe
I0730 21:20:59.378626       1 utils.go:199] ID: 31 GRPC request: {}
I0730 21:20:59.378653       1 utils.go:205] ID: 31 GRPC response: {}
I0730 21:21:59.336696       1 utils.go:198] ID: 32 GRPC call: /csi.v1.Identity/Probe
I0730 21:21:59.336753       1 utils.go:199] ID: 32 GRPC request: {}
I0730 21:21:59.336776       1 utils.go:205] ID: 32 GRPC response: {}
I0730 21:22:38.708841       1 utils.go:198] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:22:38.709019       1 utils.go:199] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656","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 21:22:38.709202       1 rbd_util.go:1315] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 21:22:38.710449       1 omap.go:89] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 21:22:38.723466       1 omap.go:159] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656:3eefdd26-c2cb-4208-8baa-8698e98bd2cd])
I0730 21:22:38.731881       1 omap.go:159] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 set omap keys (pool="kube", namespace="", name="csi.volume.3eefdd26-c2cb-4208-8baa-8698e98bd2cd"): map[csi.imagename:csi-vol-3eefdd26-c2cb-4208-8baa-8698e98bd2cd csi.volname:pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 csi.volume.owner:openstack])
I0730 21:22:38.731918       1 rbd_journal.go:491] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3eefdd26-c2cb-4208-8baa-8698e98bd2cd) and image name (csi-vol-3eefdd26-c2cb-4208-8baa-8698e98bd2cd) for request name (pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656)
I0730 21:22:38.732016       1 rbd_util.go:423] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 rbd: create kube/csi-vol-3eefdd26-c2cb-4208-8baa-8698e98bd2cd size 10240M (features: [layering]) using mon 10.96.240.200
I0730 21:22:38.732089       1 rbd_util.go:1563] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 setting image options on kube/csi-vol-3eefdd26-c2cb-4208-8baa-8698e98bd2cd
I0730 21:22:38.766864       1 controllerserver.go:763] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 created image kube/csi-vol-3eefdd26-c2cb-4208-8baa-8698e98bd2cd backed for request name pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656
I0730 21:22:38.795957       1 omap.go:159] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 set omap keys (pool="kube", namespace="", name="csi.volume.3eefdd26-c2cb-4208-8baa-8698e98bd2cd"): map[csi.imageid:37c619334d25])
I0730 21:22:38.895810       1 utils.go:205] ID: 33 Req-ID: pvc-a2c18ff6-9c76-46de-ad81-841e4d02c656 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-3eefdd26-c2cb-4208-8baa-8698e98bd2cd","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3eefdd26-c2cb-4208-8baa-8698e98bd2cd"}}
I0730 21:22:59.388612       1 utils.go:198] ID: 34 GRPC call: /csi.v1.Identity/Probe
I0730 21:22:59.388687       1 utils.go:199] ID: 34 GRPC request: {}
I0730 21:22:59.388721       1 utils.go:205] ID: 34 GRPC response: {}
I0730 21:23:59.336733       1 utils.go:198] ID: 35 GRPC call: /csi.v1.Identity/Probe
I0730 21:23:59.336789       1 utils.go:199] ID: 35 GRPC request: {}
I0730 21:23:59.336811       1 utils.go:205] ID: 35 GRPC response: {}
I0730 21:24:59.395421       1 utils.go:198] ID: 36 GRPC call: /csi.v1.Identity/Probe
I0730 21:24:59.395507       1 utils.go:199] ID: 36 GRPC request: {}
I0730 21:24:59.395534       1 utils.go:205] ID: 36 GRPC response: {}
I0730 21:25:59.350291       1 utils.go:198] ID: 37 GRPC call: /csi.v1.Identity/Probe
I0730 21:25:59.350422       1 utils.go:199] ID: 37 GRPC request: {}
I0730 21:25:59.350471       1 utils.go:205] ID: 37 GRPC response: {}
I0730 21:26:50.802721       1 utils.go:198] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:26:50.802926       1 utils.go:199] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-e0fdda37-1abe-4265-87a1-34befb9872a6","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-e0fdda37-1abe-4265-87a1-34befb9872a6","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-glance-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 21:26:50.803107       1 rbd_util.go:1315] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 21:26:50.809439       1 omap.go:89] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 21:26:50.827966       1 omap.go:159] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-e0fdda37-1abe-4265-87a1-34befb9872a6:db4cd63e-4294-4109-b93e-75924cb486e5])
I0730 21:26:50.836697       1 omap.go:159] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 set omap keys (pool="kube", namespace="", name="csi.volume.db4cd63e-4294-4109-b93e-75924cb486e5"): map[csi.imagename:csi-vol-db4cd63e-4294-4109-b93e-75924cb486e5 csi.volname:pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 csi.volume.owner:openstack])
I0730 21:26:50.836730       1 rbd_journal.go:491] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-db4cd63e-4294-4109-b93e-75924cb486e5) and image name (csi-vol-db4cd63e-4294-4109-b93e-75924cb486e5) for request name (pvc-e0fdda37-1abe-4265-87a1-34befb9872a6)
I0730 21:26:50.836814       1 rbd_util.go:423] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 rbd: create kube/csi-vol-db4cd63e-4294-4109-b93e-75924cb486e5 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 21:26:50.836855       1 rbd_util.go:1563] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 setting image options on kube/csi-vol-db4cd63e-4294-4109-b93e-75924cb486e5
I0730 21:26:50.869713       1 controllerserver.go:763] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 created image kube/csi-vol-db4cd63e-4294-4109-b93e-75924cb486e5 backed for request name pvc-e0fdda37-1abe-4265-87a1-34befb9872a6
I0730 21:26:50.899809       1 omap.go:159] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 set omap keys (pool="kube", namespace="", name="csi.volume.db4cd63e-4294-4109-b93e-75924cb486e5"): map[csi.imageid:37c6f75fd0e8])
I0730 21:26:50.995121       1 utils.go:205] ID: 38 Req-ID: pvc-e0fdda37-1abe-4265-87a1-34befb9872a6 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-db4cd63e-4294-4109-b93e-75924cb486e5","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-db4cd63e-4294-4109-b93e-75924cb486e5"}}
I0730 21:26:59.391794       1 utils.go:198] ID: 39 GRPC call: /csi.v1.Identity/Probe
I0730 21:26:59.391855       1 utils.go:199] ID: 39 GRPC request: {}
I0730 21:26:59.391878       1 utils.go:205] ID: 39 GRPC response: {}
I0730 21:27:59.337513       1 utils.go:198] ID: 40 GRPC call: /csi.v1.Identity/Probe
I0730 21:27:59.337611       1 utils.go:199] ID: 40 GRPC request: {}
I0730 21:27:59.337660       1 utils.go:205] ID: 40 GRPC response: {}
I0730 21:28:59.348930       1 utils.go:198] ID: 41 GRPC call: /csi.v1.Identity/Probe
I0730 21:28:59.348978       1 utils.go:199] ID: 41 GRPC request: {}
I0730 21:28:59.348999       1 utils.go:205] ID: 41 GRPC response: {}
I0730 21:29:59.339025       1 utils.go:198] ID: 42 GRPC call: /csi.v1.Identity/Probe
I0730 21:29:59.339083       1 utils.go:199] ID: 42 GRPC request: {}
I0730 21:29:59.339106       1 utils.go:205] ID: 42 GRPC response: {}
I0730 21:30:59.336710       1 utils.go:198] ID: 43 GRPC call: /csi.v1.Identity/Probe
I0730 21:30:59.336775       1 utils.go:199] ID: 43 GRPC request: {}
I0730 21:30:59.336798       1 utils.go:205] ID: 43 GRPC response: {}
I0730 21:31:59.347907       1 utils.go:198] ID: 44 GRPC call: /csi.v1.Identity/Probe
I0730 21:31:59.347977       1 utils.go:199] ID: 44 GRPC request: {}
I0730 21:31:59.347996       1 utils.go:205] ID: 44 GRPC response: {}
I0730 21:32:59.336733       1 utils.go:198] ID: 45 GRPC call: /csi.v1.Identity/Probe
I0730 21:32:59.336808       1 utils.go:199] ID: 45 GRPC request: {}
I0730 21:32:59.336853       1 utils.go:205] ID: 45 GRPC response: {}
I0730 21:33:59.357739       1 utils.go:198] ID: 46 GRPC call: /csi.v1.Identity/Probe
I0730 21:33:59.357829       1 utils.go:199] ID: 46 GRPC request: {}
I0730 21:33:59.357855       1 utils.go:205] ID: 46 GRPC response: {}
I0730 21:33:59.633464       1 utils.go:198] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:33:59.634519       1 utils.go:199] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 GRPC request: {"capacity_range":{"required_bytes":21474836480},"name":"pvc-c004c368-8d31-4b1e-b156-754691a23f42","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c004c368-8d31-4b1e-b156-754691a23f42","csi.storage.k8s.io/pvc/name":"data-ovn-ovsdb-nb-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 21:33:59.635246       1 rbd_util.go:1315] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 21:33:59.635540       1 utils.go:198] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:33:59.635688       1 utils.go:199] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 GRPC request: {"capacity_range":{"required_bytes":21474836480},"name":"pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67","csi.storage.k8s.io/pvc/name":"data-ovn-ovsdb-sb-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 21:33:59.635820       1 rbd_util.go:1315] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 21:33:59.643927       1 omap.go:89] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 21:33:59.645432       1 omap.go:89] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 21:33:59.665044       1 omap.go:159] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c004c368-8d31-4b1e-b156-754691a23f42:8bc61a1f-e7fa-40ea-9510-5d0b74874709])
I0730 21:33:59.672519       1 omap.go:159] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67:d052f714-5ed0-480e-9527-0e3f4451ef54])
I0730 21:33:59.676212       1 omap.go:159] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 set omap keys (pool="kube", namespace="", name="csi.volume.8bc61a1f-e7fa-40ea-9510-5d0b74874709"): map[csi.imagename:csi-vol-8bc61a1f-e7fa-40ea-9510-5d0b74874709 csi.volname:pvc-c004c368-8d31-4b1e-b156-754691a23f42 csi.volume.owner:openstack])
I0730 21:33:59.676260       1 rbd_journal.go:491] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8bc61a1f-e7fa-40ea-9510-5d0b74874709) and image name (csi-vol-8bc61a1f-e7fa-40ea-9510-5d0b74874709) for request name (pvc-c004c368-8d31-4b1e-b156-754691a23f42)
I0730 21:33:59.676379       1 rbd_util.go:423] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 rbd: create kube/csi-vol-8bc61a1f-e7fa-40ea-9510-5d0b74874709 size 20480M (features: [layering]) using mon 10.96.240.200
I0730 21:33:59.676431       1 rbd_util.go:1563] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 setting image options on kube/csi-vol-8bc61a1f-e7fa-40ea-9510-5d0b74874709
I0730 21:33:59.684014       1 omap.go:159] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 set omap keys (pool="kube", namespace="", name="csi.volume.d052f714-5ed0-480e-9527-0e3f4451ef54"): map[csi.imagename:csi-vol-d052f714-5ed0-480e-9527-0e3f4451ef54 csi.volname:pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 csi.volume.owner:openstack])
I0730 21:33:59.684048       1 rbd_journal.go:491] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d052f714-5ed0-480e-9527-0e3f4451ef54) and image name (csi-vol-d052f714-5ed0-480e-9527-0e3f4451ef54) for request name (pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67)
I0730 21:33:59.684165       1 rbd_util.go:423] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 rbd: create kube/csi-vol-d052f714-5ed0-480e-9527-0e3f4451ef54 size 20480M (features: [layering]) using mon 10.96.240.200
I0730 21:33:59.684190       1 rbd_util.go:1563] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 setting image options on kube/csi-vol-d052f714-5ed0-480e-9527-0e3f4451ef54
I0730 21:33:59.716783       1 controllerserver.go:763] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 created image kube/csi-vol-8bc61a1f-e7fa-40ea-9510-5d0b74874709 backed for request name pvc-c004c368-8d31-4b1e-b156-754691a23f42
I0730 21:33:59.731458       1 controllerserver.go:763] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 created image kube/csi-vol-d052f714-5ed0-480e-9527-0e3f4451ef54 backed for request name pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67
I0730 21:33:59.755989       1 omap.go:159] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 set omap keys (pool="kube", namespace="", name="csi.volume.8bc61a1f-e7fa-40ea-9510-5d0b74874709"): map[csi.imageid:37c66b18c373])
I0730 21:33:59.767724       1 omap.go:159] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 set omap keys (pool="kube", namespace="", name="csi.volume.d052f714-5ed0-480e-9527-0e3f4451ef54"): map[csi.imageid:37c6cf9b2797])
I0730 21:33:59.884386       1 utils.go:205] ID: 47 Req-ID: pvc-c004c368-8d31-4b1e-b156-754691a23f42 GRPC response: {"volume":{"capacity_bytes":21474836480,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-8bc61a1f-e7fa-40ea-9510-5d0b74874709","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8bc61a1f-e7fa-40ea-9510-5d0b74874709"}}
I0730 21:33:59.890913       1 utils.go:205] ID: 48 Req-ID: pvc-3ece5bfc-e59d-412a-8446-fd411d8e9b67 GRPC response: {"volume":{"capacity_bytes":21474836480,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-d052f714-5ed0-480e-9527-0e3f4451ef54","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d052f714-5ed0-480e-9527-0e3f4451ef54"}}
I0730 21:34:12.323187       1 utils.go:198] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:34:12.323497       1 utils.go:199] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-nova-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 21:34:12.323757       1 rbd_util.go:1315] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 21:34:12.325352       1 omap.go:89] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 21:34:12.341383       1 omap.go:159] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d:ebb6473e-e04e-41e9-a94f-bdf6de727bd6])
I0730 21:34:12.348965       1 omap.go:159] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d set omap keys (pool="kube", namespace="", name="csi.volume.ebb6473e-e04e-41e9-a94f-bdf6de727bd6"): map[csi.imagename:csi-vol-ebb6473e-e04e-41e9-a94f-bdf6de727bd6 csi.volname:pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d csi.volume.owner:openstack])
I0730 21:34:12.348999       1 rbd_journal.go:491] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ebb6473e-e04e-41e9-a94f-bdf6de727bd6) and image name (csi-vol-ebb6473e-e04e-41e9-a94f-bdf6de727bd6) for request name (pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d)
I0730 21:34:12.349161       1 rbd_util.go:423] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d rbd: create kube/csi-vol-ebb6473e-e04e-41e9-a94f-bdf6de727bd6 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 21:34:12.349194       1 rbd_util.go:1563] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d setting image options on kube/csi-vol-ebb6473e-e04e-41e9-a94f-bdf6de727bd6
I0730 21:34:12.379431       1 controllerserver.go:763] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d created image kube/csi-vol-ebb6473e-e04e-41e9-a94f-bdf6de727bd6 backed for request name pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d
I0730 21:34:12.415439       1 omap.go:159] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d set omap keys (pool="kube", namespace="", name="csi.volume.ebb6473e-e04e-41e9-a94f-bdf6de727bd6"): map[csi.imageid:37c681a12907])
I0730 21:34:12.533466       1 utils.go:205] ID: 49 Req-ID: pvc-0ab0b4be-e102-48d4-9b77-5c5a245b8d6d GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-ebb6473e-e04e-41e9-a94f-bdf6de727bd6","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ebb6473e-e04e-41e9-a94f-bdf6de727bd6"}}
I0730 21:34:59.336606       1 utils.go:198] ID: 50 GRPC call: /csi.v1.Identity/Probe
I0730 21:34:59.336672       1 utils.go:199] ID: 50 GRPC request: {}
I0730 21:34:59.336699       1 utils.go:205] ID: 50 GRPC response: {}
I0730 21:35:59.368088       1 utils.go:198] ID: 51 GRPC call: /csi.v1.Identity/Probe
I0730 21:35:59.368180       1 utils.go:199] ID: 51 GRPC request: {}
I0730 21:35:59.368226       1 utils.go:205] ID: 51 GRPC response: {}
I0730 21:36:59.336547       1 utils.go:198] ID: 52 GRPC call: /csi.v1.Identity/Probe
I0730 21:36:59.336640       1 utils.go:199] ID: 52 GRPC request: {}
I0730 21:36:59.336667       1 utils.go:205] ID: 52 GRPC response: {}
I0730 21:37:59.377363       1 utils.go:198] ID: 53 GRPC call: /csi.v1.Identity/Probe
I0730 21:37:59.378144       1 utils.go:199] ID: 53 GRPC request: {}
I0730 21:37:59.378214       1 utils.go:205] ID: 53 GRPC response: {}
I0730 21:38:59.336667       1 utils.go:198] ID: 54 GRPC call: /csi.v1.Identity/Probe
I0730 21:38:59.336721       1 utils.go:199] ID: 54 GRPC request: {}
I0730 21:38:59.336742       1 utils.go:205] ID: 54 GRPC response: {}
I0730 21:39:59.369455       1 utils.go:198] ID: 55 GRPC call: /csi.v1.Identity/Probe
I0730 21:39:59.369506       1 utils.go:199] ID: 55 GRPC request: {}
I0730 21:39:59.369554       1 utils.go:205] ID: 55 GRPC response: {}
I0730 21:40:59.387294       1 utils.go:198] ID: 56 GRPC call: /csi.v1.Identity/Probe
I0730 21:40:59.387354       1 utils.go:199] ID: 56 GRPC request: {}
I0730 21:40:59.387377       1 utils.go:205] ID: 56 GRPC response: {}
I0730 21:41:40.546255       1 utils.go:198] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be GRPC call: /csi.v1.Controller/CreateVolume
I0730 21:41:40.546521       1 utils.go:199] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-c765b14c-fbdb-46eb-959e-5225a369e0be","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c765b14c-fbdb-46eb-959e-5225a369e0be","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-neutron-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 21:41:40.546873       1 rbd_util.go:1315] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 21:41:40.548800       1 omap.go:89] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 21:41:40.567025       1 omap.go:159] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c765b14c-fbdb-46eb-959e-5225a369e0be:5b6e9fde-2872-49ce-98df-72eddce35856])
I0730 21:41:40.575212       1 omap.go:159] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be set omap keys (pool="kube", namespace="", name="csi.volume.5b6e9fde-2872-49ce-98df-72eddce35856"): map[csi.imagename:csi-vol-5b6e9fde-2872-49ce-98df-72eddce35856 csi.volname:pvc-c765b14c-fbdb-46eb-959e-5225a369e0be csi.volume.owner:openstack])
I0730 21:41:40.575252       1 rbd_journal.go:491] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5b6e9fde-2872-49ce-98df-72eddce35856) and image name (csi-vol-5b6e9fde-2872-49ce-98df-72eddce35856) for request name (pvc-c765b14c-fbdb-46eb-959e-5225a369e0be)
I0730 21:41:40.575484       1 rbd_util.go:423] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be rbd: create kube/csi-vol-5b6e9fde-2872-49ce-98df-72eddce35856 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 21:41:40.575616       1 rbd_util.go:1563] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be setting image options on kube/csi-vol-5b6e9fde-2872-49ce-98df-72eddce35856
I0730 21:41:40.608395       1 controllerserver.go:763] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be created image kube/csi-vol-5b6e9fde-2872-49ce-98df-72eddce35856 backed for request name pvc-c765b14c-fbdb-46eb-959e-5225a369e0be
I0730 21:41:40.645623       1 omap.go:159] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be set omap keys (pool="kube", namespace="", name="csi.volume.5b6e9fde-2872-49ce-98df-72eddce35856"): map[csi.imageid:37c6b53219e4])
I0730 21:41:40.753950       1 utils.go:205] ID: 57 Req-ID: pvc-c765b14c-fbdb-46eb-959e-5225a369e0be GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-5b6e9fde-2872-49ce-98df-72eddce35856","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5b6e9fde-2872-49ce-98df-72eddce35856"}}
I0730 21:41:59.387426       1 utils.go:198] ID: 58 GRPC call: /csi.v1.Identity/Probe
I0730 21:41:59.387491       1 utils.go:199] ID: 58 GRPC request: {}
I0730 21:41:59.387526       1 utils.go:205] ID: 58 GRPC response: {}
I0730 21:42:59.337559       1 utils.go:198] ID: 59 GRPC call: /csi.v1.Identity/Probe
I0730 21:42:59.337655       1 utils.go:199] ID: 59 GRPC request: {}
I0730 21:42:59.337731       1 utils.go:205] ID: 59 GRPC response: {}
I0730 21:43:59.341061       1 utils.go:198] ID: 60 GRPC call: /csi.v1.Identity/Probe
I0730 21:43:59.341126       1 utils.go:199] ID: 60 GRPC request: {}
I0730 21:43:59.341160       1 utils.go:205] ID: 60 GRPC response: {}
I0730 21:44:59.337112       1 utils.go:198] ID: 61 GRPC call: /csi.v1.Identity/Probe
I0730 21:44:59.337172       1 utils.go:199] ID: 61 GRPC request: {}
I0730 21:44:59.337193       1 utils.go:205] ID: 61 GRPC response: {}
I0730 21:45:59.336799       1 utils.go:198] ID: 62 GRPC call: /csi.v1.Identity/Probe
I0730 21:45:59.336868       1 utils.go:199] ID: 62 GRPC request: {}
I0730 21:45:59.336894       1 utils.go:205] ID: 62 GRPC response: {}
I0730 21:46:59.347608       1 utils.go:198] ID: 63 GRPC call: /csi.v1.Identity/Probe
I0730 21:46:59.347663       1 utils.go:199] ID: 63 GRPC request: {}
I0730 21:46:59.347685       1 utils.go:205] ID: 63 GRPC response: {}
I0730 21:47:59.336783       1 utils.go:198] ID: 64 GRPC call: /csi.v1.Identity/Probe
I0730 21:47:59.336840       1 utils.go:199] ID: 64 GRPC request: {}
I0730 21:47:59.336862       1 utils.go:205] ID: 64 GRPC response: {}
I0730 21:48:59.352667       1 utils.go:198] ID: 65 GRPC call: /csi.v1.Identity/Probe
I0730 21:48:59.352718       1 utils.go:199] ID: 65 GRPC request: {}
I0730 21:48:59.352746       1 utils.go:205] ID: 65 GRPC response: {}
I0730 21:49:59.337124       1 utils.go:198] ID: 66 GRPC call: /csi.v1.Identity/Probe
I0730 21:49:59.337175       1 utils.go:199] ID: 66 GRPC request: {}
I0730 21:49:59.337197       1 utils.go:205] ID: 66 GRPC response: {}
I0730 21:50:59.393158       1 utils.go:198] ID: 67 GRPC call: /csi.v1.Identity/Probe
I0730 21:50:59.393212       1 utils.go:199] ID: 67 GRPC request: {}
I0730 21:50:59.393233       1 utils.go:205] ID: 67 GRPC response: {}
I0730 21:51:59.347372       1 utils.go:198] ID: 68 GRPC call: /csi.v1.Identity/Probe
I0730 21:51:59.347432       1 utils.go:199] ID: 68 GRPC request: {}
I0730 21:51:59.347488       1 utils.go:205] ID: 68 GRPC response: {}
I0730 21:52:59.336641       1 utils.go:198] ID: 69 GRPC call: /csi.v1.Identity/Probe
I0730 21:52:59.336783       1 utils.go:199] ID: 69 GRPC request: {}
I0730 21:52:59.336833       1 utils.go:205] ID: 69 GRPC response: {}
I0730 21:53:59.357330       1 utils.go:198] ID: 70 GRPC call: /csi.v1.Identity/Probe
I0730 21:53:59.357395       1 utils.go:199] ID: 70 GRPC request: {}
I0730 21:53:59.357423       1 utils.go:205] ID: 70 GRPC response: {}
I0730 21:54:59.336592       1 utils.go:198] ID: 71 GRPC call: /csi.v1.Identity/Probe
I0730 21:54:59.336650       1 utils.go:199] ID: 71 GRPC request: {}
I0730 21:54:59.336672       1 utils.go:205] ID: 71 GRPC response: {}
I0730 21:55:59.363378       1 utils.go:198] ID: 72 GRPC call: /csi.v1.Identity/Probe
I0730 21:55:59.363460       1 utils.go:199] ID: 72 GRPC request: {}
I0730 21:55:59.363523       1 utils.go:205] ID: 72 GRPC response: {}
I0730 21:56:59.336713       1 utils.go:198] ID: 73 GRPC call: /csi.v1.Identity/Probe
I0730 21:56:59.336779       1 utils.go:199] ID: 73 GRPC request: {}
I0730 21:56:59.336802       1 utils.go:205] ID: 73 GRPC response: {}
I0730 21:57:59.368318       1 utils.go:198] ID: 74 GRPC call: /csi.v1.Identity/Probe
I0730 21:57:59.368430       1 utils.go:199] ID: 74 GRPC request: {}
I0730 21:57:59.368667       1 utils.go:205] ID: 74 GRPC response: {}
I0730 21:58:59.337403       1 utils.go:198] ID: 75 GRPC call: /csi.v1.Identity/Probe
I0730 21:58:59.337481       1 utils.go:199] ID: 75 GRPC request: {}
I0730 21:58:59.337517       1 utils.go:205] ID: 75 GRPC response: {}
I0730 21:59:59.380685       1 utils.go:198] ID: 76 GRPC call: /csi.v1.Identity/Probe
I0730 21:59:59.380750       1 utils.go:199] ID: 76 GRPC request: {}
I0730 21:59:59.380769       1 utils.go:205] ID: 76 GRPC response: {}
I0730 22:00:59.392719       1 utils.go:198] ID: 77 GRPC call: /csi.v1.Identity/Probe
I0730 22:00:59.392773       1 utils.go:199] ID: 77 GRPC request: {}
I0730 22:00:59.392796       1 utils.go:205] ID: 77 GRPC response: {}
I0730 22:01:59.388293       1 utils.go:198] ID: 78 GRPC call: /csi.v1.Identity/Probe
I0730 22:01:59.388401       1 utils.go:199] ID: 78 GRPC request: {}
I0730 22:01:59.388431       1 utils.go:205] ID: 78 GRPC response: {}
I0730 22:02:59.336600       1 utils.go:198] ID: 79 GRPC call: /csi.v1.Identity/Probe
I0730 22:02:59.336650       1 utils.go:199] ID: 79 GRPC request: {}
I0730 22:02:59.336671       1 utils.go:205] ID: 79 GRPC response: {}
I0730 22:03:59.398811       1 utils.go:198] ID: 80 GRPC call: /csi.v1.Identity/Probe
I0730 22:03:59.398876       1 utils.go:199] ID: 80 GRPC request: {}
I0730 22:03:59.398904       1 utils.go:205] ID: 80 GRPC response: {}
I0730 22:04:59.396203       1 utils.go:198] ID: 81 GRPC call: /csi.v1.Identity/Probe
I0730 22:04:59.396256       1 utils.go:199] ID: 81 GRPC request: {}
I0730 22:04:59.396280       1 utils.go:205] ID: 81 GRPC response: {}