I0711 03:32:37.882241       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0711 03:32:37.882378       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0711 03:32:37.882391       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0711 03:32:37.882395       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0711 03:32:37.882397       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0711 03:32:37.882400       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0711 03:32:37.882404       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0711 03:32:37.882406       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0711 03:32:37.882421       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0711 03:32:37.882424       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0711 03:32:37.882939       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0711 03:32:37.883106       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0711 03:32:40.352979       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:40.353931       1 utils.go:199] ID: 1 GRPC request: {}
I0711 03:32:40.353998       1 utils.go:205] ID: 1 GRPC response: {}
I0711 03:32:40.356749       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0711 03:32:40.356797       1 utils.go:199] ID: 2 GRPC request: {}
I0711 03:32:40.356810       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0711 03:32:40.356849       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0711 03:32:40.358233       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:40.358272       1 utils.go:199] ID: 3 GRPC request: {}
I0711 03:32:40.358292       1 utils.go:205] ID: 3 GRPC response: {}
I0711 03:32:40.358658       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0711 03:32:40.358718       1 utils.go:199] ID: 4 GRPC request: {}
I0711 03:32:40.358854       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0711 03:32:40.359504       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0711 03:32:40.359548       1 utils.go:199] ID: 5 GRPC request: {}
I0711 03:32:40.359567       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0711 03:32:40.359667       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}}}]}
I0711 03:32:41.830264       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:41.830311       1 utils.go:199] ID: 6 GRPC request: {}
I0711 03:32:41.830328       1 utils.go:205] ID: 6 GRPC response: {}
I0711 03:32:41.832373       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0711 03:32:41.832531       1 utils.go:199] ID: 7 GRPC request: {}
I0711 03:32:41.832672       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0711 03:32:41.832720       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0711 03:32:41.834632       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:41.834653       1 utils.go:199] ID: 8 GRPC request: {}
I0711 03:32:41.834666       1 utils.go:205] ID: 8 GRPC response: {}
I0711 03:32:41.835171       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0711 03:32:41.835199       1 utils.go:199] ID: 9 GRPC request: {}
I0711 03:32:41.835281       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0711 03:32:41.835921       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0711 03:32:41.835941       1 utils.go:199] ID: 10 GRPC request: {}
I0711 03:32:41.835949       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0711 03:32:41.836031       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}}}]}
I0711 03:32:41.836738       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0711 03:32:41.836774       1 utils.go:199] ID: 11 GRPC request: {}
I0711 03:32:41.836786       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0711 03:32:41.836929       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}}}]}
I0711 03:32:41.837312       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0711 03:32:41.837332       1 utils.go:199] ID: 12 GRPC request: {}
I0711 03:32:41.837339       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0711 03:32:41.837398       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}}}]}
I0711 03:32:44.128838       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0711 03:32:44.128878       1 utils.go:199] ID: 13 GRPC request: {}
I0711 03:32:44.128885       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0711 03:32:44.128917       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0711 03:32:44.132029       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:44.132058       1 utils.go:199] ID: 14 GRPC request: {}
I0711 03:32:44.132078       1 utils.go:205] ID: 14 GRPC response: {}
I0711 03:32:44.132813       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0711 03:32:44.132983       1 utils.go:199] ID: 15 GRPC request: {}
I0711 03:32:44.133002       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0711 03:32:44.133277       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}}}]}
I0711 03:32:45.835275       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:45.835334       1 utils.go:199] ID: 16 GRPC request: {}
I0711 03:32:45.835383       1 utils.go:205] ID: 16 GRPC response: {}
I0711 03:32:45.837345       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0711 03:32:45.837392       1 utils.go:199] ID: 17 GRPC request: {}
I0711 03:32:45.837401       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0711 03:32:45.837425       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0711 03:32:45.839570       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0711 03:32:45.839633       1 utils.go:199] ID: 18 GRPC request: {}
I0711 03:32:45.839690       1 utils.go:205] ID: 18 GRPC response: {}
I0711 03:32:45.840469       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0711 03:32:45.840515       1 utils.go:199] ID: 19 GRPC request: {}
I0711 03:32:45.840648       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0711 03:32:45.843584       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0711 03:32:45.843604       1 utils.go:199] ID: 20 GRPC request: {}
I0711 03:32:45.843612       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0711 03:32:45.843705       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}}}]}
I0711 03:33:46.052795       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0711 03:33:46.052843       1 utils.go:199] ID: 21 GRPC request: {}
I0711 03:33:46.052866       1 utils.go:205] ID: 21 GRPC response: {}
I0711 03:34:45.994652       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0711 03:34:45.994769       1 utils.go:199] ID: 22 GRPC request: {}
I0711 03:34:45.994800       1 utils.go:205] ID: 22 GRPC response: {}
I0711 03:35:45.993945       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0711 03:35:45.993996       1 utils.go:199] ID: 23 GRPC request: {}
I0711 03:35:45.994019       1 utils.go:205] ID: 23 GRPC response: {}
I0711 03:36:46.004691       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0711 03:36:46.005006       1 utils.go:199] ID: 24 GRPC request: {}
I0711 03:36:46.005056       1 utils.go:205] ID: 24 GRPC response: {}
I0711 03:36:49.765490       1 utils.go:198] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 GRPC call: /csi.v1.Controller/CreateVolume
I0711 03:36:49.766362       1 utils.go:199] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3","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}}]}
I0711 03:36:49.767578       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0711 03:36:49.793049       1 omap.go:80] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0711 03:36:49.805619       1 omap.go:159] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3:7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04])
I0711 03:36:49.810412       1 omap.go:159] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 set omap keys (pool="kube", namespace="", name="csi.volume.7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04"): map[csi.imagename:csi-vol-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04 csi.volname:pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 csi.volume.owner:default])
I0711 03:36:49.810439       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04) and image name (csi-vol-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04) for request name (pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3)
I0711 03:36:49.810591       1 rbd_util.go:423] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 rbd: create kube/csi-vol-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04 size 5120M (features: [layering]) using mon 199.204.45.38
I0711 03:36:49.810630       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 setting image options on kube/csi-vol-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04
I0711 03:36:49.840419       1 controllerserver.go:763] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 created image kube/csi-vol-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04 backed for request name pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3
I0711 03:36:49.875629       1 omap.go:159] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 set omap keys (pool="kube", namespace="", name="csi.volume.7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04"): map[csi.imageid:37f4e1c6c13a])
I0711 03:36:49.955197       1 utils.go:205] ID: 25 Req-ID: pvc-f20ca8f5-ccf7-451f-a551-338d3a4a67e3 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-7eef146a-9cd7-41f5-b7c0-cd3a9c5dec04"}}