I0427 12:07:40.704313       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0427 12:07:40.704437       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0427 12:07:40.704455       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0427 12:07:40.704458       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0427 12:07:40.704461       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0427 12:07:40.704463       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0427 12:07:40.704466       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0427 12:07:40.704469       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0427 12:07:40.704472       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0427 12:07:40.704474       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0427 12:07:40.704710       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0427 12:07:40.704761       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0427 12:07:47.038200       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:47.042725       1 utils.go:199] ID: 1 GRPC request: {}
I0427 12:07:47.043041       1 utils.go:205] ID: 1 GRPC response: {}
I0427 12:07:47.047194       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0427 12:07:47.047388       1 utils.go:199] ID: 2 GRPC request: {}
I0427 12:07:47.047408       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0427 12:07:47.047594       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0427 12:07:47.050806       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:47.050862       1 utils.go:199] ID: 3 GRPC request: {}
I0427 12:07:47.050886       1 utils.go:205] ID: 3 GRPC response: {}
I0427 12:07:47.051333       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0427 12:07:47.051420       1 utils.go:199] ID: 4 GRPC request: {}
I0427 12:07:47.051555       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0427 12:07:47.052380       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0427 12:07:47.052440       1 utils.go:199] ID: 5 GRPC request: {}
I0427 12:07:47.052448       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0427 12:07:47.052688       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}}}]}
I0427 12:07:48.581086       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:48.581142       1 utils.go:199] ID: 6 GRPC request: {}
I0427 12:07:48.581164       1 utils.go:205] ID: 6 GRPC response: {}
I0427 12:07:48.585757       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0427 12:07:48.585779       1 utils.go:199] ID: 7 GRPC request: {}
I0427 12:07:48.585787       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0427 12:07:48.585807       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0427 12:07:48.587799       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:48.587848       1 utils.go:199] ID: 8 GRPC request: {}
I0427 12:07:48.587918       1 utils.go:205] ID: 8 GRPC response: {}
I0427 12:07:48.588458       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0427 12:07:48.588503       1 utils.go:199] ID: 9 GRPC request: {}
I0427 12:07:48.588619       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0427 12:07:48.589328       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0427 12:07:48.589350       1 utils.go:199] ID: 10 GRPC request: {}
I0427 12:07:48.589358       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0427 12:07:48.589442       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}}}]}
I0427 12:07:48.590028       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0427 12:07:48.590076       1 utils.go:199] ID: 11 GRPC request: {}
I0427 12:07:48.590097       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0427 12:07:48.590214       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}}}]}
I0427 12:07:48.590511       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0427 12:07:48.590530       1 utils.go:199] ID: 12 GRPC request: {}
I0427 12:07:48.590595       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0427 12:07:48.590701       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}}}]}
I0427 12:07:50.666841       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0427 12:07:50.666906       1 utils.go:199] ID: 13 GRPC request: {}
I0427 12:07:50.666920       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0427 12:07:50.666994       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0427 12:07:50.670131       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:50.670169       1 utils.go:199] ID: 14 GRPC request: {}
I0427 12:07:50.670186       1 utils.go:205] ID: 14 GRPC response: {}
I0427 12:07:50.678619       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0427 12:07:50.678790       1 utils.go:199] ID: 15 GRPC request: {}
I0427 12:07:50.678817       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0427 12:07:50.679117       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}}}]}
I0427 12:07:52.042374       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:52.042418       1 utils.go:199] ID: 16 GRPC request: {}
I0427 12:07:52.042440       1 utils.go:205] ID: 16 GRPC response: {}
I0427 12:07:52.044570       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0427 12:07:52.044599       1 utils.go:199] ID: 17 GRPC request: {}
I0427 12:07:52.044609       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0427 12:07:52.044638       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0427 12:07:52.047593       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0427 12:07:52.047624       1 utils.go:199] ID: 18 GRPC request: {}
I0427 12:07:52.047642       1 utils.go:205] ID: 18 GRPC response: {}
I0427 12:07:52.048062       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0427 12:07:52.048095       1 utils.go:199] ID: 19 GRPC request: {}
I0427 12:07:52.048199       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0427 12:07:52.048812       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0427 12:07:52.048832       1 utils.go:199] ID: 20 GRPC request: {}
I0427 12:07:52.048840       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0427 12:07:52.048901       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}}}]}
I0427 12:08:52.265713       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0427 12:08:52.265897       1 utils.go:199] ID: 21 GRPC request: {}
I0427 12:08:52.265952       1 utils.go:205] ID: 21 GRPC response: {}
I0427 12:09:52.237636       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0427 12:09:52.238099       1 utils.go:199] ID: 22 GRPC request: {}
I0427 12:09:52.238154       1 utils.go:205] ID: 22 GRPC response: {}
I0427 12:10:52.236956       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0427 12:10:52.237094       1 utils.go:199] ID: 23 GRPC request: {}
I0427 12:10:52.237153       1 utils.go:205] ID: 23 GRPC response: {}
I0427 12:11:03.601207       1 utils.go:198] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 GRPC call: /csi.v1.Controller/CreateVolume
I0427 12:11:03.602077       1 utils.go:199] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-b116da1a-ba76-45cb-8020-78653536fc06","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-b116da1a-ba76-45cb-8020-78653536fc06","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}}]}
I0427 12:11:03.603031       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0427 12:11:03.622196       1 omap.go:80] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0427 12:11:03.635916       1 omap.go:159] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-b116da1a-ba76-45cb-8020-78653536fc06:495b3b50-68c3-4f71-833b-70cafac22720])
I0427 12:11:03.643223       1 omap.go:159] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 set omap keys (pool="kube", namespace="", name="csi.volume.495b3b50-68c3-4f71-833b-70cafac22720"): map[csi.imagename:csi-vol-495b3b50-68c3-4f71-833b-70cafac22720 csi.volname:pvc-b116da1a-ba76-45cb-8020-78653536fc06 csi.volume.owner:default])
I0427 12:11:03.643256       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-495b3b50-68c3-4f71-833b-70cafac22720) and image name (csi-vol-495b3b50-68c3-4f71-833b-70cafac22720) for request name (pvc-b116da1a-ba76-45cb-8020-78653536fc06)
I0427 12:11:03.643349       1 rbd_util.go:423] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 rbd: create kube/csi-vol-495b3b50-68c3-4f71-833b-70cafac22720 size 5120M (features: [layering]) using mon 199.19.213.5
I0427 12:11:03.643392       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 setting image options on kube/csi-vol-495b3b50-68c3-4f71-833b-70cafac22720
I0427 12:11:05.054761       1 controllerserver.go:763] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 created image kube/csi-vol-495b3b50-68c3-4f71-833b-70cafac22720 backed for request name pvc-b116da1a-ba76-45cb-8020-78653536fc06
I0427 12:11:05.087461       1 omap.go:159] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 set omap keys (pool="kube", namespace="", name="csi.volume.495b3b50-68c3-4f71-833b-70cafac22720"): map[csi.imageid:37d9fd19d071])
I0427 12:11:05.181876       1 utils.go:205] ID: 24 Req-ID: pvc-b116da1a-ba76-45cb-8020-78653536fc06 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-495b3b50-68c3-4f71-833b-70cafac22720","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-495b3b50-68c3-4f71-833b-70cafac22720"}}
I0427 12:11:52.252971       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0427 12:11:52.253075       1 utils.go:199] ID: 25 GRPC request: {}
I0427 12:11:52.253114       1 utils.go:205] ID: 25 GRPC response: {}