I0730 18:57:58.090478       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0730 18:57:58.090598       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0730 18:57:58.090608       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0730 18:57:58.090611       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0730 18:57:58.090614       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0730 18:57:58.090617       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0730 18:57:58.090620       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0730 18:57:58.090622       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0730 18:57:58.090625       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0730 18:57:58.090628       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0730 18:57:58.090897       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0730 18:57:58.090962       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0730 18:58:09.875069       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:09.877111       1 utils.go:199] ID: 1 GRPC request: {}
I0730 18:58:09.877188       1 utils.go:205] ID: 1 GRPC response: {}
I0730 18:58:09.881583       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:58:09.881629       1 utils.go:199] ID: 2 GRPC request: {}
I0730 18:58:09.881637       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0730 18:58:09.881681       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:58:09.883393       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:09.883412       1 utils.go:199] ID: 3 GRPC request: {}
I0730 18:58:09.883428       1 utils.go:205] ID: 3 GRPC response: {}
I0730 18:58:09.884031       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 18:58:09.884146       1 utils.go:199] ID: 4 GRPC request: {}
I0730 18:58:09.884390       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 18:58:09.885496       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:09.885540       1 utils.go:199] ID: 5 GRPC request: {}
I0730 18:58:09.885551       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0730 18:58:09.885666       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:58:11.647827       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:11.647913       1 utils.go:199] ID: 6 GRPC request: {}
I0730 18:58:11.647961       1 utils.go:205] ID: 6 GRPC response: {}
I0730 18:58:11.651006       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:58:11.651077       1 utils.go:199] ID: 7 GRPC request: {}
I0730 18:58:11.651112       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0730 18:58:11.651178       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:58:11.655584       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:11.655651       1 utils.go:199] ID: 8 GRPC request: {}
I0730 18:58:11.655682       1 utils.go:205] ID: 8 GRPC response: {}
I0730 18:58:11.656490       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 18:58:11.656538       1 utils.go:199] ID: 9 GRPC request: {}
I0730 18:58:11.656684       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 18:58:11.657598       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:11.657629       1 utils.go:199] ID: 10 GRPC request: {}
I0730 18:58:11.657642       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0730 18:58:11.657792       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:58:11.658515       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:11.658537       1 utils.go:199] ID: 11 GRPC request: {}
I0730 18:58:11.658542       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0730 18:58:11.658618       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:58:11.659027       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:11.659051       1 utils.go:199] ID: 12 GRPC request: {}
I0730 18:58:11.659060       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0730 18:58:11.659122       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:13.331929       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:58:13.331965       1 utils.go:199] ID: 13 GRPC request: {}
I0730 18:58:13.331974       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0730 18:58:13.331999       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:58:13.336366       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:13.336394       1 utils.go:199] ID: 14 GRPC request: {}
I0730 18:58:13.336410       1 utils.go:205] ID: 14 GRPC response: {}
I0730 18:58:13.336998       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:13.337060       1 utils.go:199] ID: 15 GRPC request: {}
I0730 18:58:13.337075       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0730 18:58:13.337187       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:16.544791       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:16.544838       1 utils.go:199] ID: 16 GRPC request: {}
I0730 18:58:16.544859       1 utils.go:205] ID: 16 GRPC response: {}
I0730 18:58:16.547885       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 18:58:16.547927       1 utils.go:199] ID: 17 GRPC request: {}
I0730 18:58:16.547936       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0730 18:58:16.547962       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 18:58:16.551891       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0730 18:58:16.551926       1 utils.go:199] ID: 18 GRPC request: {}
I0730 18:58:16.551941       1 utils.go:205] ID: 18 GRPC response: {}
I0730 18:58:16.552437       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 18:58:16.552476       1 utils.go:199] ID: 19 GRPC request: {}
I0730 18:58:16.552572       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 18:58:16.553447       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 18:58:16.553589       1 utils.go:199] ID: 20 GRPC request: {}
I0730 18:58:16.553611       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0730 18:58:16.553928       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:59:15.841295       1 utils.go:198] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 GRPC call: /csi.v1.Controller/CreateVolume
I0730 18:59:15.841753       1 utils.go:199] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 GRPC request: {"capacity_range":{"required_bytes":171798691840},"name":"pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7","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:59:15.842127       1 rbd_util.go:1315] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0730 18:59:15.889871       1 omap.go:80] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0730 18:59:15.900464       1 omap.go:159] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7:b4c0906c-aa66-41ef-abf4-2b53a6d64f1e])
I0730 18:59:15.918397       1 omap.go:159] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 set omap keys (pool="kube", namespace="", name="csi.volume.b4c0906c-aa66-41ef-abf4-2b53a6d64f1e"): map[csi.imagename:csi-vol-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e csi.volname:pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 csi.volume.owner:openstack])
I0730 18:59:15.918437       1 rbd_journal.go:491] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e) and image name (csi-vol-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e) for request name (pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7)
I0730 18:59:15.918542       1 rbd_util.go:423] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 rbd: create kube/csi-vol-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e size 163840M (features: [layering]) using mon 10.96.240.200
I0730 18:59:15.918591       1 rbd_util.go:1563] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 setting image options on kube/csi-vol-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e
I0730 18:59:15.955830       1 controllerserver.go:763] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 created image kube/csi-vol-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e backed for request name pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7
I0730 18:59:15.991678       1 omap.go:159] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 set omap keys (pool="kube", namespace="", name="csi.volume.b4c0906c-aa66-41ef-abf4-2b53a6d64f1e"): map[csi.imageid:37c69d09dece])
I0730 18:59:16.083976       1 utils.go:205] ID: 21 Req-ID: pvc-0f7246fb-3e0b-4795-b468-db2eec9c7fd7 GRPC response: {"volume":{"capacity_bytes":171798691840,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b4c0906c-aa66-41ef-abf4-2b53a6d64f1e"}}
I0730 18:59:16.782365       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0730 18:59:16.782422       1 utils.go:199] ID: 22 GRPC request: {}
I0730 18:59:16.782443       1 utils.go:205] ID: 22 GRPC response: {}
I0730 19:00:16.740450       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0730 19:00:16.740575       1 utils.go:199] ID: 23 GRPC request: {}
I0730 19:00:16.740610       1 utils.go:205] ID: 23 GRPC response: {}
I0730 19:01:16.788840       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0730 19:01:16.788877       1 utils.go:199] ID: 24 GRPC request: {}
I0730 19:01:16.788892       1 utils.go:205] ID: 24 GRPC response: {}
I0730 19:02:16.745128       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0730 19:02:16.745179       1 utils.go:199] ID: 25 GRPC request: {}
I0730 19:02:16.745195       1 utils.go:205] ID: 25 GRPC response: {}
I0730 19:03:16.733374       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe
I0730 19:03:16.733450       1 utils.go:199] ID: 26 GRPC request: {}
I0730 19:03:16.733478       1 utils.go:205] ID: 26 GRPC response: {}
I0730 19:04:16.754674       1 utils.go:198] ID: 27 GRPC call: /csi.v1.Identity/Probe
I0730 19:04:16.754856       1 utils.go:199] ID: 27 GRPC request: {}
I0730 19:04:16.754935       1 utils.go:205] ID: 27 GRPC response: {}
I0730 19:05:16.733033       1 utils.go:198] ID: 28 GRPC call: /csi.v1.Identity/Probe
I0730 19:05:16.733088       1 utils.go:199] ID: 28 GRPC request: {}
I0730 19:05:16.733110       1 utils.go:205] ID: 28 GRPC response: {}
I0730 19:06:16.764465       1 utils.go:198] ID: 29 GRPC call: /csi.v1.Identity/Probe
I0730 19:06:16.764535       1 utils.go:199] ID: 29 GRPC request: {}
I0730 19:06:16.764558       1 utils.go:205] ID: 29 GRPC response: {}
I0730 19:07:16.733254       1 utils.go:198] ID: 30 GRPC call: /csi.v1.Identity/Probe
I0730 19:07:16.733300       1 utils.go:199] ID: 30 GRPC request: {}
I0730 19:07:16.733323       1 utils.go:205] ID: 30 GRPC response: {}
I0730 19:08:16.777100       1 utils.go:198] ID: 31 GRPC call: /csi.v1.Identity/Probe
I0730 19:08:16.777157       1 utils.go:199] ID: 31 GRPC request: {}
I0730 19:08:16.777195       1 utils.go:205] ID: 31 GRPC response: {}
I0730 19:09:16.733144       1 utils.go:198] ID: 32 GRPC call: /csi.v1.Identity/Probe
I0730 19:09:16.733188       1 utils.go:199] ID: 32 GRPC request: {}
I0730 19:09:16.733203       1 utils.go:205] ID: 32 GRPC response: {}
I0730 19:10:16.784597       1 utils.go:198] ID: 33 GRPC call: /csi.v1.Identity/Probe
I0730 19:10:16.784644       1 utils.go:199] ID: 33 GRPC request: {}
I0730 19:10:16.784665       1 utils.go:205] ID: 33 GRPC response: {}
I0730 19:11:16.788842       1 utils.go:198] ID: 34 GRPC call: /csi.v1.Identity/Probe
I0730 19:11:16.788899       1 utils.go:199] ID: 34 GRPC request: {}
I0730 19:11:16.788921       1 utils.go:205] ID: 34 GRPC response: {}
I0730 19:11:20.352437       1 utils.go:198] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:11:20.353620       1 utils.go:199] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-6723e2dd-512d-484f-9c55-97fa083797fc","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-6723e2dd-512d-484f-9c55-97fa083797fc","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:11:20.356805       1 rbd_util.go:1315] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:11:20.358696       1 omap.go:89] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:11:20.388529       1 omap.go:159] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-6723e2dd-512d-484f-9c55-97fa083797fc:352a3e27-8022-4fde-a466-b23a2d91bddf])
I0730 19:11:20.401717       1 omap.go:159] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc set omap keys (pool="kube", namespace="", name="csi.volume.352a3e27-8022-4fde-a466-b23a2d91bddf"): map[csi.imagename:csi-vol-352a3e27-8022-4fde-a466-b23a2d91bddf csi.volname:pvc-6723e2dd-512d-484f-9c55-97fa083797fc csi.volume.owner:openstack])
I0730 19:11:20.401825       1 rbd_journal.go:491] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-352a3e27-8022-4fde-a466-b23a2d91bddf) and image name (csi-vol-352a3e27-8022-4fde-a466-b23a2d91bddf) for request name (pvc-6723e2dd-512d-484f-9c55-97fa083797fc)
I0730 19:11:20.401973       1 rbd_util.go:423] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc rbd: create kube/csi-vol-352a3e27-8022-4fde-a466-b23a2d91bddf size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:11:20.402058       1 rbd_util.go:1563] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc setting image options on kube/csi-vol-352a3e27-8022-4fde-a466-b23a2d91bddf
I0730 19:11:20.440808       1 controllerserver.go:763] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc created image kube/csi-vol-352a3e27-8022-4fde-a466-b23a2d91bddf backed for request name pvc-6723e2dd-512d-484f-9c55-97fa083797fc
I0730 19:11:20.476784       1 omap.go:159] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc set omap keys (pool="kube", namespace="", name="csi.volume.352a3e27-8022-4fde-a466-b23a2d91bddf"): map[csi.imageid:37c649dea490])
I0730 19:11:20.597099       1 utils.go:205] ID: 35 Req-ID: pvc-6723e2dd-512d-484f-9c55-97fa083797fc GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-352a3e27-8022-4fde-a466-b23a2d91bddf","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-352a3e27-8022-4fde-a466-b23a2d91bddf"}}
I0730 19:12:16.791884       1 utils.go:198] ID: 36 GRPC call: /csi.v1.Identity/Probe
I0730 19:12:16.791990       1 utils.go:199] ID: 36 GRPC request: {}
I0730 19:12:16.792022       1 utils.go:205] ID: 36 GRPC response: {}
I0730 19:13:16.738885       1 utils.go:198] ID: 37 GRPC call: /csi.v1.Identity/Probe
I0730 19:13:16.738939       1 utils.go:199] ID: 37 GRPC request: {}
I0730 19:13:16.738961       1 utils.go:205] ID: 37 GRPC response: {}
I0730 19:14:16.788919       1 utils.go:198] ID: 38 GRPC call: /csi.v1.Identity/Probe
I0730 19:14:16.788963       1 utils.go:199] ID: 38 GRPC request: {}
I0730 19:14:16.788978       1 utils.go:205] ID: 38 GRPC response: {}
I0730 19:15:16.040546       1 utils.go:198] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:15:16.040717       1 utils.go:199] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253","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:15:16.040852       1 rbd_util.go:1315] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:15:16.043129       1 omap.go:89] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:15:16.055548       1 omap.go:159] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253:67ccad29-bc42-4b57-8924-53d836d4ca3b])
I0730 19:15:16.065499       1 omap.go:159] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 set omap keys (pool="kube", namespace="", name="csi.volume.67ccad29-bc42-4b57-8924-53d836d4ca3b"): map[csi.imagename:csi-vol-67ccad29-bc42-4b57-8924-53d836d4ca3b csi.volname:pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 csi.volume.owner:openstack])
I0730 19:15:16.065536       1 rbd_journal.go:491] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-67ccad29-bc42-4b57-8924-53d836d4ca3b) and image name (csi-vol-67ccad29-bc42-4b57-8924-53d836d4ca3b) for request name (pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253)
I0730 19:15:16.065619       1 rbd_util.go:423] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 rbd: create kube/csi-vol-67ccad29-bc42-4b57-8924-53d836d4ca3b size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:15:16.065671       1 rbd_util.go:1563] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 setting image options on kube/csi-vol-67ccad29-bc42-4b57-8924-53d836d4ca3b
I0730 19:15:16.101993       1 controllerserver.go:763] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 created image kube/csi-vol-67ccad29-bc42-4b57-8924-53d836d4ca3b backed for request name pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253
I0730 19:15:16.132952       1 omap.go:159] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 set omap keys (pool="kube", namespace="", name="csi.volume.67ccad29-bc42-4b57-8924-53d836d4ca3b"): map[csi.imageid:37c632165c25])
I0730 19:15:16.227881       1 utils.go:205] ID: 39 Req-ID: pvc-b9075cd6-114f-4912-9bd2-de1f1f19f253 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-67ccad29-bc42-4b57-8924-53d836d4ca3b","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-67ccad29-bc42-4b57-8924-53d836d4ca3b"}}
I0730 19:15:16.733610       1 utils.go:198] ID: 40 GRPC call: /csi.v1.Identity/Probe
I0730 19:15:16.733664       1 utils.go:199] ID: 40 GRPC request: {}
I0730 19:15:16.733685       1 utils.go:205] ID: 40 GRPC response: {}
I0730 19:16:16.733766       1 utils.go:198] ID: 41 GRPC call: /csi.v1.Identity/Probe
I0730 19:16:16.733819       1 utils.go:199] ID: 41 GRPC request: {}
I0730 19:16:16.733840       1 utils.go:205] ID: 41 GRPC response: {}
I0730 19:17:16.749337       1 utils.go:198] ID: 42 GRPC call: /csi.v1.Identity/Probe
I0730 19:17:16.749509       1 utils.go:199] ID: 42 GRPC request: {}
I0730 19:17:16.749575       1 utils.go:205] ID: 42 GRPC response: {}
I0730 19:18:16.733087       1 utils.go:198] ID: 43 GRPC call: /csi.v1.Identity/Probe
I0730 19:18:16.733121       1 utils.go:199] ID: 43 GRPC request: {}
I0730 19:18:16.733134       1 utils.go:205] ID: 43 GRPC response: {}
I0730 19:18:20.807596       1 utils.go:198] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:18:20.808058       1 utils.go:199] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-586575a7-d221-44bf-9161-ae099b23a145","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-586575a7-d221-44bf-9161-ae099b23a145","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 19:18:20.808414       1 rbd_util.go:1315] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:18:20.810174       1 omap.go:89] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:18:20.825236       1 omap.go:159] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-586575a7-d221-44bf-9161-ae099b23a145:373c0816-d607-4164-8fdf-4fa2d5f8c820])
I0730 19:18:20.834656       1 omap.go:159] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 set omap keys (pool="kube", namespace="", name="csi.volume.373c0816-d607-4164-8fdf-4fa2d5f8c820"): map[csi.imagename:csi-vol-373c0816-d607-4164-8fdf-4fa2d5f8c820 csi.volname:pvc-586575a7-d221-44bf-9161-ae099b23a145 csi.volume.owner:openstack])
I0730 19:18:20.834704       1 rbd_journal.go:491] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-373c0816-d607-4164-8fdf-4fa2d5f8c820) and image name (csi-vol-373c0816-d607-4164-8fdf-4fa2d5f8c820) for request name (pvc-586575a7-d221-44bf-9161-ae099b23a145)
I0730 19:18:20.834809       1 rbd_util.go:423] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 rbd: create kube/csi-vol-373c0816-d607-4164-8fdf-4fa2d5f8c820 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:18:20.834872       1 rbd_util.go:1563] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 setting image options on kube/csi-vol-373c0816-d607-4164-8fdf-4fa2d5f8c820
I0730 19:18:20.862546       1 controllerserver.go:763] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 created image kube/csi-vol-373c0816-d607-4164-8fdf-4fa2d5f8c820 backed for request name pvc-586575a7-d221-44bf-9161-ae099b23a145
I0730 19:18:20.893355       1 omap.go:159] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 set omap keys (pool="kube", namespace="", name="csi.volume.373c0816-d607-4164-8fdf-4fa2d5f8c820"): map[csi.imageid:37c644af09f0])
I0730 19:18:20.976083       1 utils.go:205] ID: 44 Req-ID: pvc-586575a7-d221-44bf-9161-ae099b23a145 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-373c0816-d607-4164-8fdf-4fa2d5f8c820","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-373c0816-d607-4164-8fdf-4fa2d5f8c820"}}
I0730 19:19:16.757082       1 utils.go:198] ID: 45 GRPC call: /csi.v1.Identity/Probe
I0730 19:19:16.757180       1 utils.go:199] ID: 45 GRPC request: {}
I0730 19:19:16.757208       1 utils.go:205] ID: 45 GRPC response: {}
I0730 19:20:16.789521       1 utils.go:198] ID: 46 GRPC call: /csi.v1.Identity/Probe
I0730 19:20:16.789588       1 utils.go:199] ID: 46 GRPC request: {}
I0730 19:20:16.789618       1 utils.go:205] ID: 46 GRPC response: {}
I0730 19:21:16.743187       1 utils.go:198] ID: 47 GRPC call: /csi.v1.Identity/Probe
I0730 19:21:16.743242       1 utils.go:199] ID: 47 GRPC request: {}
I0730 19:21:16.743264       1 utils.go:205] ID: 47 GRPC response: {}
I0730 19:22:16.733538       1 utils.go:198] ID: 48 GRPC call: /csi.v1.Identity/Probe
I0730 19:22:16.733594       1 utils.go:199] ID: 48 GRPC request: {}
I0730 19:22:16.733615       1 utils.go:205] ID: 48 GRPC response: {}
I0730 19:23:16.732993       1 utils.go:198] ID: 49 GRPC call: /csi.v1.Identity/Probe
I0730 19:23:16.733031       1 utils.go:199] ID: 49 GRPC request: {}
I0730 19:23:16.733042       1 utils.go:205] ID: 49 GRPC response: {}
I0730 19:24:16.743631       1 utils.go:198] ID: 50 GRPC call: /csi.v1.Identity/Probe
I0730 19:24:16.743699       1 utils.go:199] ID: 50 GRPC request: {}
I0730 19:24:16.743722       1 utils.go:205] ID: 50 GRPC response: {}
I0730 19:25:16.761014       1 utils.go:198] ID: 51 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:16.761094       1 utils.go:199] ID: 51 GRPC request: {}
I0730 19:25:16.761124       1 utils.go:205] ID: 51 GRPC response: {}
I0730 19:25:27.225060       1 utils.go:198] ID: 52 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:27.225122       1 utils.go:199] ID: 52 GRPC request: {}
I0730 19:25:27.225143       1 utils.go:205] ID: 52 GRPC response: {}
I0730 19:25:27.229026       1 utils.go:198] ID: 53 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 19:25:27.229047       1 utils.go:199] ID: 53 GRPC request: {}
I0730 19:25:27.229056       1 identityserver-default.go:40] ID: 53 Using default GetPluginInfo
I0730 19:25:27.229089       1 utils.go:205] ID: 53 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 19:25:27.231341       1 utils.go:198] ID: 54 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:27.231362       1 utils.go:199] ID: 54 GRPC request: {}
I0730 19:25:27.231372       1 utils.go:205] ID: 54 GRPC response: {}
I0730 19:25:27.231851       1 utils.go:198] ID: 55 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 19:25:27.231873       1 utils.go:199] ID: 55 GRPC request: {}
I0730 19:25:27.231968       1 utils.go:205] ID: 55 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 19:25:27.233046       1 utils.go:198] ID: 56 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 19:25:27.233065       1 utils.go:199] ID: 56 GRPC request: {}
I0730 19:25:27.233076       1 controllerserver-default.go:42] ID: 56 Using default ControllerGetCapabilities
I0730 19:25:27.233151       1 utils.go:205] ID: 56 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0730 19:25:27.332613       1 utils.go:198] ID: 57 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:27.332671       1 utils.go:199] ID: 57 GRPC request: {}
I0730 19:25:27.332690       1 utils.go:205] ID: 57 GRPC response: {}
I0730 19:25:27.337544       1 utils.go:198] ID: 58 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 19:25:27.337595       1 utils.go:199] ID: 58 GRPC request: {}
I0730 19:25:27.337608       1 identityserver-default.go:40] ID: 58 Using default GetPluginInfo
I0730 19:25:27.337639       1 utils.go:205] ID: 58 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 19:25:27.339938       1 utils.go:198] ID: 59 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:27.339961       1 utils.go:199] ID: 59 GRPC request: {}
I0730 19:25:27.339978       1 utils.go:205] ID: 59 GRPC response: {}
I0730 19:25:27.340392       1 utils.go:198] ID: 60 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 19:25:27.340429       1 utils.go:199] ID: 60 GRPC request: {}
I0730 19:25:27.340518       1 utils.go:205] ID: 60 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 19:25:27.341626       1 utils.go:198] ID: 61 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 19:25:27.341709       1 utils.go:199] ID: 61 GRPC request: {}
I0730 19:25:27.341747       1 controllerserver-default.go:42] ID: 61 Using default ControllerGetCapabilities
I0730 19:25:27.341951       1 utils.go:205] ID: 61 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0730 19:25:28.111169       1 utils.go:198] ID: 62 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:28.111215       1 utils.go:199] ID: 62 GRPC request: {}
I0730 19:25:28.111233       1 utils.go:205] ID: 62 GRPC response: {}
I0730 19:25:28.113345       1 utils.go:198] ID: 63 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 19:25:28.113364       1 utils.go:199] ID: 63 GRPC request: {}
I0730 19:25:28.113373       1 identityserver-default.go:40] ID: 63 Using default GetPluginInfo
I0730 19:25:28.113397       1 utils.go:205] ID: 63 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 19:25:28.117419       1 utils.go:198] ID: 64 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:28.117482       1 utils.go:199] ID: 64 GRPC request: {}
I0730 19:25:28.117511       1 utils.go:205] ID: 64 GRPC response: {}
I0730 19:25:28.118897       1 utils.go:198] ID: 65 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 19:25:28.118953       1 utils.go:199] ID: 65 GRPC request: {}
I0730 19:25:28.119077       1 utils.go:205] ID: 65 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 19:25:28.119836       1 utils.go:198] ID: 66 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 19:25:28.119870       1 utils.go:199] ID: 66 GRPC request: {}
I0730 19:25:28.119889       1 controllerserver-default.go:42] ID: 66 Using default ControllerGetCapabilities
I0730 19:25:28.120024       1 utils.go:205] ID: 66 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0730 19:25:28.121075       1 utils.go:198] ID: 67 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 19:25:28.121096       1 utils.go:199] ID: 67 GRPC request: {}
I0730 19:25:28.121106       1 controllerserver-default.go:42] ID: 67 Using default ControllerGetCapabilities
I0730 19:25:28.121162       1 utils.go:205] ID: 67 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0730 19:25:28.121664       1 utils.go:198] ID: 68 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 19:25:28.121697       1 utils.go:199] ID: 68 GRPC request: {}
I0730 19:25:28.121713       1 controllerserver-default.go:42] ID: 68 Using default ControllerGetCapabilities
I0730 19:25:28.121827       1 utils.go:205] ID: 68 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0730 19:25:28.212731       1 utils.go:198] ID: 69 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 19:25:28.212788       1 utils.go:199] ID: 69 GRPC request: {}
I0730 19:25:28.212804       1 identityserver-default.go:40] ID: 69 Using default GetPluginInfo
I0730 19:25:28.212846       1 utils.go:205] ID: 69 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 19:25:28.214532       1 utils.go:198] ID: 70 GRPC call: /csi.v1.Identity/Probe
I0730 19:25:28.214563       1 utils.go:199] ID: 70 GRPC request: {}
I0730 19:25:28.214582       1 utils.go:205] ID: 70 GRPC response: {}
I0730 19:25:28.214986       1 utils.go:198] ID: 71 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 19:25:28.215014       1 utils.go:199] ID: 71 GRPC request: {}
I0730 19:25:28.215031       1 controllerserver-default.go:42] ID: 71 Using default ControllerGetCapabilities
I0730 19:25:28.215168       1 utils.go:205] ID: 71 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0730 19:26:16.759148       1 utils.go:198] ID: 72 GRPC call: /csi.v1.Identity/Probe
I0730 19:26:16.759199       1 utils.go:199] ID: 72 GRPC request: {}
I0730 19:26:16.759225       1 utils.go:205] ID: 72 GRPC response: {}
I0730 19:27:04.606645       1 utils.go:198] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:27:04.606955       1 utils.go:199] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8","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 19:27:04.607143       1 rbd_util.go:1315] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:27:04.609660       1 omap.go:89] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:27:04.625387       1 omap.go:159] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8:1dc3f972-6aa6-4595-90f1-feedf484ed60])
I0730 19:27:04.632374       1 omap.go:159] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 set omap keys (pool="kube", namespace="", name="csi.volume.1dc3f972-6aa6-4595-90f1-feedf484ed60"): map[csi.imagename:csi-vol-1dc3f972-6aa6-4595-90f1-feedf484ed60 csi.volname:pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 csi.volume.owner:openstack])
I0730 19:27:04.632409       1 rbd_journal.go:491] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1dc3f972-6aa6-4595-90f1-feedf484ed60) and image name (csi-vol-1dc3f972-6aa6-4595-90f1-feedf484ed60) for request name (pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8)
I0730 19:27:04.632510       1 rbd_util.go:423] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 rbd: create kube/csi-vol-1dc3f972-6aa6-4595-90f1-feedf484ed60 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:27:04.632600       1 rbd_util.go:1563] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 setting image options on kube/csi-vol-1dc3f972-6aa6-4595-90f1-feedf484ed60
I0730 19:27:04.666232       1 controllerserver.go:763] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 created image kube/csi-vol-1dc3f972-6aa6-4595-90f1-feedf484ed60 backed for request name pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8
I0730 19:27:04.698894       1 omap.go:159] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 set omap keys (pool="kube", namespace="", name="csi.volume.1dc3f972-6aa6-4595-90f1-feedf484ed60"): map[csi.imageid:37c693bf647a])
I0730 19:27:04.803016       1 utils.go:205] ID: 73 Req-ID: pvc-4edc53e5-8864-4222-aaf3-fec9f0997da8 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-1dc3f972-6aa6-4595-90f1-feedf484ed60","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1dc3f972-6aa6-4595-90f1-feedf484ed60"}}
I0730 19:27:16.733906       1 utils.go:198] ID: 74 GRPC call: /csi.v1.Identity/Probe
I0730 19:27:16.733963       1 utils.go:199] ID: 74 GRPC request: {}
I0730 19:27:16.734034       1 utils.go:205] ID: 74 GRPC response: {}
I0730 19:28:16.760590       1 utils.go:198] ID: 75 GRPC call: /csi.v1.Identity/Probe
I0730 19:28:16.760728       1 utils.go:199] ID: 75 GRPC request: {}
I0730 19:28:16.760768       1 utils.go:205] ID: 75 GRPC response: {}
I0730 19:29:16.733227       1 utils.go:198] ID: 76 GRPC call: /csi.v1.Identity/Probe
I0730 19:29:16.733279       1 utils.go:199] ID: 76 GRPC request: {}
I0730 19:29:16.733300       1 utils.go:205] ID: 76 GRPC response: {}
I0730 19:30:16.774827       1 utils.go:198] ID: 77 GRPC call: /csi.v1.Identity/Probe
I0730 19:30:16.774896       1 utils.go:199] ID: 77 GRPC request: {}
I0730 19:30:16.774925       1 utils.go:205] ID: 77 GRPC response: {}
I0730 19:31:16.788838       1 utils.go:198] ID: 78 GRPC call: /csi.v1.Identity/Probe
I0730 19:31:16.788904       1 utils.go:199] ID: 78 GRPC request: {}
I0730 19:31:16.788926       1 utils.go:205] ID: 78 GRPC response: {}
I0730 19:32:16.784169       1 utils.go:198] ID: 79 GRPC call: /csi.v1.Identity/Probe
I0730 19:32:16.784235       1 utils.go:199] ID: 79 GRPC request: {}
I0730 19:32:16.784257       1 utils.go:205] ID: 79 GRPC response: {}
I0730 19:33:16.733157       1 utils.go:198] ID: 80 GRPC call: /csi.v1.Identity/Probe
I0730 19:33:16.733219       1 utils.go:199] ID: 80 GRPC request: {}
I0730 19:33:16.733243       1 utils.go:205] ID: 80 GRPC response: {}
I0730 19:34:16.790771       1 utils.go:198] ID: 81 GRPC call: /csi.v1.Identity/Probe
I0730 19:34:16.790822       1 utils.go:199] ID: 81 GRPC request: {}
I0730 19:34:16.790868       1 utils.go:205] ID: 81 GRPC response: {}
I0730 19:34:56.279776       1 utils.go:198] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:34:56.280034       1 utils.go:199] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be","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 19:34:56.280231       1 rbd_util.go:1315] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:34:56.281988       1 omap.go:89] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:34:56.380243       1 omap.go:159] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be:377c9475-1412-4f2b-bdab-352c19b8f682])
I0730 19:34:56.410231       1 omap.go:159] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be set omap keys (pool="kube", namespace="", name="csi.volume.377c9475-1412-4f2b-bdab-352c19b8f682"): map[csi.imagename:csi-vol-377c9475-1412-4f2b-bdab-352c19b8f682 csi.volname:pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be csi.volume.owner:openstack])
I0730 19:34:56.410277       1 rbd_journal.go:491] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-377c9475-1412-4f2b-bdab-352c19b8f682) and image name (csi-vol-377c9475-1412-4f2b-bdab-352c19b8f682) for request name (pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be)
I0730 19:34:56.410403       1 rbd_util.go:423] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be rbd: create kube/csi-vol-377c9475-1412-4f2b-bdab-352c19b8f682 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:34:56.410455       1 rbd_util.go:1563] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be setting image options on kube/csi-vol-377c9475-1412-4f2b-bdab-352c19b8f682
I0730 19:34:56.532602       1 controllerserver.go:763] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be created image kube/csi-vol-377c9475-1412-4f2b-bdab-352c19b8f682 backed for request name pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be
I0730 19:34:56.647868       1 omap.go:159] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be set omap keys (pool="kube", namespace="", name="csi.volume.377c9475-1412-4f2b-bdab-352c19b8f682"): map[csi.imageid:37c6f77b178f])
I0730 19:34:56.816065       1 utils.go:205] ID: 82 Req-ID: pvc-406b01c2-4ce1-4a24-bea1-adea5812c6be GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-377c9475-1412-4f2b-bdab-352c19b8f682","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-377c9475-1412-4f2b-bdab-352c19b8f682"}}
I0730 19:35:16.736254       1 utils.go:198] ID: 83 GRPC call: /csi.v1.Identity/Probe
I0730 19:35:16.736369       1 utils.go:199] ID: 83 GRPC request: {}
I0730 19:35:16.736420       1 utils.go:205] ID: 83 GRPC response: {}
I0730 19:36:16.773213       1 utils.go:198] ID: 84 GRPC call: /csi.v1.Identity/Probe
I0730 19:36:16.773282       1 utils.go:199] ID: 84 GRPC request: {}
I0730 19:36:16.773298       1 utils.go:205] ID: 84 GRPC response: {}
I0730 19:37:16.745018       1 utils.go:198] ID: 85 GRPC call: /csi.v1.Identity/Probe
I0730 19:37:16.745130       1 utils.go:199] ID: 85 GRPC request: {}
I0730 19:37:16.745162       1 utils.go:205] ID: 85 GRPC response: {}
I0730 19:38:16.789504       1 utils.go:198] ID: 86 GRPC call: /csi.v1.Identity/Probe
I0730 19:38:16.789638       1 utils.go:199] ID: 86 GRPC request: {}
I0730 19:38:16.789689       1 utils.go:205] ID: 86 GRPC response: {}
I0730 19:39:16.754389       1 utils.go:198] ID: 87 GRPC call: /csi.v1.Identity/Probe
I0730 19:39:16.754453       1 utils.go:199] ID: 87 GRPC request: {}
I0730 19:39:16.754510       1 utils.go:205] ID: 87 GRPC response: {}
I0730 19:40:16.733093       1 utils.go:198] ID: 88 GRPC call: /csi.v1.Identity/Probe
I0730 19:40:16.733146       1 utils.go:199] ID: 88 GRPC request: {}
I0730 19:40:16.733167       1 utils.go:205] ID: 88 GRPC response: {}
I0730 19:41:16.764369       1 utils.go:198] ID: 89 GRPC call: /csi.v1.Identity/Probe
I0730 19:41:16.764496       1 utils.go:199] ID: 89 GRPC request: {}
I0730 19:41:16.764621       1 utils.go:205] ID: 89 GRPC response: {}
I0730 19:42:16.733205       1 utils.go:198] ID: 90 GRPC call: /csi.v1.Identity/Probe
I0730 19:42:16.733260       1 utils.go:199] ID: 90 GRPC request: {}
I0730 19:42:16.733285       1 utils.go:205] ID: 90 GRPC response: {}
I0730 19:43:16.778618       1 utils.go:198] ID: 91 GRPC call: /csi.v1.Identity/Probe
I0730 19:43:16.778691       1 utils.go:199] ID: 91 GRPC request: {}
I0730 19:43:16.778720       1 utils.go:205] ID: 91 GRPC response: {}
I0730 19:44:16.792203       1 utils.go:198] ID: 92 GRPC call: /csi.v1.Identity/Probe
I0730 19:44:16.792254       1 utils.go:199] ID: 92 GRPC request: {}
I0730 19:44:16.792276       1 utils.go:205] ID: 92 GRPC response: {}
I0730 19:44:58.774855       1 utils.go:198] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 GRPC call: /csi.v1.Controller/CreateVolume
I0730 19:44:58.775032       1 utils.go:199] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-octavia-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:44:58.775175       1 rbd_util.go:1315] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 19:44:58.777275       1 omap.go:89] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 19:44:58.794302       1 omap.go:159] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2:5186eb75-075d-4628-8821-098913c95f87])
I0730 19:44:58.801650       1 omap.go:159] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 set omap keys (pool="kube", namespace="", name="csi.volume.5186eb75-075d-4628-8821-098913c95f87"): map[csi.imagename:csi-vol-5186eb75-075d-4628-8821-098913c95f87 csi.volname:pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 csi.volume.owner:openstack])
I0730 19:44:58.801675       1 rbd_journal.go:491] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5186eb75-075d-4628-8821-098913c95f87) and image name (csi-vol-5186eb75-075d-4628-8821-098913c95f87) for request name (pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2)
I0730 19:44:58.801740       1 rbd_util.go:423] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 rbd: create kube/csi-vol-5186eb75-075d-4628-8821-098913c95f87 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 19:44:58.801778       1 rbd_util.go:1563] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 setting image options on kube/csi-vol-5186eb75-075d-4628-8821-098913c95f87
I0730 19:44:58.837855       1 controllerserver.go:763] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 created image kube/csi-vol-5186eb75-075d-4628-8821-098913c95f87 backed for request name pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2
I0730 19:44:58.895676       1 omap.go:159] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 set omap keys (pool="kube", namespace="", name="csi.volume.5186eb75-075d-4628-8821-098913c95f87"): map[csi.imageid:37c69741176d])
I0730 19:44:59.087640       1 utils.go:205] ID: 93 Req-ID: pvc-a5eb5639-ba3b-44f5-b213-fa68bccc85b2 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-5186eb75-075d-4628-8821-098913c95f87","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-5186eb75-075d-4628-8821-098913c95f87"}}
I0730 19:45:16.784084       1 utils.go:198] ID: 94 GRPC call: /csi.v1.Identity/Probe
I0730 19:45:16.784128       1 utils.go:199] ID: 94 GRPC request: {}
I0730 19:45:16.784146       1 utils.go:205] ID: 94 GRPC response: {}
I0730 19:46:16.733095       1 utils.go:198] ID: 95 GRPC call: /csi.v1.Identity/Probe
I0730 19:46:16.733153       1 utils.go:199] ID: 95 GRPC request: {}
I0730 19:46:16.733175       1 utils.go:205] ID: 95 GRPC response: {}
I0730 19:47:16.788726       1 utils.go:198] ID: 96 GRPC call: /csi.v1.Identity/Probe
I0730 19:47:16.788768       1 utils.go:199] ID: 96 GRPC request: {}
I0730 19:47:16.788783       1 utils.go:205] ID: 96 GRPC response: {}
I0730 19:48:16.789437       1 utils.go:198] ID: 97 GRPC call: /csi.v1.Identity/Probe
I0730 19:48:16.789505       1 utils.go:199] ID: 97 GRPC request: {}
I0730 19:48:16.789532       1 utils.go:205] ID: 97 GRPC response: {}
I0730 19:49:16.789546       1 utils.go:198] ID: 98 GRPC call: /csi.v1.Identity/Probe
I0730 19:49:16.789614       1 utils.go:199] ID: 98 GRPC request: {}
I0730 19:49:16.789644       1 utils.go:205] ID: 98 GRPC response: {}
I0730 19:50:16.733821       1 utils.go:198] ID: 99 GRPC call: /csi.v1.Identity/Probe
I0730 19:50:16.733880       1 utils.go:199] ID: 99 GRPC request: {}
I0730 19:50:16.733914       1 utils.go:205] ID: 99 GRPC response: {}
I0730 19:51:16.793690       1 utils.go:198] ID: 100 GRPC call: /csi.v1.Identity/Probe
I0730 19:51:16.793752       1 utils.go:199] ID: 100 GRPC request: {}
I0730 19:51:16.793772       1 utils.go:205] ID: 100 GRPC response: {}
I0730 19:52:16.744409       1 utils.go:198] ID: 101 GRPC call: /csi.v1.Identity/Probe
I0730 19:52:16.744471       1 utils.go:199] ID: 101 GRPC request: {}
I0730 19:52:16.744496       1 utils.go:205] ID: 101 GRPC response: {}
I0730 19:53:16.733135       1 utils.go:198] ID: 102 GRPC call: /csi.v1.Identity/Probe
I0730 19:53:16.733193       1 utils.go:199] ID: 102 GRPC request: {}
I0730 19:53:16.733217       1 utils.go:205] ID: 102 GRPC response: {}
I0730 19:54:16.753904       1 utils.go:198] ID: 103 GRPC call: /csi.v1.Identity/Probe
I0730 19:54:16.753982       1 utils.go:199] ID: 103 GRPC request: {}
I0730 19:54:16.754006       1 utils.go:205] ID: 103 GRPC response: {}
I0730 19:55:16.733209       1 utils.go:198] ID: 104 GRPC call: /csi.v1.Identity/Probe
I0730 19:55:16.733288       1 utils.go:199] ID: 104 GRPC request: {}
I0730 19:55:16.733312       1 utils.go:205] ID: 104 GRPC response: {}
I0730 19:56:16.763720       1 utils.go:198] ID: 105 GRPC call: /csi.v1.Identity/Probe
I0730 19:56:16.763776       1 utils.go:199] ID: 105 GRPC request: {}
I0730 19:56:16.763803       1 utils.go:205] ID: 105 GRPC response: {}
I0730 19:57:16.733164       1 utils.go:198] ID: 106 GRPC call: /csi.v1.Identity/Probe
I0730 19:57:16.733223       1 utils.go:199] ID: 106 GRPC request: {}
I0730 19:57:16.733247       1 utils.go:205] ID: 106 GRPC response: {}
I0730 19:58:16.773996       1 utils.go:198] ID: 107 GRPC call: /csi.v1.Identity/Probe
I0730 19:58:16.774051       1 utils.go:199] ID: 107 GRPC request: {}
I0730 19:58:16.774075       1 utils.go:205] ID: 107 GRPC response: {}
I0730 19:59:16.733073       1 utils.go:198] ID: 108 GRPC call: /csi.v1.Identity/Probe
I0730 19:59:16.733125       1 utils.go:199] ID: 108 GRPC request: {}
I0730 19:59:16.733149       1 utils.go:205] ID: 108 GRPC response: {}
I0730 20:00:16.786771       1 utils.go:198] ID: 109 GRPC call: /csi.v1.Identity/Probe
I0730 20:00:16.786815       1 utils.go:199] ID: 109 GRPC request: {}
I0730 20:00:16.786835       1 utils.go:205] ID: 109 GRPC response: {}
I0730 20:01:16.788940       1 utils.go:198] ID: 110 GRPC call: /csi.v1.Identity/Probe
I0730 20:01:16.789001       1 utils.go:199] ID: 110 GRPC request: {}
I0730 20:01:16.789025       1 utils.go:205] ID: 110 GRPC response: {}
I0730 20:02:16.793701       1 utils.go:198] ID: 111 GRPC call: /csi.v1.Identity/Probe
I0730 20:02:16.793742       1 utils.go:199] ID: 111 GRPC request: {}
I0730 20:02:16.793773       1 utils.go:205] ID: 111 GRPC response: {}
I0730 20:03:16.733287       1 utils.go:198] ID: 112 GRPC call: /csi.v1.Identity/Probe
I0730 20:03:16.733377       1 utils.go:199] ID: 112 GRPC request: {}
I0730 20:03:16.733404       1 utils.go:205] ID: 112 GRPC response: {}