I0606 17:05:32.673139       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0606 17:05:32.673242       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0606 17:05:32.673254       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0606 17:05:32.673257       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0606 17:05:32.673260       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0606 17:05:32.673263       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0606 17:05:32.673266       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0606 17:05:32.673269       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0606 17:05:32.673271       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0606 17:05:32.673274       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0606 17:05:32.673519       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0606 17:05:32.673640       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0606 17:05:36.933282       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:36.935387       1 utils.go:199] ID: 1 GRPC request: {}
I0606 17:05:36.935459       1 utils.go:205] ID: 1 GRPC response: {}
I0606 17:05:36.938321       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0606 17:05:36.938644       1 utils.go:199] ID: 2 GRPC request: {}
I0606 17:05:36.938673       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0606 17:05:36.938746       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0606 17:05:36.945153       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:36.945296       1 utils.go:199] ID: 3 GRPC request: {}
I0606 17:05:36.945340       1 utils.go:205] ID: 3 GRPC response: {}
I0606 17:05:36.945904       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0606 17:05:36.945947       1 utils.go:199] ID: 4 GRPC request: {}
I0606 17:05:36.946166       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0606 17:05:36.947883       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0606 17:05:36.947920       1 utils.go:199] ID: 5 GRPC request: {}
I0606 17:05:36.947927       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0606 17:05:36.948001       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}}}]}
I0606 17:05:38.712937       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:38.713064       1 utils.go:199] ID: 6 GRPC request: {}
I0606 17:05:38.713092       1 utils.go:205] ID: 6 GRPC response: {}
I0606 17:05:38.715772       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0606 17:05:38.715809       1 utils.go:199] ID: 7 GRPC request: {}
I0606 17:05:38.715817       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0606 17:05:38.715841       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0606 17:05:38.718228       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:38.718250       1 utils.go:199] ID: 8 GRPC request: {}
I0606 17:05:38.718264       1 utils.go:205] ID: 8 GRPC response: {}
I0606 17:05:38.718768       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0606 17:05:38.718793       1 utils.go:199] ID: 9 GRPC request: {}
I0606 17:05:38.718870       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0606 17:05:38.719513       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0606 17:05:38.719616       1 utils.go:199] ID: 10 GRPC request: {}
I0606 17:05:38.719648       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0606 17:05:38.719780       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}}}]}
I0606 17:05:38.720332       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0606 17:05:38.720353       1 utils.go:199] ID: 11 GRPC request: {}
I0606 17:05:38.720361       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0606 17:05:38.720456       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}}}]}
I0606 17:05:38.720777       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0606 17:05:38.720795       1 utils.go:199] ID: 12 GRPC request: {}
I0606 17:05:38.720801       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0606 17:05:38.720849       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}}}]}
I0606 17:05:41.897098       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0606 17:05:41.897139       1 utils.go:199] ID: 13 GRPC request: {}
I0606 17:05:41.897148       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0606 17:05:41.897210       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0606 17:05:41.900249       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:41.900266       1 utils.go:199] ID: 14 GRPC request: {}
I0606 17:05:41.900280       1 utils.go:205] ID: 14 GRPC response: {}
I0606 17:05:41.900697       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0606 17:05:41.900713       1 utils.go:199] ID: 15 GRPC request: {}
I0606 17:05:41.900719       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0606 17:05:41.900777       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}}}]}
I0606 17:05:43.825584       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:43.825667       1 utils.go:199] ID: 16 GRPC request: {}
I0606 17:05:43.825700       1 utils.go:205] ID: 16 GRPC response: {}
I0606 17:05:43.829294       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0606 17:05:43.829330       1 utils.go:199] ID: 17 GRPC request: {}
I0606 17:05:43.829338       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0606 17:05:43.829375       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0606 17:05:43.831467       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0606 17:05:43.831524       1 utils.go:199] ID: 18 GRPC request: {}
I0606 17:05:43.831544       1 utils.go:205] ID: 18 GRPC response: {}
I0606 17:05:43.831979       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0606 17:05:43.832000       1 utils.go:199] ID: 19 GRPC request: {}
I0606 17:05:43.832210       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0606 17:05:43.832791       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0606 17:05:43.832816       1 utils.go:199] ID: 20 GRPC request: {}
I0606 17:05:43.832825       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0606 17:05:43.832910       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}}}]}
I0606 17:06:44.010924       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0606 17:06:44.010978       1 utils.go:199] ID: 21 GRPC request: {}
I0606 17:06:44.011001       1 utils.go:205] ID: 21 GRPC response: {}
I0606 17:07:43.976339       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0606 17:07:43.976456       1 utils.go:199] ID: 22 GRPC request: {}
I0606 17:07:43.976523       1 utils.go:205] ID: 22 GRPC response: {}
I0606 17:08:43.971230       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0606 17:08:43.971286       1 utils.go:199] ID: 23 GRPC request: {}
I0606 17:08:43.971303       1 utils.go:205] ID: 23 GRPC response: {}
I0606 17:09:42.352039       1 utils.go:198] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd GRPC call: /csi.v1.Controller/CreateVolume
I0606 17:09:42.352437       1 utils.go:199] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd","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}}]}
I0606 17:09:42.352741       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd setting disableInUseChecks: false image features: [layering] mounter: rbd
E0606 17:09:42.368898       1 omap.go:80] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0606 17:09:42.384908       1 omap.go:159] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd:4d87cf6e-3b28-4603-a672-55ca6c753586])
I0606 17:09:42.390851       1 omap.go:159] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd set omap keys (pool="kube", namespace="", name="csi.volume.4d87cf6e-3b28-4603-a672-55ca6c753586"): map[csi.imagename:csi-vol-4d87cf6e-3b28-4603-a672-55ca6c753586 csi.volname:pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd csi.volume.owner:default])
I0606 17:09:42.390892       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-4d87cf6e-3b28-4603-a672-55ca6c753586) and image name (csi-vol-4d87cf6e-3b28-4603-a672-55ca6c753586) for request name (pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd)
I0606 17:09:42.390980       1 rbd_util.go:423] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd rbd: create kube/csi-vol-4d87cf6e-3b28-4603-a672-55ca6c753586 size 5120M (features: [layering]) using mon 199.204.45.89
I0606 17:09:42.391031       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd setting image options on kube/csi-vol-4d87cf6e-3b28-4603-a672-55ca6c753586
I0606 17:09:42.421911       1 controllerserver.go:763] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd created image kube/csi-vol-4d87cf6e-3b28-4603-a672-55ca6c753586 backed for request name pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd
I0606 17:09:42.453203       1 omap.go:159] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd set omap keys (pool="kube", namespace="", name="csi.volume.4d87cf6e-3b28-4603-a672-55ca6c753586"): map[csi.imageid:37ec9a745a73])
I0606 17:09:42.543320       1 utils.go:205] ID: 24 Req-ID: pvc-5f520417-0dd8-42c7-9fcf-a1fd45d6a3bd GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-4d87cf6e-3b28-4603-a672-55ca6c753586","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-4d87cf6e-3b28-4603-a672-55ca6c753586"}}
I0606 17:09:43.982376       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0606 17:09:43.982490       1 utils.go:199] ID: 25 GRPC request: {}
I0606 17:09:43.982530       1 utils.go:205] ID: 25 GRPC response: {}