I0730 18:58:28.422314 1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a I0730 18:58:28.422469 1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com I0730 18:58:28.422485 1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME I0730 18:58:28.422489 1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT I0730 18:58:28.422492 1 driver.go:95] Enabling controller service capability: CLONE_VOLUME I0730 18:58:28.422494 1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME I0730 18:58:28.422498 1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER I0730 18:58:28.422501 1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER I0730 18:58:28.422504 1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER I0730 18:58:28.422506 1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER I0730 18:58:28.422856 1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"} I0730 18:58:28.422883 1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"} I0730 18:58:39.682317 1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe I0730 18:58:39.684589 1 utils.go:199] ID: 1 GRPC request: {} I0730 18:58:39.684675 1 utils.go:205] ID: 1 GRPC response: {} I0730 18:58:39.688840 1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo I0730 18:58:39.689024 1 utils.go:199] ID: 2 GRPC request: {} I0730 18:58:39.689052 1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo I0730 18:58:39.689171 1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"} I0730 18:58:39.691168 1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe I0730 18:58:39.691207 1 utils.go:199] ID: 3 GRPC request: {} I0730 18:58:39.691226 1 utils.go:205] ID: 3 GRPC response: {} I0730 18:58:39.691811 1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities I0730 18:58:39.691986 1 utils.go:199] ID: 4 GRPC request: {} I0730 18:58:39.692252 1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0730 18:58:39.693300 1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0730 18:58:39.693410 1 utils.go:199] ID: 5 GRPC request: {} I0730 18:58:39.693460 1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities I0730 18:58:39.693607 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:41.388177 1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe I0730 18:58:41.388256 1 utils.go:199] ID: 6 GRPC request: {} I0730 18:58:41.388298 1 utils.go:205] ID: 6 GRPC response: {} I0730 18:58:41.393256 1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo I0730 18:58:41.393300 1 utils.go:199] ID: 7 GRPC request: {} I0730 18:58:41.393358 1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo I0730 18:58:41.393444 1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"} I0730 18:58:41.396347 1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe I0730 18:58:41.396375 1 utils.go:199] ID: 8 GRPC request: {} I0730 18:58:41.396431 1 utils.go:205] ID: 8 GRPC response: {} I0730 18:58:41.397255 1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities I0730 18:58:41.397313 1 utils.go:199] ID: 9 GRPC request: {} I0730 18:58:41.397470 1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0730 18:58:41.398598 1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0730 18:58:41.398629 1 utils.go:199] ID: 10 GRPC request: {} I0730 18:58:41.398641 1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities I0730 18:58:41.398876 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:41.399554 1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0730 18:58:41.399581 1 utils.go:199] ID: 11 GRPC request: {} I0730 18:58:41.399589 1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities I0730 18:58:41.399661 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:41.400044 1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0730 18:58:41.400079 1 utils.go:199] ID: 12 GRPC request: {} I0730 18:58:41.400087 1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities I0730 18:58:41.400244 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:48.943838 1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo I0730 18:58:48.943957 1 utils.go:199] ID: 13 GRPC request: {} I0730 18:58:48.943972 1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo I0730 18:58:48.944023 1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"} I0730 18:58:48.949635 1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe I0730 18:58:48.949849 1 utils.go:199] ID: 14 GRPC request: {} I0730 18:58:48.950148 1 utils.go:205] ID: 14 GRPC response: {} I0730 18:58:48.951191 1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0730 18:58:48.951244 1 utils.go:199] ID: 15 GRPC request: {} I0730 18:58:48.951255 1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities I0730 18:58:48.951378 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:50.813259 1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe I0730 18:58:50.813319 1 utils.go:199] ID: 16 GRPC request: {} I0730 18:58:50.813345 1 utils.go:205] ID: 16 GRPC response: {} I0730 18:58:50.817267 1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo I0730 18:58:50.817321 1 utils.go:199] ID: 17 GRPC request: {} I0730 18:58:50.817333 1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo I0730 18:58:50.817366 1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"} I0730 18:58:50.819104 1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe I0730 18:58:50.819232 1 utils.go:199] ID: 18 GRPC request: {} I0730 18:58:50.819323 1 utils.go:205] ID: 18 GRPC response: {} I0730 18:58:50.820083 1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities I0730 18:58:50.820139 1 utils.go:199] ID: 19 GRPC request: {} I0730 18:58:50.820282 1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0730 18:58:50.820937 1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0730 18:58:50.820973 1 utils.go:199] ID: 20 GRPC request: {} I0730 18:58:50.820990 1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities I0730 18:58:50.821122 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:33.895701 1 utils.go:198] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 GRPC call: /csi.v1.Controller/CreateVolume I0730 18:59:33.896158 1 utils.go:199] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 GRPC request: {"capacity_range":{"required_bytes":171798691840},"name":"pvc-db4aac20-9666-44ce-a904-9d8240fe3d32","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-db4aac20-9666-44ce-a904-9d8240fe3d32","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:33.896445 1 rbd_util.go:1315] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 setting disableInUseChecks: false image features: [layering] mounter: rbd E0730 18:59:33.915945 1 omap.go:80] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory I0730 18:59:33.929244 1 omap.go:159] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-db4aac20-9666-44ce-a904-9d8240fe3d32:bf9488b3-8a6c-4827-b111-7b33444e7931]) I0730 18:59:33.936081 1 omap.go:159] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 set omap keys (pool="kube", namespace="", name="csi.volume.bf9488b3-8a6c-4827-b111-7b33444e7931"): map[csi.imagename:csi-vol-bf9488b3-8a6c-4827-b111-7b33444e7931 csi.volname:pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 csi.volume.owner:openstack]) I0730 18:59:33.936125 1 rbd_journal.go:491] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-bf9488b3-8a6c-4827-b111-7b33444e7931) and image name (csi-vol-bf9488b3-8a6c-4827-b111-7b33444e7931) for request name (pvc-db4aac20-9666-44ce-a904-9d8240fe3d32) I0730 18:59:33.936211 1 rbd_util.go:423] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 rbd: create kube/csi-vol-bf9488b3-8a6c-4827-b111-7b33444e7931 size 163840M (features: [layering]) using mon 10.96.240.200 I0730 18:59:33.936268 1 rbd_util.go:1563] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 setting image options on kube/csi-vol-bf9488b3-8a6c-4827-b111-7b33444e7931 I0730 18:59:33.966567 1 controllerserver.go:763] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 created image kube/csi-vol-bf9488b3-8a6c-4827-b111-7b33444e7931 backed for request name pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 I0730 18:59:34.000138 1 omap.go:159] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 set omap keys (pool="kube", namespace="", name="csi.volume.bf9488b3-8a6c-4827-b111-7b33444e7931"): map[csi.imageid:37c66968dc8d]) I0730 18:59:34.108435 1 utils.go:205] ID: 21 Req-ID: pvc-db4aac20-9666-44ce-a904-9d8240fe3d32 GRPC response: {"volume":{"capacity_bytes":171798691840,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-bf9488b3-8a6c-4827-b111-7b33444e7931","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-bf9488b3-8a6c-4827-b111-7b33444e7931"}} I0730 18:59:51.002650 1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe I0730 18:59:51.002746 1 utils.go:199] ID: 22 GRPC request: {} I0730 18:59:51.002774 1 utils.go:205] ID: 22 GRPC response: {} I0730 19:00:51.001611 1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe I0730 19:00:51.001730 1 utils.go:199] ID: 23 GRPC request: {} I0730 19:00:51.001761 1 utils.go:205] ID: 23 GRPC response: {} I0730 19:01:51.001759 1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe I0730 19:01:51.001819 1 utils.go:199] ID: 24 GRPC request: {} I0730 19:01:51.001844 1 utils.go:205] ID: 24 GRPC response: {} I0730 19:02:51.011525 1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe I0730 19:02:51.011592 1 utils.go:199] ID: 25 GRPC request: {} I0730 19:02:51.011760 1 utils.go:205] ID: 25 GRPC response: {} I0730 19:03:51.037552 1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe I0730 19:03:51.037627 1 utils.go:199] ID: 26 GRPC request: {} I0730 19:03:51.037668 1 utils.go:205] ID: 26 GRPC response: {} I0730 19:04:51.022658 1 utils.go:198] ID: 27 GRPC call: /csi.v1.Identity/Probe I0730 19:04:51.022734 1 utils.go:199] ID: 27 GRPC request: {} I0730 19:04:51.022761 1 utils.go:205] ID: 27 GRPC response: {} I0730 19:05:51.001067 1 utils.go:198] ID: 28 GRPC call: /csi.v1.Identity/Probe I0730 19:05:51.001129 1 utils.go:199] ID: 28 GRPC request: {} I0730 19:05:51.001153 1 utils.go:205] ID: 28 GRPC response: {} I0730 19:06:51.032390 1 utils.go:198] ID: 29 GRPC call: /csi.v1.Identity/Probe I0730 19:06:51.032542 1 utils.go:199] ID: 29 GRPC request: {} I0730 19:06:51.032578 1 utils.go:205] ID: 29 GRPC response: {} I0730 19:07:51.057962 1 utils.go:198] ID: 30 GRPC call: /csi.v1.Identity/Probe I0730 19:07:51.058017 1 utils.go:199] ID: 30 GRPC request: {} I0730 19:07:51.058038 1 utils.go:205] ID: 30 GRPC response: {} I0730 19:08:51.042672 1 utils.go:198] ID: 31 GRPC call: /csi.v1.Identity/Probe I0730 19:08:51.042719 1 utils.go:199] ID: 31 GRPC request: {} I0730 19:08:51.042745 1 utils.go:205] ID: 31 GRPC response: {} I0730 19:09:51.056441 1 utils.go:198] ID: 32 GRPC call: /csi.v1.Identity/Probe I0730 19:09:51.056493 1 utils.go:199] ID: 32 GRPC request: {} I0730 19:09:51.056514 1 utils.go:205] ID: 32 GRPC response: {} I0730 19:10:15.063059 1 utils.go:198] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b GRPC call: /csi.v1.Controller/CreateVolume I0730 19:10:15.063318 1 utils.go:199] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b","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:10:15.063502 1 rbd_util.go:1315] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b setting disableInUseChecks: false image features: [layering] mounter: rbd I0730 19:10:15.065951 1 omap.go:89] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[] I0730 19:10:15.104912 1 omap.go:159] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b:8c4b69a8-0b1b-407f-b27b-31240ff7f775]) I0730 19:10:15.115653 1 omap.go:159] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b set omap keys (pool="kube", namespace="", name="csi.volume.8c4b69a8-0b1b-407f-b27b-31240ff7f775"): map[csi.imagename:csi-vol-8c4b69a8-0b1b-407f-b27b-31240ff7f775 csi.volname:pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b csi.volume.owner:openstack]) I0730 19:10:15.115687 1 rbd_journal.go:491] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8c4b69a8-0b1b-407f-b27b-31240ff7f775) and image name (csi-vol-8c4b69a8-0b1b-407f-b27b-31240ff7f775) for request name (pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b) I0730 19:10:15.115762 1 rbd_util.go:423] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b rbd: create kube/csi-vol-8c4b69a8-0b1b-407f-b27b-31240ff7f775 size 10240M (features: [layering]) using mon 10.96.240.200 I0730 19:10:15.115808 1 rbd_util.go:1563] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b setting image options on kube/csi-vol-8c4b69a8-0b1b-407f-b27b-31240ff7f775 I0730 19:10:15.166188 1 controllerserver.go:763] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b created image kube/csi-vol-8c4b69a8-0b1b-407f-b27b-31240ff7f775 backed for request name pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b I0730 19:10:15.210156 1 omap.go:159] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b set omap keys (pool="kube", namespace="", name="csi.volume.8c4b69a8-0b1b-407f-b27b-31240ff7f775"): map[csi.imageid:37c63f5eedec]) I0730 19:10:15.386823 1 utils.go:205] ID: 33 Req-ID: pvc-a4abf95b-887e-4843-b10f-6d9845d58d5b GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-8c4b69a8-0b1b-407f-b27b-31240ff7f775","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8c4b69a8-0b1b-407f-b27b-31240ff7f775"}} I0730 19:10:51.053011 1 utils.go:198] ID: 34 GRPC call: /csi.v1.Identity/Probe I0730 19:10:51.053140 1 utils.go:199] ID: 34 GRPC request: {} I0730 19:10:51.053182 1 utils.go:205] ID: 34 GRPC response: {} I0730 19:11:51.057681 1 utils.go:198] ID: 35 GRPC call: /csi.v1.Identity/Probe I0730 19:11:51.057738 1 utils.go:199] ID: 35 GRPC request: {} I0730 19:11:51.057761 1 utils.go:205] ID: 35 GRPC response: {} I0730 19:12:51.041006 1 utils.go:198] ID: 36 GRPC call: /csi.v1.Identity/Probe I0730 19:12:51.041060 1 utils.go:199] ID: 36 GRPC request: {} I0730 19:12:51.041083 1 utils.go:205] ID: 36 GRPC response: {} I0730 19:13:51.003322 1 utils.go:198] ID: 37 GRPC call: /csi.v1.Identity/Probe I0730 19:13:51.003379 1 utils.go:199] ID: 37 GRPC request: {} I0730 19:13:51.003402 1 utils.go:205] ID: 37 GRPC response: {} I0730 19:14:17.672151 1 utils.go:198] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 GRPC call: /csi.v1.Controller/CreateVolume I0730 19:14:17.672511 1 utils.go:199] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334","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:14:17.673909 1 rbd_util.go:1315] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 setting disableInUseChecks: false image features: [layering] mounter: rbd I0730 19:14:17.675983 1 omap.go:89] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[] I0730 19:14:17.695040 1 omap.go:159] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334:b548eb28-df35-447a-9258-de9d25de3896]) I0730 19:14:17.702216 1 omap.go:159] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 set omap keys (pool="kube", namespace="", name="csi.volume.b548eb28-df35-447a-9258-de9d25de3896"): map[csi.imagename:csi-vol-b548eb28-df35-447a-9258-de9d25de3896 csi.volname:pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 csi.volume.owner:openstack]) I0730 19:14:17.702244 1 rbd_journal.go:491] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b548eb28-df35-447a-9258-de9d25de3896) and image name (csi-vol-b548eb28-df35-447a-9258-de9d25de3896) for request name (pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334) I0730 19:14:17.702325 1 rbd_util.go:423] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 rbd: create kube/csi-vol-b548eb28-df35-447a-9258-de9d25de3896 size 10240M (features: [layering]) using mon 10.96.240.200 I0730 19:14:17.702669 1 rbd_util.go:1563] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 setting image options on kube/csi-vol-b548eb28-df35-447a-9258-de9d25de3896 I0730 19:14:17.735348 1 controllerserver.go:763] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 created image kube/csi-vol-b548eb28-df35-447a-9258-de9d25de3896 backed for request name pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 I0730 19:14:17.765926 1 omap.go:159] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 set omap keys (pool="kube", namespace="", name="csi.volume.b548eb28-df35-447a-9258-de9d25de3896"): map[csi.imageid:37c663e11d99]) I0730 19:14:17.864509 1 utils.go:205] ID: 38 Req-ID: pvc-b6c6aa12-9988-40f9-a28f-3e7eef957334 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-b548eb28-df35-447a-9258-de9d25de3896","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b548eb28-df35-447a-9258-de9d25de3896"}} I0730 19:14:51.057737 1 utils.go:198] ID: 39 GRPC call: /csi.v1.Identity/Probe I0730 19:14:51.057794 1 utils.go:199] ID: 39 GRPC request: {} I0730 19:14:51.057818 1 utils.go:205] ID: 39 GRPC response: {} I0730 19:15:51.001750 1 utils.go:198] ID: 40 GRPC call: /csi.v1.Identity/Probe I0730 19:15:51.001830 1 utils.go:199] ID: 40 GRPC request: {} I0730 19:15:51.001858 1 utils.go:205] ID: 40 GRPC response: {} I0730 19:16:51.001039 1 utils.go:198] ID: 41 GRPC call: /csi.v1.Identity/Probe I0730 19:16:51.001113 1 utils.go:199] ID: 41 GRPC request: {} I0730 19:16:51.001139 1 utils.go:205] ID: 41 GRPC response: {} I0730 19:17:51.016243 1 utils.go:198] ID: 42 GRPC call: /csi.v1.Identity/Probe I0730 19:17:51.016395 1 utils.go:199] ID: 42 GRPC request: {} I0730 19:17:51.016423 1 utils.go:205] ID: 42 GRPC response: {} I0730 19:18:51.021052 1 utils.go:198] ID: 43 GRPC call: /csi.v1.Identity/Probe I0730 19:18:51.021112 1 utils.go:199] ID: 43 GRPC request: {} I0730 19:18:51.021145 1 utils.go:205] ID: 43 GRPC response: {} I0730 19:19:51.022947 1 utils.go:198] ID: 44 GRPC call: /csi.v1.Identity/Probe I0730 19:19:51.023055 1 utils.go:199] ID: 44 GRPC request: {} I0730 19:19:51.023094 1 utils.go:205] ID: 44 GRPC response: {} I0730 19:20:51.000920 1 utils.go:198] ID: 45 GRPC call: /csi.v1.Identity/Probe I0730 19:20:51.000984 1 utils.go:199] ID: 45 GRPC request: {} I0730 19:20:51.001011 1 utils.go:205] ID: 45 GRPC response: {} I0730 19:21:34.695384 1 utils.go:198] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f GRPC call: /csi.v1.Controller/CreateVolume I0730 19:21:34.695590 1 utils.go:199] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f","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:21:34.695772 1 rbd_util.go:1315] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f setting disableInUseChecks: false image features: [layering] mounter: rbd I0730 19:21:34.698156 1 omap.go:89] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[] I0730 19:21:34.712184 1 omap.go:159] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f:c8e7d67a-fc08-42fc-acea-da9c202a95f6]) I0730 19:21:34.719658 1 omap.go:159] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f set omap keys (pool="kube", namespace="", name="csi.volume.c8e7d67a-fc08-42fc-acea-da9c202a95f6"): map[csi.imagename:csi-vol-c8e7d67a-fc08-42fc-acea-da9c202a95f6 csi.volname:pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f csi.volume.owner:openstack]) I0730 19:21:34.719694 1 rbd_journal.go:491] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c8e7d67a-fc08-42fc-acea-da9c202a95f6) and image name (csi-vol-c8e7d67a-fc08-42fc-acea-da9c202a95f6) for request name (pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f) I0730 19:21:34.719775 1 rbd_util.go:423] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f rbd: create kube/csi-vol-c8e7d67a-fc08-42fc-acea-da9c202a95f6 size 10240M (features: [layering]) using mon 10.96.240.200 I0730 19:21:34.719820 1 rbd_util.go:1563] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f setting image options on kube/csi-vol-c8e7d67a-fc08-42fc-acea-da9c202a95f6 I0730 19:21:34.751870 1 controllerserver.go:763] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f created image kube/csi-vol-c8e7d67a-fc08-42fc-acea-da9c202a95f6 backed for request name pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f I0730 19:21:34.781907 1 omap.go:159] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f set omap keys (pool="kube", namespace="", name="csi.volume.c8e7d67a-fc08-42fc-acea-da9c202a95f6"): map[csi.imageid:37c679d9fea4]) I0730 19:21:34.875279 1 utils.go:205] ID: 46 Req-ID: pvc-50c9e85d-1ca1-4ea5-bf36-12fbbbfb072f GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-c8e7d67a-fc08-42fc-acea-da9c202a95f6","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c8e7d67a-fc08-42fc-acea-da9c202a95f6"}} I0730 19:21:51.032037 1 utils.go:198] ID: 47 GRPC call: /csi.v1.Identity/Probe I0730 19:21:51.032126 1 utils.go:199] ID: 47 GRPC request: {} I0730 19:21:51.032159 1 utils.go:205] ID: 47 GRPC response: {} I0730 19:22:51.000783 1 utils.go:198] ID: 48 GRPC call: /csi.v1.Identity/Probe I0730 19:22:51.000837 1 utils.go:199] ID: 48 GRPC request: {} I0730 19:22:51.000860 1 utils.go:205] ID: 48 GRPC response: {} I0730 19:23:51.042782 1 utils.go:198] ID: 49 GRPC call: /csi.v1.Identity/Probe I0730 19:23:51.042881 1 utils.go:199] ID: 49 GRPC request: {} I0730 19:23:51.042916 1 utils.go:205] ID: 49 GRPC response: {} I0730 19:24:51.001638 1 utils.go:198] ID: 50 GRPC call: /csi.v1.Identity/Probe I0730 19:24:51.001698 1 utils.go:199] ID: 50 GRPC request: {} I0730 19:24:51.001721 1 utils.go:205] ID: 50 GRPC response: {} I0730 19:25:51.053096 1 utils.go:198] ID: 51 GRPC call: /csi.v1.Identity/Probe I0730 19:25:51.053169 1 utils.go:199] ID: 51 GRPC request: {} I0730 19:25:51.053193 1 utils.go:205] ID: 51 GRPC response: {} I0730 19:26:51.000860 1 utils.go:198] ID: 52 GRPC call: /csi.v1.Identity/Probe I0730 19:26:51.000914 1 utils.go:199] ID: 52 GRPC request: {} I0730 19:26:51.000937 1 utils.go:205] ID: 52 GRPC response: {} I0730 19:27:51.057312 1 utils.go:198] ID: 53 GRPC call: /csi.v1.Identity/Probe I0730 19:27:51.057374 1 utils.go:199] ID: 53 GRPC request: {} I0730 19:27:51.057401 1 utils.go:205] ID: 53 GRPC response: {} I0730 19:28:18.572333 1 utils.go:198] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 GRPC call: /csi.v1.Controller/CreateVolume I0730 19:28:18.572529 1 utils.go:199] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 GRPC request: {"capacity_range":{"required_bytes":10737418240},"name":"pvc-20f506ed-4ba6-4044-9e43-c47b41df0392","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-20f506ed-4ba6-4044-9e43-c47b41df0392","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:28:18.572713 1 rbd_util.go:1315] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 setting disableInUseChecks: false image features: [layering] mounter: rbd I0730 19:28:18.575320 1 omap.go:89] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[] I0730 19:28:18.590902 1 omap.go:159] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-20f506ed-4ba6-4044-9e43-c47b41df0392:aa22d75a-9ea5-448d-9e62-90851331a7c2]) I0730 19:28:18.598446 1 omap.go:159] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 set omap keys (pool="kube", namespace="", name="csi.volume.aa22d75a-9ea5-448d-9e62-90851331a7c2"): map[csi.imagename:csi-vol-aa22d75a-9ea5-448d-9e62-90851331a7c2 csi.volname:pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 csi.volume.owner:openstack]) I0730 19:28:18.598493 1 rbd_journal.go:491] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-aa22d75a-9ea5-448d-9e62-90851331a7c2) and image name (csi-vol-aa22d75a-9ea5-448d-9e62-90851331a7c2) for request name (pvc-20f506ed-4ba6-4044-9e43-c47b41df0392) I0730 19:28:18.598598 1 rbd_util.go:423] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 rbd: create kube/csi-vol-aa22d75a-9ea5-448d-9e62-90851331a7c2 size 10240M (features: [layering]) using mon 10.96.240.200 I0730 19:28:18.598648 1 rbd_util.go:1563] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 setting image options on kube/csi-vol-aa22d75a-9ea5-448d-9e62-90851331a7c2 I0730 19:28:18.626563 1 controllerserver.go:763] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 created image kube/csi-vol-aa22d75a-9ea5-448d-9e62-90851331a7c2 backed for request name pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 I0730 19:28:18.658664 1 omap.go:159] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 set omap keys (pool="kube", namespace="", name="csi.volume.aa22d75a-9ea5-448d-9e62-90851331a7c2"): map[csi.imageid:37c6a6a21c18]) I0730 19:28:18.755535 1 utils.go:205] ID: 54 Req-ID: pvc-20f506ed-4ba6-4044-9e43-c47b41df0392 GRPC response: {"volume":{"capacity_bytes":10737418240,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-aa22d75a-9ea5-448d-9e62-90851331a7c2","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-aa22d75a-9ea5-448d-9e62-90851331a7c2"}} I0730 19:28:51.003639 1 utils.go:198] ID: 55 GRPC call: /csi.v1.Identity/Probe I0730 19:28:51.003780 1 utils.go:199] ID: 55 GRPC request: {} I0730 19:28:51.003821 1 utils.go:205] ID: 55 GRPC response: {} I0730 19:29:51.057138 1 utils.go:198] ID: 56 GRPC call: /csi.v1.Identity/Probe I0730 19:29:51.057190 1 utils.go:199] ID: 56 GRPC request: {} I0730 19:29:51.057212 1 utils.go:205] ID: 56 GRPC response: {} I0730 19:30:51.003452 1 utils.go:198] ID: 57 GRPC call: /csi.v1.Identity/Probe I0730 19:30:51.003508 1 utils.go:199] ID: 57 GRPC request: {} I0730 19:30:51.003530 1 utils.go:205] ID: 57 GRPC response: {} I0730 19:31:51.000916 1 utils.go:198] ID: 58 GRPC call: /csi.v1.Identity/Probe I0730 19:31:51.000977 1 utils.go:199] ID: 58 GRPC request: {} I0730 19:31:51.001006 1 utils.go:205] ID: 58 GRPC response: {} I0730 19:32:51.011193 1 utils.go:198] ID: 59 GRPC call: /csi.v1.Identity/Probe I0730 19:32:51.011252 1 utils.go:199] ID: 59 GRPC request: {} I0730 19:32:51.011277 1 utils.go:205] ID: 59 GRPC response: {} I0730 19:33:51.000926 1 utils.go:198] ID: 60 GRPC call: /csi.v1.Identity/Probe I0730 19:33:51.000984 1 utils.go:199] ID: 60 GRPC request: {} I0730 19:33:51.001006 1 utils.go:205] ID: 60 GRPC response: {} I0730 19:34:51.021984 1 utils.go:198] ID: 61 GRPC call: /csi.v1.Identity/Probe I0730 19:34:51.022050 1 utils.go:199] ID: 61 GRPC request: {} I0730 19:34:51.022076 1 utils.go:205] ID: 61 GRPC response: {} I0730 19:35:51.000933 1 utils.go:198] ID: 62 GRPC call: /csi.v1.Identity/Probe I0730 19:35:51.000989 1 utils.go:199] ID: 62 GRPC request: {} I0730 19:35:51.001010 1 utils.go:205] ID: 62 GRPC response: {} I0730 19:36:51.033212 1 utils.go:198] ID: 63 GRPC call: /csi.v1.Identity/Probe I0730 19:36:51.033308 1 utils.go:199] ID: 63 GRPC request: {} I0730 19:36:51.033344 1 utils.go:205] ID: 63 GRPC response: {} I0730 19:37:51.000785 1 utils.go:198] ID: 64 GRPC call: /csi.v1.Identity/Probe I0730 19:37:51.000852 1 utils.go:199] ID: 64 GRPC request: {} I0730 19:37:51.000882 1 utils.go:205] ID: 64 GRPC response: {} I0730 19:38:51.053817 1 utils.go:198] ID: 65 GRPC call: /csi.v1.Identity/Probe I0730 19:38:51.054041 1 utils.go:199] ID: 65 GRPC request: {} I0730 19:38:51.054166 1 utils.go:205] ID: 65 GRPC response: {} I0730 19:39:51.057262 1 utils.go:198] ID: 66 GRPC call: /csi.v1.Identity/Probe I0730 19:39:51.057337 1 utils.go:199] ID: 66 GRPC request: {} I0730 19:39:51.057367 1 utils.go:205] ID: 66 GRPC response: {} I0730 19:40:51.057296 1 utils.go:198] ID: 67 GRPC call: /csi.v1.Identity/Probe I0730 19:40:51.057402 1 utils.go:199] ID: 67 GRPC request: {} I0730 19:40:51.057461 1 utils.go:205] ID: 67 GRPC response: {} I0730 19:41:51.000814 1 utils.go:198] ID: 68 GRPC call: /csi.v1.Identity/Probe I0730 19:41:51.000868 1 utils.go:199] ID: 68 GRPC request: {} I0730 19:41:51.000890 1 utils.go:205] ID: 68 GRPC response: {} I0730 19:42:51.061442 1 utils.go:198] ID: 69 GRPC call: /csi.v1.Identity/Probe I0730 19:42:51.061543 1 utils.go:199] ID: 69 GRPC request: {} I0730 19:42:51.061581 1 utils.go:205] ID: 69 GRPC response: {} I0730 19:43:51.000743 1 utils.go:198] ID: 70 GRPC call: /csi.v1.Identity/Probe I0730 19:43:51.000801 1 utils.go:199] ID: 70 GRPC request: {} I0730 19:43:51.000824 1 utils.go:205] ID: 70 GRPC response: {} I0730 19:44:51.056394 1 utils.go:198] ID: 71 GRPC call: /csi.v1.Identity/Probe I0730 19:44:51.056448 1 utils.go:199] ID: 71 GRPC request: {} I0730 19:44:51.056470 1 utils.go:205] ID: 71 GRPC response: {} I0730 19:45:51.001995 1 utils.go:198] ID: 72 GRPC call: /csi.v1.Identity/Probe I0730 19:45:51.002064 1 utils.go:199] ID: 72 GRPC request: {} I0730 19:45:51.002092 1 utils.go:205] ID: 72 GRPC response: {} I0730 19:46:51.000725 1 utils.go:198] ID: 73 GRPC call: /csi.v1.Identity/Probe I0730 19:46:51.000759 1 utils.go:199] ID: 73 GRPC request: {} I0730 19:46:51.000775 1 utils.go:205] ID: 73 GRPC response: {}