I0422 06:06:25.021353       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0422 06:06:25.021489       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0422 06:06:25.021505       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0422 06:06:25.021511       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0422 06:06:25.021516       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0422 06:06:25.021521       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0422 06:06:25.021527       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0422 06:06:25.021532       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0422 06:06:25.021537       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0422 06:06:25.021542       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0422 06:06:25.021896       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0422 06:06:25.021898       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0422 06:06:27.022338       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:27.023641       1 utils.go:199] ID: 1 GRPC request: {}
I0422 06:06:27.023693       1 utils.go:205] ID: 1 GRPC response: {}
I0422 06:06:27.027819       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 06:06:27.027898       1 utils.go:199] ID: 2 GRPC request: {}
I0422 06:06:27.027912       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0422 06:06:27.027960       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 06:06:27.029589       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:27.029615       1 utils.go:199] ID: 3 GRPC request: {}
I0422 06:06:27.029629       1 utils.go:205] ID: 3 GRPC response: {}
I0422 06:06:27.029971       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0422 06:06:27.030025       1 utils.go:199] ID: 4 GRPC request: {}
I0422 06:06:27.030153       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0422 06:06:27.030934       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 06:06:27.030975       1 utils.go:199] ID: 5 GRPC request: {}
I0422 06:06:27.030981       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0422 06:06:27.031065       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}}}]}
I0422 06:06:29.885375       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:29.885412       1 utils.go:199] ID: 6 GRPC request: {}
I0422 06:06:29.885432       1 utils.go:205] ID: 6 GRPC response: {}
I0422 06:06:29.888394       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 06:06:29.888433       1 utils.go:199] ID: 7 GRPC request: {}
I0422 06:06:29.888445       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0422 06:06:29.888587       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 06:06:29.892372       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:29.892427       1 utils.go:199] ID: 8 GRPC request: {}
I0422 06:06:29.892448       1 utils.go:205] ID: 8 GRPC response: {}
I0422 06:06:29.892971       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0422 06:06:29.892992       1 utils.go:199] ID: 9 GRPC request: {}
I0422 06:06:29.893068       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0422 06:06:29.893741       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 06:06:29.893767       1 utils.go:199] ID: 10 GRPC request: {}
I0422 06:06:29.893774       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0422 06:06:29.893835       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}}}]}
I0422 06:06:29.894254       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 06:06:29.894270       1 utils.go:199] ID: 11 GRPC request: {}
I0422 06:06:29.894276       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0422 06:06:29.894336       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}}}]}
I0422 06:06:29.894729       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 06:06:29.894980       1 utils.go:199] ID: 12 GRPC request: {}
I0422 06:06:29.894992       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0422 06:06:29.895120       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}}}]}
I0422 06:06:31.675471       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 06:06:31.675507       1 utils.go:199] ID: 13 GRPC request: {}
I0422 06:06:31.675528       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0422 06:06:31.675557       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 06:06:31.677267       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:31.677286       1 utils.go:199] ID: 14 GRPC request: {}
I0422 06:06:31.677299       1 utils.go:205] ID: 14 GRPC response: {}
I0422 06:06:31.677725       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 06:06:31.677768       1 utils.go:199] ID: 15 GRPC request: {}
I0422 06:06:31.677784       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0422 06:06:31.677925       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}}}]}
I0422 06:06:32.798754       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:32.798786       1 utils.go:199] ID: 16 GRPC request: {}
I0422 06:06:32.798800       1 utils.go:205] ID: 16 GRPC response: {}
I0422 06:06:32.803073       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0422 06:06:32.803103       1 utils.go:199] ID: 17 GRPC request: {}
I0422 06:06:32.803112       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0422 06:06:32.803136       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0422 06:06:32.805316       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0422 06:06:32.805344       1 utils.go:199] ID: 18 GRPC request: {}
I0422 06:06:32.805355       1 utils.go:205] ID: 18 GRPC response: {}
I0422 06:06:32.805693       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0422 06:06:32.805714       1 utils.go:199] ID: 19 GRPC request: {}
I0422 06:06:32.805826       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0422 06:06:32.806602       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0422 06:06:32.806653       1 utils.go:199] ID: 20 GRPC request: {}
I0422 06:06:32.806666       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0422 06:06:32.806886       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}}}]}
I0422 06:07:33.015557       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0422 06:07:33.015680       1 utils.go:199] ID: 21 GRPC request: {}
I0422 06:07:33.015749       1 utils.go:205] ID: 21 GRPC response: {}
I0422 06:08:32.968559       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0422 06:08:32.968758       1 utils.go:199] ID: 22 GRPC request: {}
I0422 06:08:32.968971       1 utils.go:205] ID: 22 GRPC response: {}
I0422 06:09:32.967256       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0422 06:09:32.967384       1 utils.go:199] ID: 23 GRPC request: {}
I0422 06:09:32.967463       1 utils.go:205] ID: 23 GRPC response: {}
I0422 06:09:33.257374       1 utils.go:198] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 GRPC call: /csi.v1.Controller/CreateVolume
I0422 06:09:33.257835       1 utils.go:199] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7","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}}]}
I0422 06:09:33.258048       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0422 06:09:33.275332       1 omap.go:80] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0422 06:09:33.290874       1 omap.go:159] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7:b01e6dc1-c7a9-431c-86e7-cc07685945f8])
I0422 06:09:33.299140       1 omap.go:159] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 set omap keys (pool="kube", namespace="", name="csi.volume.b01e6dc1-c7a9-431c-86e7-cc07685945f8"): map[csi.imagename:csi-vol-b01e6dc1-c7a9-431c-86e7-cc07685945f8 csi.volname:pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 csi.volume.owner:default])
I0422 06:09:33.299180       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b01e6dc1-c7a9-431c-86e7-cc07685945f8) and image name (csi-vol-b01e6dc1-c7a9-431c-86e7-cc07685945f8) for request name (pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7)
I0422 06:09:33.299256       1 rbd_util.go:423] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 rbd: create kube/csi-vol-b01e6dc1-c7a9-431c-86e7-cc07685945f8 size 5120M (features: [layering]) using mon 199.204.45.19
I0422 06:09:33.299302       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 setting image options on kube/csi-vol-b01e6dc1-c7a9-431c-86e7-cc07685945f8
I0422 06:09:35.222044       1 controllerserver.go:763] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 created image kube/csi-vol-b01e6dc1-c7a9-431c-86e7-cc07685945f8 backed for request name pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7
I0422 06:09:35.260108       1 omap.go:159] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 set omap keys (pool="kube", namespace="", name="csi.volume.b01e6dc1-c7a9-431c-86e7-cc07685945f8"): map[csi.imageid:37d97bfbae40])
I0422 06:09:35.359206       1 utils.go:205] ID: 24 Req-ID: pvc-dbf1cc26-0202-488c-b229-d5c6eb4a16e7 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-b01e6dc1-c7a9-431c-86e7-cc07685945f8","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-b01e6dc1-c7a9-431c-86e7-cc07685945f8"}}