I0730 05:01:00.074717       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0730 05:01:00.074890       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0730 05:01:00.074905       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0730 05:01:00.074909       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0730 05:01:00.074911       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0730 05:01:00.074914       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0730 05:01:00.074917       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0730 05:01:00.074920       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0730 05:01:00.074922       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0730 05:01:00.074925       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0730 05:01:00.075290       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0730 05:01:00.075367       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0730 05:01:09.116473       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:09.118598       1 utils.go:199] ID: 1 GRPC request: {}
I0730 05:01:09.118687       1 utils.go:205] ID: 1 GRPC response: {}
I0730 05:01:09.120511       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 05:01:09.120569       1 utils.go:199] ID: 2 GRPC request: {}
I0730 05:01:09.120591       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0730 05:01:09.120660       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 05:01:09.124260       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:09.124326       1 utils.go:199] ID: 3 GRPC request: {}
I0730 05:01:09.124351       1 utils.go:205] ID: 3 GRPC response: {}
I0730 05:01:09.124998       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 05:01:09.125088       1 utils.go:199] ID: 4 GRPC request: {}
I0730 05:01:09.125343       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 05:01:09.126128       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 05:01:09.126187       1 utils.go:199] ID: 5 GRPC request: {}
I0730 05:01:09.126201       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0730 05:01:09.126320       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 05:01:12.906039       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:12.906130       1 utils.go:199] ID: 6 GRPC request: {}
I0730 05:01:12.906165       1 utils.go:205] ID: 6 GRPC response: {}
I0730 05:01:12.908520       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 05:01:12.908565       1 utils.go:199] ID: 7 GRPC request: {}
I0730 05:01:12.908575       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0730 05:01:12.908604       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 05:01:12.912064       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:12.912086       1 utils.go:199] ID: 8 GRPC request: {}
I0730 05:01:12.912102       1 utils.go:205] ID: 8 GRPC response: {}
I0730 05:01:12.913205       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 05:01:12.913252       1 utils.go:199] ID: 9 GRPC request: {}
I0730 05:01:12.913534       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 05:01:12.915044       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 05:01:12.915074       1 utils.go:199] ID: 10 GRPC request: {}
I0730 05:01:12.915085       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0730 05:01:12.915234       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 05:01:12.916203       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 05:01:12.916226       1 utils.go:199] ID: 11 GRPC request: {}
I0730 05:01:12.916234       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0730 05:01:12.916304       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 05:01:12.916715       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 05:01:12.916733       1 utils.go:199] ID: 12 GRPC request: {}
I0730 05:01:12.916739       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0730 05:01:12.916798       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 05:01:14.543005       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 05:01:14.543066       1 utils.go:199] ID: 13 GRPC request: {}
I0730 05:01:14.543080       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0730 05:01:14.543131       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 05:01:14.548236       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:14.548315       1 utils.go:199] ID: 14 GRPC request: {}
I0730 05:01:14.548345       1 utils.go:205] ID: 14 GRPC response: {}
I0730 05:01:14.549699       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 05:01:14.549741       1 utils.go:199] ID: 15 GRPC request: {}
I0730 05:01:14.549751       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0730 05:01:14.549881       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 05:01:16.125716       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:16.125785       1 utils.go:199] ID: 16 GRPC request: {}
I0730 05:01:16.125810       1 utils.go:205] ID: 16 GRPC response: {}
I0730 05:01:16.129098       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0730 05:01:16.129133       1 utils.go:199] ID: 17 GRPC request: {}
I0730 05:01:16.129145       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0730 05:01:16.129178       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0730 05:01:16.131528       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0730 05:01:16.131604       1 utils.go:199] ID: 18 GRPC request: {}
I0730 05:01:16.131627       1 utils.go:205] ID: 18 GRPC response: {}
I0730 05:01:16.132060       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0730 05:01:16.132083       1 utils.go:199] ID: 19 GRPC request: {}
I0730 05:01:16.132304       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0730 05:01:16.133126       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0730 05:01:16.133172       1 utils.go:199] ID: 20 GRPC request: {}
I0730 05:01:16.133183       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0730 05:01:16.133302       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 05:02:12.849371       1 utils.go:198] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 GRPC call: /csi.v1.Controller/CreateVolume
I0730 05:02:12.849835       1 utils.go:199] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 GRPC request: {"capacity_range":{"required_bytes":171798691840},"name":"pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73","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 05:02:12.850142       1 rbd_util.go:1315] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0730 05:02:12.870242       1 omap.go:80] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0730 05:02:12.887026       1 omap.go:159] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73:3294fd44-e9e7-404a-99ba-a3c19a2765b5])
I0730 05:02:12.896838       1 omap.go:159] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 set omap keys (pool="kube", namespace="", name="csi.volume.3294fd44-e9e7-404a-99ba-a3c19a2765b5"): map[csi.imagename:csi-vol-3294fd44-e9e7-404a-99ba-a3c19a2765b5 csi.volname:pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 csi.volume.owner:openstack])
I0730 05:02:12.896886       1 rbd_journal.go:491] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3294fd44-e9e7-404a-99ba-a3c19a2765b5) and image name (csi-vol-3294fd44-e9e7-404a-99ba-a3c19a2765b5) for request name (pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73)
I0730 05:02:12.896989       1 rbd_util.go:423] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 rbd: create kube/csi-vol-3294fd44-e9e7-404a-99ba-a3c19a2765b5 size 163840M (features: [layering]) using mon 10.96.240.200
I0730 05:02:12.897040       1 rbd_util.go:1563] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 setting image options on kube/csi-vol-3294fd44-e9e7-404a-99ba-a3c19a2765b5
I0730 05:02:12.934665       1 controllerserver.go:763] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 created image kube/csi-vol-3294fd44-e9e7-404a-99ba-a3c19a2765b5 backed for request name pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73
I0730 05:02:12.977446       1 omap.go:159] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 set omap keys (pool="kube", namespace="", name="csi.volume.3294fd44-e9e7-404a-99ba-a3c19a2765b5"): map[csi.imageid:37c5303553e1])
I0730 05:02:13.080783       1 utils.go:205] ID: 21 Req-ID: pvc-1fa25cd3-d246-40a3-8d18-d57d8108fc73 GRPC response: {"volume":{"capacity_bytes":171798691840,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-3294fd44-e9e7-404a-99ba-a3c19a2765b5","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3294fd44-e9e7-404a-99ba-a3c19a2765b5"}}
I0730 05:02:18.049040       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0730 05:02:18.049099       1 utils.go:199] ID: 22 GRPC request: {}
I0730 05:02:18.049127       1 utils.go:205] ID: 22 GRPC response: {}
I0730 05:03:18.006140       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0730 05:03:18.006226       1 utils.go:199] ID: 23 GRPC request: {}
I0730 05:03:18.006259       1 utils.go:205] ID: 23 GRPC response: {}
I0730 05:04:18.014598       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0730 05:04:18.014656       1 utils.go:199] ID: 24 GRPC request: {}
I0730 05:04:18.014681       1 utils.go:205] ID: 24 GRPC response: {}
I0730 05:05:18.014067       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0730 05:05:18.014159       1 utils.go:199] ID: 25 GRPC request: {}
I0730 05:05:18.014222       1 utils.go:205] ID: 25 GRPC response: {}
I0730 05:06:18.053648       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe
I0730 05:06:18.053718       1 utils.go:199] ID: 26 GRPC request: {}
I0730 05:06:18.053746       1 utils.go:205] ID: 26 GRPC response: {}
I0730 05:07:18.026876       1 utils.go:198] ID: 27 GRPC call: /csi.v1.Identity/Probe
I0730 05:07:18.026970       1 utils.go:199] ID: 27 GRPC request: {}
I0730 05:07:18.027031       1 utils.go:205] ID: 27 GRPC response: {}
I0730 05:08:18.056633       1 utils.go:198] ID: 28 GRPC call: /csi.v1.Identity/Probe
I0730 05:08:18.056691       1 utils.go:199] ID: 28 GRPC request: {}
I0730 05:08:18.056714       1 utils.go:205] ID: 28 GRPC response: {}
I0730 05:09:18.036657       1 utils.go:198] ID: 29 GRPC call: /csi.v1.Identity/Probe
I0730 05:09:18.036724       1 utils.go:199] ID: 29 GRPC request: {}
I0730 05:09:18.036751       1 utils.go:205] ID: 29 GRPC response: {}
I0730 05:10:18.001644       1 utils.go:198] ID: 30 GRPC call: /csi.v1.Identity/Probe
I0730 05:10:18.001711       1 utils.go:199] ID: 30 GRPC request: {}
I0730 05:10:18.001742       1 utils.go:205] ID: 30 GRPC response: {}
I0730 05:11:18.043663       1 utils.go:198] ID: 31 GRPC call: /csi.v1.Identity/Probe
I0730 05:11:18.043741       1 utils.go:199] ID: 31 GRPC request: {}
I0730 05:11:18.043794       1 utils.go:205] ID: 31 GRPC response: {}
I0730 05:12:18.056491       1 utils.go:198] ID: 32 GRPC call: /csi.v1.Identity/Probe
I0730 05:12:18.056564       1 utils.go:199] ID: 32 GRPC request: {}
I0730 05:12:18.056596       1 utils.go:205] ID: 32 GRPC response: {}
I0730 05:13:18.053637       1 utils.go:198] ID: 33 GRPC call: /csi.v1.Identity/Probe
I0730 05:13:18.053690       1 utils.go:199] ID: 33 GRPC request: {}
I0730 05:13:18.053711       1 utils.go:205] ID: 33 GRPC response: {}
I0730 05:13:57.092799       1 utils.go:198] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe GRPC call: /csi.v1.Controller/CreateVolume
I0730 05:13:57.093046       1 utils.go:199] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe","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 05:13:57.093244       1 rbd_util.go:1315] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 05:13:57.096661       1 omap.go:89] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 05:13:57.107479       1 omap.go:159] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe:755e8612-b45a-4091-a712-c890e17cda64])
I0730 05:13:57.115958       1 omap.go:159] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe set omap keys (pool="kube", namespace="", name="csi.volume.755e8612-b45a-4091-a712-c890e17cda64"): map[csi.imagename:csi-vol-755e8612-b45a-4091-a712-c890e17cda64 csi.volname:pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe csi.volume.owner:openstack])
I0730 05:13:57.116015       1 rbd_journal.go:491] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-755e8612-b45a-4091-a712-c890e17cda64) and image name (csi-vol-755e8612-b45a-4091-a712-c890e17cda64) for request name (pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe)
I0730 05:13:57.116146       1 rbd_util.go:423] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe rbd: create kube/csi-vol-755e8612-b45a-4091-a712-c890e17cda64 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 05:13:57.116232       1 rbd_util.go:1563] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe setting image options on kube/csi-vol-755e8612-b45a-4091-a712-c890e17cda64
I0730 05:13:57.151382       1 controllerserver.go:763] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe created image kube/csi-vol-755e8612-b45a-4091-a712-c890e17cda64 backed for request name pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe
I0730 05:13:57.183300       1 omap.go:159] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe set omap keys (pool="kube", namespace="", name="csi.volume.755e8612-b45a-4091-a712-c890e17cda64"): map[csi.imageid:37c54c526ece])
I0730 05:13:57.284743       1 utils.go:205] ID: 34 Req-ID: pvc-9edc875b-4317-45a9-a906-dd5c2731cdbe GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-755e8612-b45a-4091-a712-c890e17cda64","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-755e8612-b45a-4091-a712-c890e17cda64"}}
I0730 05:14:18.052829       1 utils.go:198] ID: 35 GRPC call: /csi.v1.Identity/Probe
I0730 05:14:18.052884       1 utils.go:199] ID: 35 GRPC request: {}
I0730 05:14:18.052908       1 utils.go:205] ID: 35 GRPC response: {}
I0730 05:15:18.061314       1 utils.go:198] ID: 36 GRPC call: /csi.v1.Identity/Probe
I0730 05:15:18.061392       1 utils.go:199] ID: 36 GRPC request: {}
I0730 05:15:18.061417       1 utils.go:205] ID: 36 GRPC response: {}
I0730 05:16:18.005303       1 utils.go:198] ID: 37 GRPC call: /csi.v1.Identity/Probe
I0730 05:16:18.005349       1 utils.go:199] ID: 37 GRPC request: {}
I0730 05:16:18.005367       1 utils.go:205] ID: 37 GRPC response: {}
I0730 05:17:18.026030       1 utils.go:198] ID: 38 GRPC call: /csi.v1.Identity/Probe
I0730 05:17:18.026105       1 utils.go:199] ID: 38 GRPC request: {}
I0730 05:17:18.026129       1 utils.go:205] ID: 38 GRPC response: {}
I0730 05:18:00.030432       1 utils.go:198] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba GRPC call: /csi.v1.Controller/CreateVolume
I0730 05:18:00.030644       1 utils.go:199] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-424c0bff-9a96-419e-b742-cb5e402f8cba","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-424c0bff-9a96-419e-b742-cb5e402f8cba","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 05:18:00.030840       1 rbd_util.go:1315] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 05:18:00.034344       1 omap.go:89] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 05:18:00.054439       1 omap.go:159] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-424c0bff-9a96-419e-b742-cb5e402f8cba:bbe2eca9-6028-4116-86c3-41e128c64afc])
I0730 05:18:00.063544       1 omap.go:159] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba set omap keys (pool="kube", namespace="", name="csi.volume.bbe2eca9-6028-4116-86c3-41e128c64afc"): map[csi.imagename:csi-vol-bbe2eca9-6028-4116-86c3-41e128c64afc csi.volname:pvc-424c0bff-9a96-419e-b742-cb5e402f8cba csi.volume.owner:openstack])
I0730 05:18:00.063575       1 rbd_journal.go:491] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-bbe2eca9-6028-4116-86c3-41e128c64afc) and image name (csi-vol-bbe2eca9-6028-4116-86c3-41e128c64afc) for request name (pvc-424c0bff-9a96-419e-b742-cb5e402f8cba)
I0730 05:18:00.063674       1 rbd_util.go:423] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba rbd: create kube/csi-vol-bbe2eca9-6028-4116-86c3-41e128c64afc size 10240M (features: [layering]) using mon 10.96.240.200
I0730 05:18:00.063732       1 rbd_util.go:1563] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba setting image options on kube/csi-vol-bbe2eca9-6028-4116-86c3-41e128c64afc
I0730 05:18:00.105277       1 controllerserver.go:763] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba created image kube/csi-vol-bbe2eca9-6028-4116-86c3-41e128c64afc backed for request name pvc-424c0bff-9a96-419e-b742-cb5e402f8cba
I0730 05:18:00.144003       1 omap.go:159] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba set omap keys (pool="kube", namespace="", name="csi.volume.bbe2eca9-6028-4116-86c3-41e128c64afc"): map[csi.imageid:37c58bdf9ae2])
I0730 05:18:00.268578       1 utils.go:205] ID: 39 Req-ID: pvc-424c0bff-9a96-419e-b742-cb5e402f8cba GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-bbe2eca9-6028-4116-86c3-41e128c64afc","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-bbe2eca9-6028-4116-86c3-41e128c64afc"}}
I0730 05:18:18.002899       1 utils.go:198] ID: 40 GRPC call: /csi.v1.Identity/Probe
I0730 05:18:18.002965       1 utils.go:199] ID: 40 GRPC request: {}
I0730 05:18:18.002988       1 utils.go:205] ID: 40 GRPC response: {}
I0730 05:19:18.001585       1 utils.go:198] ID: 41 GRPC call: /csi.v1.Identity/Probe
I0730 05:19:18.001653       1 utils.go:199] ID: 41 GRPC request: {}
I0730 05:19:18.001696       1 utils.go:205] ID: 41 GRPC response: {}
I0730 05:20:18.013603       1 utils.go:198] ID: 42 GRPC call: /csi.v1.Identity/Probe
I0730 05:20:18.013683       1 utils.go:199] ID: 42 GRPC request: {}
I0730 05:20:18.013727       1 utils.go:205] ID: 42 GRPC response: {}
I0730 05:21:18.001728       1 utils.go:198] ID: 43 GRPC call: /csi.v1.Identity/Probe
I0730 05:21:18.001786       1 utils.go:199] ID: 43 GRPC request: {}
I0730 05:21:18.001806       1 utils.go:205] ID: 43 GRPC response: {}
I0730 05:22:18.029703       1 utils.go:198] ID: 44 GRPC call: /csi.v1.Identity/Probe
I0730 05:22:18.029776       1 utils.go:199] ID: 44 GRPC request: {}
I0730 05:22:18.029799       1 utils.go:205] ID: 44 GRPC response: {}
I0730 05:23:13.337970       1 utils.go:198] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab GRPC call: /csi.v1.Controller/CreateVolume
I0730 05:23:13.338456       1 utils.go:199] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab","csi.storage.k8s.io/pvc/name":"persistence-rabbitmq-cinder-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 05:23:13.338761       1 rbd_util.go:1315] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab setting disableInUseChecks: false image features: [layering] mounter: rbd
I0730 05:23:13.341329       1 omap.go:89] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[]
I0730 05:23:13.359028       1 omap.go:159] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab:8b415538-47d2-41a6-b58b-2e75c973d924])
I0730 05:23:13.366665       1 omap.go:159] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab set omap keys (pool="kube", namespace="", name="csi.volume.8b415538-47d2-41a6-b58b-2e75c973d924"): map[csi.imagename:csi-vol-8b415538-47d2-41a6-b58b-2e75c973d924 csi.volname:pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab csi.volume.owner:openstack])
I0730 05:23:13.366711       1 rbd_journal.go:491] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8b415538-47d2-41a6-b58b-2e75c973d924) and image name (csi-vol-8b415538-47d2-41a6-b58b-2e75c973d924) for request name (pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab)
I0730 05:23:13.366898       1 rbd_util.go:423] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab rbd: create kube/csi-vol-8b415538-47d2-41a6-b58b-2e75c973d924 size 10240M (features: [layering]) using mon 10.96.240.200
I0730 05:23:13.367025       1 rbd_util.go:1563] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab setting image options on kube/csi-vol-8b415538-47d2-41a6-b58b-2e75c973d924
I0730 05:23:13.404053       1 controllerserver.go:763] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab created image kube/csi-vol-8b415538-47d2-41a6-b58b-2e75c973d924 backed for request name pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab
I0730 05:23:13.439137       1 omap.go:159] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab set omap keys (pool="kube", namespace="", name="csi.volume.8b415538-47d2-41a6-b58b-2e75c973d924"): map[csi.imageid:37c519bf52a5])
I0730 05:23:13.546557       1 utils.go:205] ID: 45 Req-ID: pvc-892b3a3f-c18e-499e-82b3-5f8b5b3824ab GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-8b415538-47d2-41a6-b58b-2e75c973d924","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8b415538-47d2-41a6-b58b-2e75c973d924"}}
I0730 05:23:18.029419       1 utils.go:198] ID: 46 GRPC call: /csi.v1.Identity/Probe
I0730 05:23:18.029478       1 utils.go:199] ID: 46 GRPC request: {}
I0730 05:23:18.029503       1 utils.go:205] ID: 46 GRPC response: {}
I0730 05:24:18.035171       1 utils.go:198] ID: 47 GRPC call: /csi.v1.Identity/Probe
I0730 05:24:18.035257       1 utils.go:199] ID: 47 GRPC request: {}
I0730 05:24:18.035292       1 utils.go:205] ID: 47 GRPC response: {}
I0730 05:25:18.044570       1 utils.go:198] ID: 48 GRPC call: /csi.v1.Identity/Probe
I0730 05:25:18.044641       1 utils.go:199] ID: 48 GRPC request: {}
I0730 05:25:18.044676       1 utils.go:205] ID: 48 GRPC response: {}
I0730 05:26:18.043663       1 utils.go:198] ID: 49 GRPC call: /csi.v1.Identity/Probe
I0730 05:26:18.043850       1 utils.go:199] ID: 49 GRPC request: {}
I0730 05:26:18.043895       1 utils.go:205] ID: 49 GRPC response: {}
I0730 05:27:18.002193       1 utils.go:198] ID: 50 GRPC call: /csi.v1.Identity/Probe
I0730 05:27:18.002263       1 utils.go:199] ID: 50 GRPC request: {}
I0730 05:27:18.002453       1 utils.go:205] ID: 50 GRPC response: {}
I0730 05:28:18.056096       1 utils.go:198] ID: 51 GRPC call: /csi.v1.Identity/Probe
I0730 05:28:18.056217       1 utils.go:199] ID: 51 GRPC request: {}
I0730 05:28:18.056277       1 utils.go:205] ID: 51 GRPC response: {}
I0730 05:29:18.056866       1 utils.go:198] ID: 52 GRPC call: /csi.v1.Identity/Probe
I0730 05:29:18.056949       1 utils.go:199] ID: 52 GRPC request: {}
I0730 05:29:18.056974       1 utils.go:205] ID: 52 GRPC response: {}
I0730 05:30:18.056385       1 utils.go:198] ID: 53 GRPC call: /csi.v1.Identity/Probe
I0730 05:30:18.056526       1 utils.go:199] ID: 53 GRPC request: {}
I0730 05:30:18.056587       1 utils.go:205] ID: 53 GRPC response: {}
I0730 05:31:18.005960       1 utils.go:198] ID: 54 GRPC call: /csi.v1.Identity/Probe
I0730 05:31:18.006019       1 utils.go:199] ID: 54 GRPC request: {}
I0730 05:31:18.006041       1 utils.go:205] ID: 54 GRPC response: {}
I0730 05:32:18.053741       1 utils.go:198] ID: 55 GRPC call: /csi.v1.Identity/Probe
I0730 05:32:18.053848       1 utils.go:199] ID: 55 GRPC request: {}
I0730 05:32:18.053934       1 utils.go:205] ID: 55 GRPC response: {}
I0730 05:33:18.004208       1 utils.go:198] ID: 56 GRPC call: /csi.v1.Identity/Probe
I0730 05:33:18.004522       1 utils.go:199] ID: 56 GRPC request: {}
I0730 05:33:18.004557       1 utils.go:205] ID: 56 GRPC response: {}
I0730 05:34:18.052724       1 utils.go:198] ID: 57 GRPC call: /csi.v1.Identity/Probe
I0730 05:34:18.052802       1 utils.go:199] ID: 57 GRPC request: {}
I0730 05:34:18.052832       1 utils.go:205] ID: 57 GRPC response: {}
I0730 05:35:18.013818       1 utils.go:198] ID: 58 GRPC call: /csi.v1.Identity/Probe
I0730 05:35:18.013946       1 utils.go:199] ID: 58 GRPC request: {}
I0730 05:35:18.013987       1 utils.go:205] ID: 58 GRPC response: {}
I0730 05:36:18.002324       1 utils.go:198] ID: 59 GRPC call: /csi.v1.Identity/Probe
I0730 05:36:18.002369       1 utils.go:199] ID: 59 GRPC request: {}
I0730 05:36:18.002383       1 utils.go:205] ID: 59 GRPC response: {}
I0730 05:37:18.025612       1 utils.go:198] ID: 60 GRPC call: /csi.v1.Identity/Probe
I0730 05:37:18.025705       1 utils.go:199] ID: 60 GRPC request: {}
I0730 05:37:18.025736       1 utils.go:205] ID: 60 GRPC response: {}
I0730 05:38:18.056330       1 utils.go:198] ID: 61 GRPC call: /csi.v1.Identity/Probe
I0730 05:38:18.056379       1 utils.go:199] ID: 61 GRPC request: {}
I0730 05:38:18.056397       1 utils.go:205] ID: 61 GRPC response: {}