I0402 06:07:17.984517       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0402 06:07:17.984689       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0402 06:07:17.984705       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0402 06:07:17.984714       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0402 06:07:17.984719       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0402 06:07:17.984723       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0402 06:07:17.984727       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0402 06:07:17.984732       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0402 06:07:17.984738       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0402 06:07:17.984743       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0402 06:07:17.985450       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0402 06:07:17.985522       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0402 06:07:19.905354       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:19.906779       1 utils.go:199] ID: 1 GRPC request: {}
I0402 06:07:19.906901       1 utils.go:205] ID: 1 GRPC response: {}
I0402 06:07:19.909862       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 06:07:19.909959       1 utils.go:199] ID: 2 GRPC request: {}
I0402 06:07:19.909972       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0402 06:07:19.910026       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 06:07:19.912946       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:19.912979       1 utils.go:199] ID: 3 GRPC request: {}
I0402 06:07:19.913002       1 utils.go:205] ID: 3 GRPC response: {}
I0402 06:07:19.913521       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 06:07:19.913580       1 utils.go:199] ID: 4 GRPC request: {}
I0402 06:07:19.913739       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 06:07:19.914495       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 06:07:19.914583       1 utils.go:199] ID: 5 GRPC request: {}
I0402 06:07:19.914596       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0402 06:07:19.914799       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}}}]}
I0402 06:07:22.557671       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:22.557703       1 utils.go:199] ID: 6 GRPC request: {}
I0402 06:07:22.557716       1 utils.go:205] ID: 6 GRPC response: {}
I0402 06:07:22.559454       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 06:07:22.559483       1 utils.go:199] ID: 7 GRPC request: {}
I0402 06:07:22.559490       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0402 06:07:22.559529       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 06:07:22.561872       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:22.561895       1 utils.go:199] ID: 8 GRPC request: {}
I0402 06:07:22.561908       1 utils.go:205] ID: 8 GRPC response: {}
I0402 06:07:22.562481       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 06:07:22.562523       1 utils.go:199] ID: 9 GRPC request: {}
I0402 06:07:22.562668       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 06:07:22.563604       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 06:07:22.563667       1 utils.go:199] ID: 10 GRPC request: {}
I0402 06:07:22.563681       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0402 06:07:22.563826       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}}}]}
I0402 06:07:22.564645       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 06:07:22.564684       1 utils.go:199] ID: 11 GRPC request: {}
I0402 06:07:22.564702       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0402 06:07:22.564783       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}}}]}
I0402 06:07:22.565810       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 06:07:22.565849       1 utils.go:199] ID: 12 GRPC request: {}
I0402 06:07:22.565859       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0402 06:07:22.565957       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}}}]}
I0402 06:07:23.807380       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 06:07:23.807421       1 utils.go:199] ID: 13 GRPC request: {}
I0402 06:07:23.807427       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0402 06:07:23.807553       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 06:07:23.810621       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:23.810653       1 utils.go:199] ID: 14 GRPC request: {}
I0402 06:07:23.810665       1 utils.go:205] ID: 14 GRPC response: {}
I0402 06:07:23.811123       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 06:07:23.811191       1 utils.go:199] ID: 15 GRPC request: {}
I0402 06:07:23.811224       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0402 06:07:23.811383       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}}}]}
I0402 06:07:25.025420       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:25.025453       1 utils.go:199] ID: 16 GRPC request: {}
I0402 06:07:25.025467       1 utils.go:205] ID: 16 GRPC response: {}
I0402 06:07:25.027870       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 06:07:25.027901       1 utils.go:199] ID: 17 GRPC request: {}
I0402 06:07:25.027911       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0402 06:07:25.028107       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 06:07:25.030467       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0402 06:07:25.030492       1 utils.go:199] ID: 18 GRPC request: {}
I0402 06:07:25.030504       1 utils.go:205] ID: 18 GRPC response: {}
I0402 06:07:25.030842       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 06:07:25.030868       1 utils.go:199] ID: 19 GRPC request: {}
I0402 06:07:25.030977       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 06:07:25.031727       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 06:07:25.031754       1 utils.go:199] ID: 20 GRPC request: {}
I0402 06:07:25.031763       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0402 06:07:25.031832       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}}}]}
I0402 06:08:25.243039       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0402 06:08:25.243094       1 utils.go:199] ID: 21 GRPC request: {}
I0402 06:08:25.243126       1 utils.go:205] ID: 21 GRPC response: {}
I0402 06:09:25.198192       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0402 06:09:25.198353       1 utils.go:199] ID: 22 GRPC request: {}
I0402 06:09:25.198439       1 utils.go:205] ID: 22 GRPC response: {}
I0402 06:10:25.197121       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0402 06:10:25.197188       1 utils.go:199] ID: 23 GRPC request: {}
I0402 06:10:25.197398       1 utils.go:205] ID: 23 GRPC response: {}
I0402 06:10:27.997301       1 utils.go:198] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f GRPC call: /csi.v1.Controller/CreateVolume
I0402 06:10:27.997961       1 utils.go:199] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f","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}}]}
I0402 06:10:27.998491       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f setting disableInUseChecks: false image features: [layering] mounter: rbd
E0402 06:10:28.019220       1 omap.go:80] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0402 06:10:28.035179       1 omap.go:159] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f:9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2])
I0402 06:10:28.040973       1 omap.go:159] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f set omap keys (pool="kube", namespace="", name="csi.volume.9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2"): map[csi.imagename:csi-vol-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2 csi.volname:pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f csi.volume.owner:default])
I0402 06:10:28.041014       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2) and image name (csi-vol-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2) for request name (pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f)
I0402 06:10:28.041302       1 rbd_util.go:423] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f rbd: create kube/csi-vol-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2 size 5120M (features: [layering]) using mon 199.204.45.223
I0402 06:10:28.041511       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f setting image options on kube/csi-vol-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2
I0402 06:10:30.047313       1 controllerserver.go:763] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f created image kube/csi-vol-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2 backed for request name pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f
I0402 06:10:30.080325       1 omap.go:159] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f set omap keys (pool="kube", namespace="", name="csi.volume.9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2"): map[csi.imageid:37d96267bdd6])
I0402 06:10:30.178952       1 utils.go:205] ID: 24 Req-ID: pvc-cf8573c2-5dd5-49c3-a1ca-f9f9fdc8289f GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9ba6cb4a-91d3-482f-accb-d9ed7c1fe8a2"}}