I0507 09:18:25.787053       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0507 09:18:25.787167       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0507 09:18:25.787179       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0507 09:18:25.787182       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0507 09:18:25.787185       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0507 09:18:25.787188       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0507 09:18:25.787194       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0507 09:18:25.787196       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0507 09:18:25.787199       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0507 09:18:25.787202       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0507 09:18:25.787559       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0507 09:18:25.787630       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0507 09:18:27.770987       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:27.772971       1 utils.go:199] ID: 1 GRPC request: {}
I0507 09:18:27.773059       1 utils.go:205] ID: 1 GRPC response: {}
I0507 09:18:27.777238       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 09:18:27.777295       1 utils.go:199] ID: 2 GRPC request: {}
I0507 09:18:27.777309       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0507 09:18:27.777385       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 09:18:27.779199       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:27.779216       1 utils.go:199] ID: 3 GRPC request: {}
I0507 09:18:27.779227       1 utils.go:205] ID: 3 GRPC response: {}
I0507 09:18:27.779822       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0507 09:18:27.779901       1 utils.go:199] ID: 4 GRPC request: {}
I0507 09:18:27.780037       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0507 09:18:27.780739       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 09:18:27.780785       1 utils.go:199] ID: 5 GRPC request: {}
I0507 09:18:27.780797       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0507 09:18:27.780928       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}}}]}
I0507 09:18:28.887529       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:28.887558       1 utils.go:199] ID: 6 GRPC request: {}
I0507 09:18:28.887571       1 utils.go:205] ID: 6 GRPC response: {}
I0507 09:18:28.889370       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 09:18:28.889387       1 utils.go:199] ID: 7 GRPC request: {}
I0507 09:18:28.889394       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0507 09:18:28.889414       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 09:18:28.892446       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:28.892461       1 utils.go:199] ID: 8 GRPC request: {}
I0507 09:18:28.892471       1 utils.go:205] ID: 8 GRPC response: {}
I0507 09:18:28.892912       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0507 09:18:28.892937       1 utils.go:199] ID: 9 GRPC request: {}
I0507 09:18:28.893020       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0507 09:18:28.893830       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 09:18:28.893846       1 utils.go:199] ID: 10 GRPC request: {}
I0507 09:18:28.893853       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0507 09:18:28.893938       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}}}]}
I0507 09:18:28.894407       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 09:18:28.894430       1 utils.go:199] ID: 11 GRPC request: {}
I0507 09:18:28.894436       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0507 09:18:28.894500       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}}}]}
I0507 09:18:28.894794       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 09:18:28.894809       1 utils.go:199] ID: 12 GRPC request: {}
I0507 09:18:28.894815       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0507 09:18:28.894872       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}}}]}
I0507 09:18:30.514168       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 09:18:30.514222       1 utils.go:199] ID: 13 GRPC request: {}
I0507 09:18:30.514233       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0507 09:18:30.514265       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 09:18:30.517004       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:30.517027       1 utils.go:199] ID: 14 GRPC request: {}
I0507 09:18:30.517042       1 utils.go:205] ID: 14 GRPC response: {}
I0507 09:18:30.518080       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 09:18:30.518177       1 utils.go:199] ID: 15 GRPC request: {}
I0507 09:18:30.518240       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0507 09:18:30.518425       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}}}]}
I0507 09:18:32.935287       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:32.935321       1 utils.go:199] ID: 16 GRPC request: {}
I0507 09:18:32.935336       1 utils.go:205] ID: 16 GRPC response: {}
I0507 09:18:32.937878       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0507 09:18:32.937898       1 utils.go:199] ID: 17 GRPC request: {}
I0507 09:18:32.937906       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0507 09:18:32.937926       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0507 09:18:32.942077       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0507 09:18:32.942142       1 utils.go:199] ID: 18 GRPC request: {}
I0507 09:18:32.943075       1 utils.go:205] ID: 18 GRPC response: {}
I0507 09:18:32.944757       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0507 09:18:32.944803       1 utils.go:199] ID: 19 GRPC request: {}
I0507 09:18:32.944908       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0507 09:18:32.945657       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0507 09:18:32.945681       1 utils.go:199] ID: 20 GRPC request: {}
I0507 09:18:32.945690       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0507 09:18:32.945770       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}}}]}
I0507 09:19:33.155064       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0507 09:19:33.155231       1 utils.go:199] ID: 21 GRPC request: {}
I0507 09:19:33.155283       1 utils.go:205] ID: 21 GRPC response: {}
I0507 09:20:33.107304       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0507 09:20:33.107343       1 utils.go:199] ID: 22 GRPC request: {}
I0507 09:20:33.107359       1 utils.go:205] ID: 22 GRPC response: {}
I0507 09:21:33.107000       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0507 09:21:33.107060       1 utils.go:199] ID: 23 GRPC request: {}
I0507 09:21:33.107092       1 utils.go:205] ID: 23 GRPC response: {}
I0507 09:21:34.889042       1 utils.go:198] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc GRPC call: /csi.v1.Controller/CreateVolume
I0507 09:21:34.889821       1 utils.go:199] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-6f1fc001-305a-4368-8d06-d038614fbbbc","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-6f1fc001-305a-4368-8d06-d038614fbbbc","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}}]}
I0507 09:21:34.890253       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc setting disableInUseChecks: false image features: [layering] mounter: rbd
E0507 09:21:34.909519       1 omap.go:80] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0507 09:21:34.921519       1 omap.go:159] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-6f1fc001-305a-4368-8d06-d038614fbbbc:1cf4eea8-33c6-404e-835d-8082d74b0af1])
I0507 09:21:34.926819       1 omap.go:159] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc set omap keys (pool="kube", namespace="", name="csi.volume.1cf4eea8-33c6-404e-835d-8082d74b0af1"): map[csi.imagename:csi-vol-1cf4eea8-33c6-404e-835d-8082d74b0af1 csi.volname:pvc-6f1fc001-305a-4368-8d06-d038614fbbbc csi.volume.owner:default])
I0507 09:21:34.926844       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1cf4eea8-33c6-404e-835d-8082d74b0af1) and image name (csi-vol-1cf4eea8-33c6-404e-835d-8082d74b0af1) for request name (pvc-6f1fc001-305a-4368-8d06-d038614fbbbc)
I0507 09:21:34.926931       1 rbd_util.go:423] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc rbd: create kube/csi-vol-1cf4eea8-33c6-404e-835d-8082d74b0af1 size 5120M (features: [layering]) using mon 199.204.45.156
I0507 09:21:34.926992       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc setting image options on kube/csi-vol-1cf4eea8-33c6-404e-835d-8082d74b0af1
I0507 09:21:36.580437       1 controllerserver.go:763] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc created image kube/csi-vol-1cf4eea8-33c6-404e-835d-8082d74b0af1 backed for request name pvc-6f1fc001-305a-4368-8d06-d038614fbbbc
I0507 09:21:36.614509       1 omap.go:159] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc set omap keys (pool="kube", namespace="", name="csi.volume.1cf4eea8-33c6-404e-835d-8082d74b0af1"): map[csi.imageid:37d9ec0c64dc])
I0507 09:21:36.753404       1 utils.go:205] ID: 24 Req-ID: pvc-6f1fc001-305a-4368-8d06-d038614fbbbc GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-1cf4eea8-33c6-404e-835d-8082d74b0af1","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1cf4eea8-33c6-404e-835d-8082d74b0af1"}}