I0510 00:55:51.303966       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0510 00:55:51.304103       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0510 00:55:51.304120       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0510 00:55:51.304124       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0510 00:55:51.304128       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0510 00:55:51.304133       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0510 00:55:51.304137       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0510 00:55:51.304141       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0510 00:55:51.304144       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0510 00:55:51.304147       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0510 00:55:51.304767       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0510 00:55:51.304833       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0510 00:55:56.578125       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0510 00:55:56.579109       1 utils.go:199] ID: 1 GRPC request: {}
I0510 00:55:56.579159       1 utils.go:205] ID: 1 GRPC response: {}
I0510 00:55:56.580732       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:55:56.580769       1 utils.go:199] ID: 2 GRPC request: {}
I0510 00:55:56.580780       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0510 00:55:56.580846       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:55:56.582489       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0510 00:55:56.582534       1 utils.go:199] ID: 3 GRPC request: {}
I0510 00:55:56.582557       1 utils.go:205] ID: 3 GRPC response: {}
I0510 00:55:56.583056       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0510 00:55:56.583119       1 utils.go:199] ID: 4 GRPC request: {}
I0510 00:55:56.583242       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0510 00:55:56.584134       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:55:56.584220       1 utils.go:199] ID: 5 GRPC request: {}
I0510 00:55:56.584234       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0510 00:55:56.584378       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}}}]}
I0510 00:55:59.962528       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0510 00:55:59.962568       1 utils.go:199] ID: 6 GRPC request: {}
I0510 00:55:59.962584       1 utils.go:205] ID: 6 GRPC response: {}
I0510 00:55:59.969038       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:55:59.969055       1 utils.go:199] ID: 7 GRPC request: {}
I0510 00:55:59.969062       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0510 00:55:59.969099       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:55:59.978304       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0510 00:55:59.978452       1 utils.go:199] ID: 8 GRPC request: {}
I0510 00:55:59.978486       1 utils.go:205] ID: 8 GRPC response: {}
I0510 00:55:59.979182       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0510 00:55:59.979346       1 utils.go:199] ID: 9 GRPC request: {}
I0510 00:55:59.979630       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0510 00:55:59.980765       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:55:59.980802       1 utils.go:199] ID: 10 GRPC request: {}
I0510 00:55:59.980824       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0510 00:55:59.980975       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}}}]}
I0510 00:55:59.981812       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:55:59.981907       1 utils.go:199] ID: 11 GRPC request: {}
I0510 00:55:59.981946       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0510 00:55:59.982108       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}}}]}
I0510 00:55:59.982727       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:55:59.982790       1 utils.go:199] ID: 12 GRPC request: {}
I0510 00:55:59.982811       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0510 00:55:59.983002       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}}}]}
I0510 00:56:01.302319       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:56:01.302361       1 utils.go:199] ID: 13 GRPC request: {}
I0510 00:56:01.302397       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0510 00:56:01.302429       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:56:01.305523       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0510 00:56:01.305547       1 utils.go:199] ID: 14 GRPC request: {}
I0510 00:56:01.305562       1 utils.go:205] ID: 14 GRPC response: {}
I0510 00:56:01.306537       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:56:01.306577       1 utils.go:199] ID: 15 GRPC request: {}
I0510 00:56:01.306597       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0510 00:56:01.306772       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}}}]}
I0510 00:56:02.564660       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0510 00:56:02.564719       1 utils.go:199] ID: 16 GRPC request: {}
I0510 00:56:02.564742       1 utils.go:205] ID: 16 GRPC response: {}
I0510 00:56:02.569177       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:56:02.569319       1 utils.go:199] ID: 17 GRPC request: {}
I0510 00:56:02.569385       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0510 00:56:02.569457       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:56:02.571716       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0510 00:56:02.571736       1 utils.go:199] ID: 18 GRPC request: {}
I0510 00:56:02.571748       1 utils.go:205] ID: 18 GRPC response: {}
I0510 00:56:02.572671       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0510 00:56:02.572692       1 utils.go:199] ID: 19 GRPC request: {}
I0510 00:56:02.572782       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0510 00:56:02.573453       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:56:02.573498       1 utils.go:199] ID: 20 GRPC request: {}
I0510 00:56:02.573508       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0510 00:56:02.573722       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}}}]}
I0510 00:57:02.828535       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0510 00:57:02.828617       1 utils.go:199] ID: 21 GRPC request: {}
I0510 00:57:02.828662       1 utils.go:205] ID: 21 GRPC response: {}
I0510 00:58:02.781110       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0510 00:58:02.781314       1 utils.go:199] ID: 22 GRPC request: {}
I0510 00:58:02.781370       1 utils.go:205] ID: 22 GRPC response: {}
I0510 00:59:02.779740       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0510 00:59:02.779818       1 utils.go:199] ID: 23 GRPC request: {}
I0510 00:59:02.780066       1 utils.go:205] ID: 23 GRPC response: {}
I0510 00:59:05.154250       1 utils.go:198] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 GRPC call: /csi.v1.Controller/CreateVolume
I0510 00:59:05.154715       1 utils.go:199] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6","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}}]}
I0510 00:59:05.155044       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0510 00:59:05.172062       1 omap.go:80] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0510 00:59:05.189641       1 omap.go:159] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6:86d4571c-6870-4a2a-beb5-393e9fbd57be])
I0510 00:59:05.195312       1 omap.go:159] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 set omap keys (pool="kube", namespace="", name="csi.volume.86d4571c-6870-4a2a-beb5-393e9fbd57be"): map[csi.imagename:csi-vol-86d4571c-6870-4a2a-beb5-393e9fbd57be csi.volname:pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 csi.volume.owner:default])
I0510 00:59:05.195365       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-86d4571c-6870-4a2a-beb5-393e9fbd57be) and image name (csi-vol-86d4571c-6870-4a2a-beb5-393e9fbd57be) for request name (pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6)
I0510 00:59:05.195577       1 rbd_util.go:423] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 rbd: create kube/csi-vol-86d4571c-6870-4a2a-beb5-393e9fbd57be size 5120M (features: [layering]) using mon 199.204.45.33
I0510 00:59:05.195725       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 setting image options on kube/csi-vol-86d4571c-6870-4a2a-beb5-393e9fbd57be
I0510 00:59:06.581978       1 controllerserver.go:763] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 created image kube/csi-vol-86d4571c-6870-4a2a-beb5-393e9fbd57be backed for request name pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6
I0510 00:59:06.626205       1 omap.go:159] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 set omap keys (pool="kube", namespace="", name="csi.volume.86d4571c-6870-4a2a-beb5-393e9fbd57be"): map[csi.imageid:37d97c9b1330])
I0510 00:59:06.724300       1 utils.go:205] ID: 24 Req-ID: pvc-9ab69807-4ce4-44dd-bfa7-244e8bf1e3e6 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-86d4571c-6870-4a2a-beb5-393e9fbd57be","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-86d4571c-6870-4a2a-beb5-393e9fbd57be"}}