I0720 19:04:30.176982       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0720 19:04:30.177186       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0720 19:04:30.177211       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0720 19:04:30.177218       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0720 19:04:30.177224       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0720 19:04:30.177230       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0720 19:04:30.177237       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0720 19:04:30.177243       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0720 19:04:30.177248       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0720 19:04:30.177254       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0720 19:04:30.177605       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0720 19:04:30.177674       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0720 19:04:33.377650       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:33.378739       1 utils.go:199] ID: 1 GRPC request: {}
I0720 19:04:33.378809       1 utils.go:205] ID: 1 GRPC response: {}
I0720 19:04:33.380973       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0720 19:04:33.381074       1 utils.go:199] ID: 2 GRPC request: {}
I0720 19:04:33.381101       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0720 19:04:33.381174       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0720 19:04:33.384803       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:33.384866       1 utils.go:199] ID: 3 GRPC request: {}
I0720 19:04:33.384899       1 utils.go:205] ID: 3 GRPC response: {}
I0720 19:04:33.385338       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0720 19:04:33.385399       1 utils.go:199] ID: 4 GRPC request: {}
I0720 19:04:33.387743       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0720 19:04:33.388487       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0720 19:04:33.388675       1 utils.go:199] ID: 5 GRPC request: {}
I0720 19:04:33.388689       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0720 19:04:33.388897       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}}}]}
I0720 19:04:35.104604       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:35.104668       1 utils.go:199] ID: 6 GRPC request: {}
I0720 19:04:35.104699       1 utils.go:205] ID: 6 GRPC response: {}
I0720 19:04:35.107058       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0720 19:04:35.107096       1 utils.go:199] ID: 7 GRPC request: {}
I0720 19:04:35.107105       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0720 19:04:35.107134       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0720 19:04:35.109016       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:35.109046       1 utils.go:199] ID: 8 GRPC request: {}
I0720 19:04:35.109068       1 utils.go:205] ID: 8 GRPC response: {}
I0720 19:04:35.109483       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0720 19:04:35.109581       1 utils.go:199] ID: 9 GRPC request: {}
I0720 19:04:35.109759       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0720 19:04:35.110333       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0720 19:04:35.110381       1 utils.go:199] ID: 10 GRPC request: {}
I0720 19:04:35.110455       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0720 19:04:35.110588       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}}}]}
I0720 19:04:35.111040       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0720 19:04:35.111082       1 utils.go:199] ID: 11 GRPC request: {}
I0720 19:04:35.111101       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0720 19:04:35.111188       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}}}]}
I0720 19:04:35.111485       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0720 19:04:35.111526       1 utils.go:199] ID: 12 GRPC request: {}
I0720 19:04:35.111546       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0720 19:04:35.111672       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}}}]}
I0720 19:04:36.760992       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0720 19:04:36.761026       1 utils.go:199] ID: 13 GRPC request: {}
I0720 19:04:36.761033       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0720 19:04:36.761054       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0720 19:04:36.762908       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:36.762925       1 utils.go:199] ID: 14 GRPC request: {}
I0720 19:04:36.762936       1 utils.go:205] ID: 14 GRPC response: {}
I0720 19:04:36.763330       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0720 19:04:36.763376       1 utils.go:199] ID: 15 GRPC request: {}
I0720 19:04:36.763385       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0720 19:04:36.763503       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}}}]}
I0720 19:04:39.454014       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:39.454054       1 utils.go:199] ID: 16 GRPC request: {}
I0720 19:04:39.454073       1 utils.go:205] ID: 16 GRPC response: {}
I0720 19:04:39.461227       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0720 19:04:39.461298       1 utils.go:199] ID: 17 GRPC request: {}
I0720 19:04:39.461322       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0720 19:04:39.461377       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0720 19:04:39.463205       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0720 19:04:39.463240       1 utils.go:199] ID: 18 GRPC request: {}
I0720 19:04:39.463257       1 utils.go:205] ID: 18 GRPC response: {}
I0720 19:04:39.463696       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0720 19:04:39.463753       1 utils.go:199] ID: 19 GRPC request: {}
I0720 19:04:39.463899       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0720 19:04:39.464573       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0720 19:04:39.464613       1 utils.go:199] ID: 20 GRPC request: {}
I0720 19:04:39.464623       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0720 19:04:39.464712       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}}}]}
I0720 19:05:39.673382       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0720 19:05:39.673430       1 utils.go:199] ID: 21 GRPC request: {}
I0720 19:05:39.673447       1 utils.go:205] ID: 21 GRPC response: {}
I0720 19:06:39.672995       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0720 19:06:39.676744       1 utils.go:199] ID: 22 GRPC request: {}
I0720 19:06:39.676765       1 utils.go:205] ID: 22 GRPC response: {}
I0720 19:07:39.672614       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0720 19:07:39.672690       1 utils.go:199] ID: 23 GRPC request: {}
I0720 19:07:39.672713       1 utils.go:205] ID: 23 GRPC response: {}
I0720 19:08:36.184108       1 utils.go:198] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c GRPC call: /csi.v1.Controller/CreateVolume
I0720 19:08:36.184549       1 utils.go:199] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c63ff60c-d119-4f57-874a-8558f95b721c","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c63ff60c-d119-4f57-874a-8558f95b721c","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","pool":"kube"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0720 19:08:36.184887       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c setting disableInUseChecks: false image features: [layering] mounter: rbd
E0720 19:08:36.204358       1 omap.go:80] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0720 19:08:36.220919       1 omap.go:159] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c63ff60c-d119-4f57-874a-8558f95b721c:6da664c7-b890-469f-b56e-fd8922f12791])
I0720 19:08:36.227227       1 omap.go:159] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c set omap keys (pool="kube", namespace="", name="csi.volume.6da664c7-b890-469f-b56e-fd8922f12791"): map[csi.imagename:csi-vol-6da664c7-b890-469f-b56e-fd8922f12791 csi.volname:pvc-c63ff60c-d119-4f57-874a-8558f95b721c csi.volume.owner:default])
I0720 19:08:36.227269       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6da664c7-b890-469f-b56e-fd8922f12791) and image name (csi-vol-6da664c7-b890-469f-b56e-fd8922f12791) for request name (pvc-c63ff60c-d119-4f57-874a-8558f95b721c)
I0720 19:08:36.227472       1 rbd_util.go:423] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c rbd: create kube/csi-vol-6da664c7-b890-469f-b56e-fd8922f12791 size 5120M (features: [layering]) using mon 199.204.45.74
I0720 19:08:36.227585       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c setting image options on kube/csi-vol-6da664c7-b890-469f-b56e-fd8922f12791
I0720 19:08:36.266459       1 controllerserver.go:763] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c created image kube/csi-vol-6da664c7-b890-469f-b56e-fd8922f12791 backed for request name pvc-c63ff60c-d119-4f57-874a-8558f95b721c
I0720 19:08:36.303162       1 omap.go:159] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c set omap keys (pool="kube", namespace="", name="csi.volume.6da664c7-b890-469f-b56e-fd8922f12791"): map[csi.imageid:37f43ce5c602])
I0720 19:08:36.407252       1 utils.go:205] ID: 24 Req-ID: pvc-c63ff60c-d119-4f57-874a-8558f95b721c GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-6da664c7-b890-469f-b56e-fd8922f12791","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6da664c7-b890-469f-b56e-fd8922f12791"}}
I0720 19:08:39.683993       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0720 19:08:39.684098       1 utils.go:199] ID: 25 GRPC request: {}
I0720 19:08:39.684149       1 utils.go:205] ID: 25 GRPC response: {}