I0702 15:31:26.628774       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0702 15:31:26.628906       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0702 15:31:26.628917       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0702 15:31:26.628921       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0702 15:31:26.628924       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0702 15:31:26.628928       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0702 15:31:26.628931       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0702 15:31:26.628934       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0702 15:31:26.628938       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0702 15:31:26.628942       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0702 15:31:26.629177       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0702 15:31:26.629250       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0702 15:31:33.886379       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:33.887441       1 utils.go:199] ID: 1 GRPC request: {}
I0702 15:31:33.887487       1 utils.go:205] ID: 1 GRPC response: {}
I0702 15:31:33.890971       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0702 15:31:33.891082       1 utils.go:199] ID: 2 GRPC request: {}
I0702 15:31:33.891111       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0702 15:31:33.891201       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0702 15:31:33.892950       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:33.892976       1 utils.go:199] ID: 3 GRPC request: {}
I0702 15:31:33.892991       1 utils.go:205] ID: 3 GRPC response: {}
I0702 15:31:33.893396       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0702 15:31:33.893468       1 utils.go:199] ID: 4 GRPC request: {}
I0702 15:31:33.893625       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0702 15:31:33.894496       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0702 15:31:33.894600       1 utils.go:199] ID: 5 GRPC request: {}
I0702 15:31:33.894621       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0702 15:31:33.894771       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}}}]}
I0702 15:31:37.076018       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:37.076091       1 utils.go:199] ID: 6 GRPC request: {}
I0702 15:31:37.076118       1 utils.go:205] ID: 6 GRPC response: {}
I0702 15:31:37.080313       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0702 15:31:37.080372       1 utils.go:199] ID: 7 GRPC request: {}
I0702 15:31:37.080399       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0702 15:31:37.080549       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0702 15:31:37.082185       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:37.082214       1 utils.go:199] ID: 8 GRPC request: {}
I0702 15:31:37.082368       1 utils.go:205] ID: 8 GRPC response: {}
I0702 15:31:37.082980       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0702 15:31:37.082994       1 utils.go:199] ID: 9 GRPC request: {}
I0702 15:31:37.083063       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0702 15:31:37.083664       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0702 15:31:37.083683       1 utils.go:199] ID: 10 GRPC request: {}
I0702 15:31:37.083689       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0702 15:31:37.083769       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}}}]}
I0702 15:31:37.084215       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0702 15:31:37.084243       1 utils.go:199] ID: 11 GRPC request: {}
I0702 15:31:37.084251       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0702 15:31:37.084333       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}}}]}
I0702 15:31:37.084719       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0702 15:31:37.084737       1 utils.go:199] ID: 12 GRPC request: {}
I0702 15:31:37.084745       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0702 15:31:37.084798       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}}}]}
I0702 15:31:39.309503       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0702 15:31:39.309545       1 utils.go:199] ID: 13 GRPC request: {}
I0702 15:31:39.309557       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0702 15:31:39.309587       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0702 15:31:39.313321       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:39.313340       1 utils.go:199] ID: 14 GRPC request: {}
I0702 15:31:39.313353       1 utils.go:205] ID: 14 GRPC response: {}
I0702 15:31:39.313770       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0702 15:31:39.313797       1 utils.go:199] ID: 15 GRPC request: {}
I0702 15:31:39.313804       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0702 15:31:39.313896       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}}}]}
I0702 15:31:41.105967       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:41.106013       1 utils.go:199] ID: 16 GRPC request: {}
I0702 15:31:41.106036       1 utils.go:205] ID: 16 GRPC response: {}
I0702 15:31:41.108252       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0702 15:31:41.108409       1 utils.go:199] ID: 17 GRPC request: {}
I0702 15:31:41.108427       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0702 15:31:41.108480       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0702 15:31:41.110415       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0702 15:31:41.110442       1 utils.go:199] ID: 18 GRPC request: {}
I0702 15:31:41.110458       1 utils.go:205] ID: 18 GRPC response: {}
I0702 15:31:41.110822       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0702 15:31:41.110851       1 utils.go:199] ID: 19 GRPC request: {}
I0702 15:31:41.110995       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0702 15:31:41.111678       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0702 15:31:41.111715       1 utils.go:199] ID: 20 GRPC request: {}
I0702 15:31:41.111724       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0702 15:31:41.112469       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}}}]}
I0702 15:32:41.292130       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0702 15:32:41.292187       1 utils.go:199] ID: 21 GRPC request: {}
I0702 15:32:41.292217       1 utils.go:205] ID: 21 GRPC response: {}
I0702 15:33:41.293566       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0702 15:33:41.294223       1 utils.go:199] ID: 22 GRPC request: {}
I0702 15:33:41.294283       1 utils.go:205] ID: 22 GRPC response: {}
I0702 15:34:41.291811       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0702 15:34:41.291871       1 utils.go:199] ID: 23 GRPC request: {}
I0702 15:34:41.291893       1 utils.go:205] ID: 23 GRPC response: {}
I0702 15:35:22.944317       1 utils.go:198] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 GRPC call: /csi.v1.Controller/CreateVolume
I0702 15:35:22.944774       1 utils.go:199] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-012843f7-9df0-4a9a-9683-3c6b64335c82","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-012843f7-9df0-4a9a-9683-3c6b64335c82","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}}]}
I0702 15:35:22.945001       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0702 15:35:22.969134       1 omap.go:80] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0702 15:35:22.989764       1 omap.go:159] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-012843f7-9df0-4a9a-9683-3c6b64335c82:8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a])
I0702 15:35:22.997055       1 omap.go:159] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 set omap keys (pool="kube", namespace="", name="csi.volume.8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a"): map[csi.imagename:csi-vol-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a csi.volname:pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 csi.volume.owner:default])
I0702 15:35:22.997103       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a) and image name (csi-vol-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a) for request name (pvc-012843f7-9df0-4a9a-9683-3c6b64335c82)
I0702 15:35:22.997227       1 rbd_util.go:423] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 rbd: create kube/csi-vol-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a size 5120M (features: [layering]) using mon 199.19.213.152
I0702 15:35:22.997323       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 setting image options on kube/csi-vol-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a
I0702 15:35:23.026900       1 controllerserver.go:763] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 created image kube/csi-vol-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a backed for request name pvc-012843f7-9df0-4a9a-9683-3c6b64335c82
I0702 15:35:23.094791       1 omap.go:159] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 set omap keys (pool="kube", namespace="", name="csi.volume.8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a"): map[csi.imageid:37ecdfbbafc5])
I0702 15:35:23.233774       1 utils.go:205] ID: 24 Req-ID: pvc-012843f7-9df0-4a9a-9683-3c6b64335c82 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8b1ec1e0-e64e-40bb-ae2a-f3fdfa84ba8a"}}
I0702 15:35:41.303094       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0702 15:35:41.303421       1 utils.go:199] ID: 25 GRPC request: {}
I0702 15:35:41.303474       1 utils.go:205] ID: 25 GRPC response: {}