I0227 21:02:45.486714       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0227 21:02:45.486826       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0227 21:02:45.486836       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0227 21:02:45.486839       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0227 21:02:45.486844       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0227 21:02:45.486846       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0227 21:02:45.486850       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0227 21:02:45.486853       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0227 21:02:45.486855       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0227 21:02:45.486858       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0227 21:02:45.487097       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0227 21:02:45.487125       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0227 21:02:47.293824       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:47.294789       1 utils.go:199] ID: 1 GRPC request: {}
I0227 21:02:47.294831       1 utils.go:205] ID: 1 GRPC response: {}
I0227 21:02:47.297166       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0227 21:02:47.297249       1 utils.go:199] ID: 2 GRPC request: {}
I0227 21:02:47.297269       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0227 21:02:47.297325       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0227 21:02:47.299128       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:47.299195       1 utils.go:199] ID: 3 GRPC request: {}
I0227 21:02:47.299217       1 utils.go:205] ID: 3 GRPC response: {}
I0227 21:02:47.299701       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0227 21:02:47.299851       1 utils.go:199] ID: 4 GRPC request: {}
I0227 21:02:47.300093       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0227 21:02:47.300941       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0227 21:02:47.301040       1 utils.go:199] ID: 5 GRPC request: {}
I0227 21:02:47.301059       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0227 21:02:47.301260       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}}}]}
I0227 21:02:49.509251       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:49.509288       1 utils.go:199] ID: 6 GRPC request: {}
I0227 21:02:49.509303       1 utils.go:205] ID: 6 GRPC response: {}
I0227 21:02:49.513649       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0227 21:02:49.513668       1 utils.go:199] ID: 7 GRPC request: {}
I0227 21:02:49.513676       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0227 21:02:49.513701       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0227 21:02:49.515624       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:49.515641       1 utils.go:199] ID: 8 GRPC request: {}
I0227 21:02:49.515651       1 utils.go:205] ID: 8 GRPC response: {}
I0227 21:02:49.516334       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0227 21:02:49.516353       1 utils.go:199] ID: 9 GRPC request: {}
I0227 21:02:49.516492       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0227 21:02:49.517194       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0227 21:02:49.517211       1 utils.go:199] ID: 10 GRPC request: {}
I0227 21:02:49.517218       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0227 21:02:49.517361       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}}}]}
I0227 21:02:49.517915       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0227 21:02:49.517929       1 utils.go:199] ID: 11 GRPC request: {}
I0227 21:02:49.517934       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0227 21:02:49.518045       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}}}]}
I0227 21:02:49.518465       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0227 21:02:49.518480       1 utils.go:199] ID: 12 GRPC request: {}
I0227 21:02:49.518485       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0227 21:02:49.518603       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}}}]}
I0227 21:02:52.322682       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0227 21:02:52.322718       1 utils.go:199] ID: 13 GRPC request: {}
I0227 21:02:52.322725       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0227 21:02:52.322750       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0227 21:02:52.331010       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:52.331054       1 utils.go:199] ID: 14 GRPC request: {}
I0227 21:02:52.331154       1 utils.go:205] ID: 14 GRPC response: {}
I0227 21:02:52.331665       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0227 21:02:52.331687       1 utils.go:199] ID: 15 GRPC request: {}
I0227 21:02:52.331695       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0227 21:02:52.331877       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}}}]}
I0227 21:02:53.806662       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:53.806713       1 utils.go:199] ID: 16 GRPC request: {}
I0227 21:02:53.806735       1 utils.go:205] ID: 16 GRPC response: {}
I0227 21:02:53.808537       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0227 21:02:53.808565       1 utils.go:199] ID: 17 GRPC request: {}
I0227 21:02:53.808575       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0227 21:02:53.808777       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0227 21:02:53.810172       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0227 21:02:53.810206       1 utils.go:199] ID: 18 GRPC request: {}
I0227 21:02:53.810222       1 utils.go:205] ID: 18 GRPC response: {}
I0227 21:02:53.810505       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0227 21:02:53.810530       1 utils.go:199] ID: 19 GRPC request: {}
I0227 21:02:53.810767       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0227 21:02:53.811344       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0227 21:02:53.811383       1 utils.go:199] ID: 20 GRPC request: {}
I0227 21:02:53.811392       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0227 21:02:53.811584       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}}}]}
I0227 21:03:53.988690       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0227 21:03:53.988738       1 utils.go:199] ID: 21 GRPC request: {}
I0227 21:03:53.988760       1 utils.go:205] ID: 21 GRPC response: {}
I0227 21:04:53.989697       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0227 21:04:53.990679       1 utils.go:199] ID: 22 GRPC request: {}
I0227 21:04:53.990714       1 utils.go:205] ID: 22 GRPC response: {}
I0227 21:05:53.866965       1 utils.go:198] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 GRPC call: /csi.v1.Controller/CreateVolume
I0227 21:05:53.867593       1 utils.go:199] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995","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}}]}
I0227 21:05:53.867901       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0227 21:05:53.889815       1 omap.go:80] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0227 21:05:53.903263       1 omap.go:159] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995:dded4b69-d84a-4c42-9f8a-37488341cc74])
I0227 21:05:53.909906       1 omap.go:159] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 set omap keys (pool="kube", namespace="", name="csi.volume.dded4b69-d84a-4c42-9f8a-37488341cc74"): map[csi.imagename:csi-vol-dded4b69-d84a-4c42-9f8a-37488341cc74 csi.volname:pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 csi.volume.owner:default])
I0227 21:05:53.909944       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-dded4b69-d84a-4c42-9f8a-37488341cc74) and image name (csi-vol-dded4b69-d84a-4c42-9f8a-37488341cc74) for request name (pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995)
I0227 21:05:53.910018       1 rbd_util.go:423] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 rbd: create kube/csi-vol-dded4b69-d84a-4c42-9f8a-37488341cc74 size 5120M (features: [layering]) using mon 199.204.45.238
I0227 21:05:53.910069       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 setting image options on kube/csi-vol-dded4b69-d84a-4c42-9f8a-37488341cc74
I0227 21:05:53.987994       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0227 21:05:53.988082       1 utils.go:199] ID: 24 GRPC request: {}
I0227 21:05:53.988111       1 utils.go:205] ID: 24 GRPC response: {}
I0227 21:05:55.641751       1 controllerserver.go:763] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 created image kube/csi-vol-dded4b69-d84a-4c42-9f8a-37488341cc74 backed for request name pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995
I0227 21:05:55.672914       1 omap.go:159] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 set omap keys (pool="kube", namespace="", name="csi.volume.dded4b69-d84a-4c42-9f8a-37488341cc74"): map[csi.imageid:37d9cf54d95])
I0227 21:05:55.763982       1 utils.go:205] ID: 23 Req-ID: pvc-2cd6af08-e39c-4b9e-b5a1-a59d1dea0995 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-dded4b69-d84a-4c42-9f8a-37488341cc74","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-dded4b69-d84a-4c42-9f8a-37488341cc74"}}