I0225 19:32:32.927524       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0225 19:32:32.927786       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0225 19:32:32.927818       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0225 19:32:32.927825       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0225 19:32:32.927857       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0225 19:32:32.927866       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0225 19:32:32.927873       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0225 19:32:32.927878       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0225 19:32:32.927883       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0225 19:32:32.927903       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0225 19:32:32.928350       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0225 19:32:32.928355       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0225 19:32:37.738261       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:37.739535       1 utils.go:199] ID: 1 GRPC request: {}
I0225 19:32:37.739626       1 utils.go:205] ID: 1 GRPC response: {}
I0225 19:32:37.742683       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0225 19:32:37.742877       1 utils.go:199] ID: 2 GRPC request: {}
I0225 19:32:37.742905       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0225 19:32:37.742996       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0225 19:32:37.746195       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:37.746230       1 utils.go:199] ID: 3 GRPC request: {}
I0225 19:32:37.746244       1 utils.go:205] ID: 3 GRPC response: {}
I0225 19:32:37.747135       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0225 19:32:37.747224       1 utils.go:199] ID: 4 GRPC request: {}
I0225 19:32:37.747515       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0225 19:32:37.748520       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 19:32:37.748604       1 utils.go:199] ID: 5 GRPC request: {}
I0225 19:32:37.748618       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0225 19:32:37.748839       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}}}]}
I0225 19:32:44.415540       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:44.415607       1 utils.go:199] ID: 6 GRPC request: {}
I0225 19:32:44.415627       1 utils.go:205] ID: 6 GRPC response: {}
I0225 19:32:44.419445       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0225 19:32:44.419461       1 utils.go:199] ID: 7 GRPC request: {}
I0225 19:32:44.419469       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0225 19:32:44.419493       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0225 19:32:44.430158       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:44.430190       1 utils.go:199] ID: 8 GRPC request: {}
I0225 19:32:44.430204       1 utils.go:205] ID: 8 GRPC response: {}
I0225 19:32:44.430804       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0225 19:32:44.430827       1 utils.go:199] ID: 9 GRPC request: {}
I0225 19:32:44.430945       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0225 19:32:44.431997       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 19:32:44.432017       1 utils.go:199] ID: 10 GRPC request: {}
I0225 19:32:44.432028       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0225 19:32:44.432119       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}}}]}
I0225 19:32:44.432761       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 19:32:44.432779       1 utils.go:199] ID: 11 GRPC request: {}
I0225 19:32:44.432784       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0225 19:32:44.432839       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}}}]}
I0225 19:32:44.433287       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 19:32:44.433305       1 utils.go:199] ID: 12 GRPC request: {}
I0225 19:32:44.433311       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0225 19:32:44.433382       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}}}]}
I0225 19:32:45.973660       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0225 19:32:45.973808       1 utils.go:199] ID: 13 GRPC request: {}
I0225 19:32:45.973867       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0225 19:32:45.973993       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0225 19:32:45.985313       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:45.985387       1 utils.go:199] ID: 14 GRPC request: {}
I0225 19:32:45.985463       1 utils.go:205] ID: 14 GRPC response: {}
I0225 19:32:45.986325       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 19:32:45.986345       1 utils.go:199] ID: 15 GRPC request: {}
I0225 19:32:45.986355       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0225 19:32:45.986582       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}}}]}
I0225 19:32:47.448663       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:47.448699       1 utils.go:199] ID: 16 GRPC request: {}
I0225 19:32:47.448715       1 utils.go:205] ID: 16 GRPC response: {}
I0225 19:32:47.451441       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0225 19:32:47.451464       1 utils.go:199] ID: 17 GRPC request: {}
I0225 19:32:47.451471       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0225 19:32:47.451496       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0225 19:32:47.455087       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0225 19:32:47.455110       1 utils.go:199] ID: 18 GRPC request: {}
I0225 19:32:47.455122       1 utils.go:205] ID: 18 GRPC response: {}
I0225 19:32:47.455763       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0225 19:32:47.455835       1 utils.go:199] ID: 19 GRPC request: {}
I0225 19:32:47.456918       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0225 19:32:47.457809       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 19:32:47.457841       1 utils.go:199] ID: 20 GRPC request: {}
I0225 19:32:47.457878       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0225 19:32:47.458044       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}}}]}
I0225 19:33:47.703034       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0225 19:33:47.703083       1 utils.go:199] ID: 21 GRPC request: {}
I0225 19:33:47.703105       1 utils.go:205] ID: 21 GRPC response: {}
I0225 19:34:47.672483       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0225 19:34:47.672575       1 utils.go:199] ID: 22 GRPC request: {}
I0225 19:34:47.672594       1 utils.go:205] ID: 22 GRPC response: {}
I0225 19:35:45.551718       1 utils.go:198] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 GRPC call: /csi.v1.Controller/CreateVolume
I0225 19:35:45.552419       1 utils.go:199] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6","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}}]}
I0225 19:35:45.553000       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0225 19:35:45.572276       1 omap.go:80] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0225 19:35:45.588811       1 omap.go:159] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6:ffd52ab3-a55c-478c-9eca-f505044d17c8])
I0225 19:35:45.598747       1 omap.go:159] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 set omap keys (pool="kube", namespace="", name="csi.volume.ffd52ab3-a55c-478c-9eca-f505044d17c8"): map[csi.imagename:csi-vol-ffd52ab3-a55c-478c-9eca-f505044d17c8 csi.volname:pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 csi.volume.owner:default])
I0225 19:35:45.598783       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ffd52ab3-a55c-478c-9eca-f505044d17c8) and image name (csi-vol-ffd52ab3-a55c-478c-9eca-f505044d17c8) for request name (pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6)
I0225 19:35:45.598862       1 rbd_util.go:423] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 rbd: create kube/csi-vol-ffd52ab3-a55c-478c-9eca-f505044d17c8 size 5120M (features: [layering]) using mon 199.19.213.223
I0225 19:35:45.598912       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 setting image options on kube/csi-vol-ffd52ab3-a55c-478c-9eca-f505044d17c8
I0225 19:35:47.533071       1 controllerserver.go:763] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 created image kube/csi-vol-ffd52ab3-a55c-478c-9eca-f505044d17c8 backed for request name pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6
I0225 19:35:47.570750       1 omap.go:159] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 set omap keys (pool="kube", namespace="", name="csi.volume.ffd52ab3-a55c-478c-9eca-f505044d17c8"): map[csi.imageid:37d73ec052c0])
I0225 19:35:47.668684       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0225 19:35:47.668752       1 utils.go:199] ID: 24 GRPC request: {}
I0225 19:35:47.668779       1 utils.go:205] ID: 24 GRPC response: {}
I0225 19:35:47.678217       1 utils.go:205] ID: 23 Req-ID: pvc-41d4eebd-1e47-43ba-9c44-04717ae065b6 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-ffd52ab3-a55c-478c-9eca-f505044d17c8","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ffd52ab3-a55c-478c-9eca-f505044d17c8"}}