I0729 15:49:11.108915       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0729 15:49:11.109066       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0729 15:49:11.109084       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0729 15:49:11.109095       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0729 15:49:11.109100       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0729 15:49:11.109104       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0729 15:49:11.109109       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0729 15:49:11.109113       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0729 15:49:11.109121       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0729 15:49:11.109126       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0729 15:49:11.109611       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0729 15:49:11.109621       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0729 15:49:18.359278       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:18.360279       1 utils.go:199] ID: 1 GRPC request: {}
I0729 15:49:18.360318       1 utils.go:205] ID: 1 GRPC response: {}
I0729 15:49:18.365667       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0729 15:49:18.365784       1 utils.go:199] ID: 2 GRPC request: {}
I0729 15:49:18.365835       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0729 15:49:18.365962       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0729 15:49:18.367593       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:18.367619       1 utils.go:199] ID: 3 GRPC request: {}
I0729 15:49:18.367639       1 utils.go:205] ID: 3 GRPC response: {}
I0729 15:49:18.368014       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0729 15:49:18.368078       1 utils.go:199] ID: 4 GRPC request: {}
I0729 15:49:18.368236       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0729 15:49:18.369154       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0729 15:49:18.369213       1 utils.go:199] ID: 5 GRPC request: {}
I0729 15:49:18.369225       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0729 15:49:18.369373       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}}}]}
I0729 15:49:19.928525       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:19.928721       1 utils.go:199] ID: 6 GRPC request: {}
I0729 15:49:19.928767       1 utils.go:205] ID: 6 GRPC response: {}
I0729 15:49:19.933136       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0729 15:49:19.933260       1 utils.go:199] ID: 7 GRPC request: {}
I0729 15:49:19.933359       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0729 15:49:19.933544       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0729 15:49:19.936565       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:19.936625       1 utils.go:199] ID: 8 GRPC request: {}
I0729 15:49:19.936642       1 utils.go:205] ID: 8 GRPC response: {}
I0729 15:49:19.937239       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0729 15:49:19.937260       1 utils.go:199] ID: 9 GRPC request: {}
I0729 15:49:19.937372       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0729 15:49:19.937965       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0729 15:49:19.937983       1 utils.go:199] ID: 10 GRPC request: {}
I0729 15:49:19.937995       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0729 15:49:19.938078       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}}}]}
I0729 15:49:19.938612       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0729 15:49:19.938629       1 utils.go:199] ID: 11 GRPC request: {}
I0729 15:49:19.938635       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0729 15:49:19.938687       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}}}]}
I0729 15:49:19.939013       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0729 15:49:19.939031       1 utils.go:199] ID: 12 GRPC request: {}
I0729 15:49:19.939037       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0729 15:49:19.939093       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}}}]}
I0729 15:49:21.615393       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0729 15:49:21.615461       1 utils.go:199] ID: 13 GRPC request: {}
I0729 15:49:21.615475       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0729 15:49:21.615508       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0729 15:49:21.617803       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:21.617828       1 utils.go:199] ID: 14 GRPC request: {}
I0729 15:49:21.617844       1 utils.go:205] ID: 14 GRPC response: {}
I0729 15:49:21.618201       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0729 15:49:21.618253       1 utils.go:199] ID: 15 GRPC request: {}
I0729 15:49:21.618279       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0729 15:49:21.618433       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}}}]}
I0729 15:49:23.589819       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:23.589868       1 utils.go:199] ID: 16 GRPC request: {}
I0729 15:49:23.589888       1 utils.go:205] ID: 16 GRPC response: {}
I0729 15:49:23.591972       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0729 15:49:23.591993       1 utils.go:199] ID: 17 GRPC request: {}
I0729 15:49:23.592001       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0729 15:49:23.592023       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0729 15:49:23.595065       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0729 15:49:23.595131       1 utils.go:199] ID: 18 GRPC request: {}
I0729 15:49:23.595155       1 utils.go:205] ID: 18 GRPC response: {}
I0729 15:49:23.596497       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0729 15:49:23.596801       1 utils.go:199] ID: 19 GRPC request: {}
I0729 15:49:23.597073       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0729 15:49:23.597955       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0729 15:49:23.598505       1 utils.go:199] ID: 20 GRPC request: {}
I0729 15:49:23.598542       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0729 15:49:23.598701       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}}}]}
I0729 15:50:23.831758       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0729 15:50:23.831796       1 utils.go:199] ID: 21 GRPC request: {}
I0729 15:50:23.831809       1 utils.go:205] ID: 21 GRPC response: {}
I0729 15:51:23.785190       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0729 15:51:23.785573       1 utils.go:199] ID: 22 GRPC request: {}
I0729 15:51:23.785761       1 utils.go:205] ID: 22 GRPC response: {}
I0729 15:52:23.782723       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0729 15:52:23.782763       1 utils.go:199] ID: 23 GRPC request: {}
I0729 15:52:23.782788       1 utils.go:205] ID: 23 GRPC response: {}
I0729 15:53:23.794344       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0729 15:53:23.794423       1 utils.go:199] ID: 24 GRPC request: {}
I0729 15:53:23.794450       1 utils.go:205] ID: 24 GRPC response: {}
I0729 15:53:25.671715       1 utils.go:198] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec GRPC call: /csi.v1.Controller/CreateVolume
I0729 15:53:25.672134       1 utils.go:199] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec","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}}]}
I0729 15:53:25.672493       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec setting disableInUseChecks: false image features: [layering] mounter: rbd
E0729 15:53:25.692151       1 omap.go:80] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0729 15:53:25.708970       1 omap.go:159] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec:9b85b664-0ee8-4d39-89cf-6511394ac82d])
I0729 15:53:25.716211       1 omap.go:159] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec set omap keys (pool="kube", namespace="", name="csi.volume.9b85b664-0ee8-4d39-89cf-6511394ac82d"): map[csi.imagename:csi-vol-9b85b664-0ee8-4d39-89cf-6511394ac82d csi.volname:pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec csi.volume.owner:default])
I0729 15:53:25.716249       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9b85b664-0ee8-4d39-89cf-6511394ac82d) and image name (csi-vol-9b85b664-0ee8-4d39-89cf-6511394ac82d) for request name (pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec)
I0729 15:53:25.716460       1 rbd_util.go:423] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec rbd: create kube/csi-vol-9b85b664-0ee8-4d39-89cf-6511394ac82d size 5120M (features: [layering]) using mon 199.19.213.54
I0729 15:53:25.716558       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec setting image options on kube/csi-vol-9b85b664-0ee8-4d39-89cf-6511394ac82d
I0729 15:53:25.756165       1 controllerserver.go:763] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec created image kube/csi-vol-9b85b664-0ee8-4d39-89cf-6511394ac82d backed for request name pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec
I0729 15:53:25.791806       1 omap.go:159] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec set omap keys (pool="kube", namespace="", name="csi.volume.9b85b664-0ee8-4d39-89cf-6511394ac82d"): map[csi.imageid:37f464dd59e1])
I0729 15:53:25.883952       1 utils.go:205] ID: 25 Req-ID: pvc-02f097c3-241c-4ab4-b35e-4f9fb3bb70ec GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-9b85b664-0ee8-4d39-89cf-6511394ac82d","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9b85b664-0ee8-4d39-89cf-6511394ac82d"}}