I0218 10:13:57.075187       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0218 10:13:57.075288       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0218 10:13:57.075302       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0218 10:13:57.075307       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0218 10:13:57.075310       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0218 10:13:57.075443       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0218 10:13:57.075447       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0218 10:13:57.075456       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0218 10:13:57.075459       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0218 10:13:57.075462       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0218 10:13:57.077334       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0218 10:13:57.077758       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0218 10:14:04.080126       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:04.081221       1 utils.go:199] ID: 1 GRPC request: {}
I0218 10:14:04.081319       1 utils.go:205] ID: 1 GRPC response: {}
I0218 10:14:04.083194       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0218 10:14:04.083333       1 utils.go:199] ID: 2 GRPC request: {}
I0218 10:14:04.083392       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0218 10:14:04.083470       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0218 10:14:04.084498       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:04.084553       1 utils.go:199] ID: 3 GRPC request: {}
I0218 10:14:04.084580       1 utils.go:205] ID: 3 GRPC response: {}
I0218 10:14:04.084801       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0218 10:14:04.084880       1 utils.go:199] ID: 4 GRPC request: {}
I0218 10:14:04.085085       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0218 10:14:04.087821       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0218 10:14:04.087880       1 utils.go:199] ID: 5 GRPC request: {}
I0218 10:14:04.087927       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0218 10:14:04.088050       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}}}]}
I0218 10:14:06.481752       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:06.481879       1 utils.go:199] ID: 6 GRPC request: {}
I0218 10:14:06.481952       1 utils.go:205] ID: 6 GRPC response: {}
I0218 10:14:06.491211       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0218 10:14:06.491271       1 utils.go:199] ID: 7 GRPC request: {}
I0218 10:14:06.491294       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0218 10:14:06.491341       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0218 10:14:06.493777       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:06.493812       1 utils.go:199] ID: 8 GRPC request: {}
I0218 10:14:06.493829       1 utils.go:205] ID: 8 GRPC response: {}
I0218 10:14:06.494304       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0218 10:14:06.494320       1 utils.go:199] ID: 9 GRPC request: {}
I0218 10:14:06.494404       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0218 10:14:06.496608       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0218 10:14:06.496625       1 utils.go:199] ID: 10 GRPC request: {}
I0218 10:14:06.496632       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0218 10:14:06.496705       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}}}]}
I0218 10:14:06.498143       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0218 10:14:06.498160       1 utils.go:199] ID: 11 GRPC request: {}
I0218 10:14:06.498164       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0218 10:14:06.498209       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}}}]}
I0218 10:14:06.498587       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0218 10:14:06.498602       1 utils.go:199] ID: 12 GRPC request: {}
I0218 10:14:06.498607       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0218 10:14:06.498649       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}}}]}
I0218 10:14:08.648361       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0218 10:14:08.648515       1 utils.go:199] ID: 13 GRPC request: {}
I0218 10:14:08.648582       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0218 10:14:08.648658       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0218 10:14:08.652976       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:08.653116       1 utils.go:199] ID: 14 GRPC request: {}
I0218 10:14:08.653207       1 utils.go:205] ID: 14 GRPC response: {}
I0218 10:14:08.653705       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0218 10:14:08.653803       1 utils.go:199] ID: 15 GRPC request: {}
I0218 10:14:08.653850       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0218 10:14:08.654011       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}}}]}
I0218 10:14:10.866198       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:10.866231       1 utils.go:199] ID: 16 GRPC request: {}
I0218 10:14:10.866243       1 utils.go:205] ID: 16 GRPC response: {}
I0218 10:14:10.873880       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0218 10:14:10.873936       1 utils.go:199] ID: 17 GRPC request: {}
I0218 10:14:10.873949       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0218 10:14:10.873989       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0218 10:14:10.877886       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0218 10:14:10.877928       1 utils.go:199] ID: 18 GRPC request: {}
I0218 10:14:10.877942       1 utils.go:205] ID: 18 GRPC response: {}
I0218 10:14:10.878224       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0218 10:14:10.878272       1 utils.go:199] ID: 19 GRPC request: {}
I0218 10:14:10.878357       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0218 10:14:10.878898       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0218 10:14:10.878925       1 utils.go:199] ID: 20 GRPC request: {}
I0218 10:14:10.878941       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0218 10:14:10.879015       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}}}]}
I0218 10:15:11.202390       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0218 10:15:11.202562       1 utils.go:199] ID: 21 GRPC request: {}
I0218 10:15:11.202641       1 utils.go:205] ID: 21 GRPC response: {}
I0218 10:16:11.188344       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0218 10:16:11.188377       1 utils.go:199] ID: 22 GRPC request: {}
I0218 10:16:11.188408       1 utils.go:205] ID: 22 GRPC response: {}
I0218 10:17:11.192301       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0218 10:17:11.192338       1 utils.go:199] ID: 23 GRPC request: {}
I0218 10:17:11.192352       1 utils.go:205] ID: 23 GRPC response: {}
I0218 10:17:18.396076       1 utils.go:198] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f GRPC call: /csi.v1.Controller/CreateVolume
I0218 10:17:18.396753       1 utils.go:199] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f","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}}]}
I0218 10:17:18.397945       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f setting disableInUseChecks: false image features: [layering] mounter: rbd
E0218 10:17:18.421375       1 omap.go:80] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0218 10:17:18.436281       1 omap.go:159] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f:37e53f4b-bb83-4e45-af90-ea688a538e4d])
I0218 10:17:18.444347       1 omap.go:159] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f set omap keys (pool="kube", namespace="", name="csi.volume.37e53f4b-bb83-4e45-af90-ea688a538e4d"): map[csi.imagename:csi-vol-37e53f4b-bb83-4e45-af90-ea688a538e4d csi.volname:pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f csi.volume.owner:default])
I0218 10:17:18.444389       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-37e53f4b-bb83-4e45-af90-ea688a538e4d) and image name (csi-vol-37e53f4b-bb83-4e45-af90-ea688a538e4d) for request name (pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f)
I0218 10:17:18.444628       1 rbd_util.go:423] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f rbd: create kube/csi-vol-37e53f4b-bb83-4e45-af90-ea688a538e4d size 5120M (features: [layering]) using mon 199.19.213.157
I0218 10:17:18.444974       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f setting image options on kube/csi-vol-37e53f4b-bb83-4e45-af90-ea688a538e4d
I0218 10:17:19.610741       1 controllerserver.go:763] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f created image kube/csi-vol-37e53f4b-bb83-4e45-af90-ea688a538e4d backed for request name pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f
I0218 10:17:19.647360       1 omap.go:159] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f set omap keys (pool="kube", namespace="", name="csi.volume.37e53f4b-bb83-4e45-af90-ea688a538e4d"): map[csi.imageid:37d98525958b])
I0218 10:17:19.751207       1 utils.go:205] ID: 24 Req-ID: pvc-5eb088f4-a3b5-47eb-aacf-dd22a7924a4f GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-37e53f4b-bb83-4e45-af90-ea688a538e4d","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-37e53f4b-bb83-4e45-af90-ea688a538e4d"}}
I0218 10:18:11.135068       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0218 10:18:11.138762       1 utils.go:199] ID: 25 GRPC request: {}
I0218 10:18:11.138891       1 utils.go:205] ID: 25 GRPC response: {}