I0808 05:56:50.169757       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0808 05:56:50.170017       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0808 05:56:50.170042       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0808 05:56:50.170048       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0808 05:56:50.170052       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0808 05:56:50.170057       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0808 05:56:50.170062       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0808 05:56:50.170067       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0808 05:56:50.170073       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0808 05:56:50.170077       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0808 05:56:50.170485       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0808 05:56:50.170515       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0808 05:56:52.522217       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:52.524167       1 utils.go:199] ID: 1 GRPC request: {}
I0808 05:56:52.524315       1 utils.go:205] ID: 1 GRPC response: {}
I0808 05:56:52.526367       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0808 05:56:52.526479       1 utils.go:199] ID: 2 GRPC request: {}
I0808 05:56:52.526502       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0808 05:56:52.526628       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0808 05:56:52.529459       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:52.529529       1 utils.go:199] ID: 3 GRPC request: {}
I0808 05:56:52.529550       1 utils.go:205] ID: 3 GRPC response: {}
I0808 05:56:52.530094       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0808 05:56:52.530233       1 utils.go:199] ID: 4 GRPC request: {}
I0808 05:56:52.530407       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0808 05:56:52.531790       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0808 05:56:52.531886       1 utils.go:199] ID: 5 GRPC request: {}
I0808 05:56:52.531905       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0808 05:56:52.532145       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}}}]}
I0808 05:56:54.615304       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:54.615339       1 utils.go:199] ID: 6 GRPC request: {}
I0808 05:56:54.615362       1 utils.go:205] ID: 6 GRPC response: {}
I0808 05:56:54.617288       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0808 05:56:54.617310       1 utils.go:199] ID: 7 GRPC request: {}
I0808 05:56:54.617318       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0808 05:56:54.617347       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0808 05:56:54.621673       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:54.621709       1 utils.go:199] ID: 8 GRPC request: {}
I0808 05:56:54.621723       1 utils.go:205] ID: 8 GRPC response: {}
I0808 05:56:54.622082       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0808 05:56:54.622106       1 utils.go:199] ID: 9 GRPC request: {}
I0808 05:56:54.622179       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0808 05:56:54.622651       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0808 05:56:54.622674       1 utils.go:199] ID: 10 GRPC request: {}
I0808 05:56:54.622682       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0808 05:56:54.622770       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}}}]}
I0808 05:56:54.623160       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0808 05:56:54.623185       1 utils.go:199] ID: 11 GRPC request: {}
I0808 05:56:54.623194       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0808 05:56:54.623251       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}}}]}
I0808 05:56:54.623512       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0808 05:56:54.623532       1 utils.go:199] ID: 12 GRPC request: {}
I0808 05:56:54.623538       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0808 05:56:54.623608       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}}}]}
I0808 05:56:56.521245       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0808 05:56:56.521289       1 utils.go:199] ID: 13 GRPC request: {}
I0808 05:56:56.521297       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0808 05:56:56.521548       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0808 05:56:56.525003       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:56.525024       1 utils.go:199] ID: 14 GRPC request: {}
I0808 05:56:56.525100       1 utils.go:205] ID: 14 GRPC response: {}
I0808 05:56:56.525883       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0808 05:56:56.525916       1 utils.go:199] ID: 15 GRPC request: {}
I0808 05:56:56.526130       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0808 05:56:56.526338       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}}}]}
I0808 05:56:57.793756       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:57.793836       1 utils.go:199] ID: 16 GRPC request: {}
I0808 05:56:57.793888       1 utils.go:205] ID: 16 GRPC response: {}
I0808 05:56:57.796649       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0808 05:56:57.796707       1 utils.go:199] ID: 17 GRPC request: {}
I0808 05:56:57.796736       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0808 05:56:57.796876       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0808 05:56:57.799961       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0808 05:56:57.799981       1 utils.go:199] ID: 18 GRPC request: {}
I0808 05:56:57.799993       1 utils.go:205] ID: 18 GRPC response: {}
I0808 05:56:57.800524       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0808 05:56:57.800570       1 utils.go:199] ID: 19 GRPC request: {}
I0808 05:56:57.800699       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0808 05:56:57.801448       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0808 05:56:57.801467       1 utils.go:199] ID: 20 GRPC request: {}
I0808 05:56:57.801476       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0808 05:56:57.801567       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}}}]}
I0808 05:57:57.971605       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0808 05:57:57.971693       1 utils.go:199] ID: 21 GRPC request: {}
I0808 05:57:57.971724       1 utils.go:205] ID: 21 GRPC response: {}
I0808 05:58:57.976550       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0808 05:58:57.976692       1 utils.go:199] ID: 22 GRPC request: {}
I0808 05:58:57.976731       1 utils.go:205] ID: 22 GRPC response: {}
I0808 05:59:42.028366       1 utils.go:198] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b GRPC call: /csi.v1.Controller/CreateVolume
I0808 05:59:42.029152       1 utils.go:199] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b","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}}]}
I0808 05:59:42.029511       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b setting disableInUseChecks: false image features: [layering] mounter: rbd
E0808 05:59:42.048656       1 omap.go:80] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0808 05:59:42.057620       1 omap.go:159] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b:bad437e0-d5e5-4608-8baf-c2b355871161])
I0808 05:59:42.061445       1 omap.go:159] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b set omap keys (pool="kube", namespace="", name="csi.volume.bad437e0-d5e5-4608-8baf-c2b355871161"): map[csi.imagename:csi-vol-bad437e0-d5e5-4608-8baf-c2b355871161 csi.volname:pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b csi.volume.owner:default])
I0808 05:59:42.061572       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-bad437e0-d5e5-4608-8baf-c2b355871161) and image name (csi-vol-bad437e0-d5e5-4608-8baf-c2b355871161) for request name (pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b)
I0808 05:59:42.061682       1 rbd_util.go:423] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b rbd: create kube/csi-vol-bad437e0-d5e5-4608-8baf-c2b355871161 size 5120M (features: [layering]) using mon 199.204.45.140
I0808 05:59:42.061803       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b setting image options on kube/csi-vol-bad437e0-d5e5-4608-8baf-c2b355871161
I0808 05:59:42.079652       1 controllerserver.go:763] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b created image kube/csi-vol-bad437e0-d5e5-4608-8baf-c2b355871161 backed for request name pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b
I0808 05:59:42.098925       1 omap.go:159] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b set omap keys (pool="kube", namespace="", name="csi.volume.bad437e0-d5e5-4608-8baf-c2b355871161"): map[csi.imageid:3804fe241dca])
I0808 05:59:42.152244       1 utils.go:205] ID: 23 Req-ID: pvc-d044c6f6-2cc5-4d14-bf34-0d9b64d4488b GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-bad437e0-d5e5-4608-8baf-c2b355871161","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-bad437e0-d5e5-4608-8baf-c2b355871161"}}
I0808 05:59:57.970887       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0808 05:59:57.970941       1 utils.go:199] ID: 24 GRPC request: {}
I0808 05:59:57.970964       1 utils.go:205] ID: 24 GRPC response: {}