I0506 00:49:13.005158       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0506 00:49:13.005283       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0506 00:49:13.005294       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0506 00:49:13.005298       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0506 00:49:13.005301       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0506 00:49:13.005304       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0506 00:49:13.005308       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0506 00:49:13.005311       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0506 00:49:13.005318       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0506 00:49:13.005322       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0506 00:49:13.005724       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0506 00:49:13.005783       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0506 00:49:15.126375       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:15.128330       1 utils.go:199] ID: 1 GRPC request: {}
I0506 00:49:15.128533       1 utils.go:205] ID: 1 GRPC response: {}
I0506 00:49:15.131001       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 00:49:15.131169       1 utils.go:199] ID: 2 GRPC request: {}
I0506 00:49:15.131195       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0506 00:49:15.131347       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 00:49:15.134650       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:15.134714       1 utils.go:199] ID: 3 GRPC request: {}
I0506 00:49:15.134740       1 utils.go:205] ID: 3 GRPC response: {}
I0506 00:49:15.135111       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0506 00:49:15.135158       1 utils.go:199] ID: 4 GRPC request: {}
I0506 00:49:15.135290       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0506 00:49:15.136116       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 00:49:15.136216       1 utils.go:199] ID: 5 GRPC request: {}
I0506 00:49:15.136229       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0506 00:49:15.136677       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}}}]}
I0506 00:49:17.780942       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:17.780989       1 utils.go:199] ID: 6 GRPC request: {}
I0506 00:49:17.781008       1 utils.go:205] ID: 6 GRPC response: {}
I0506 00:49:17.784297       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 00:49:17.784365       1 utils.go:199] ID: 7 GRPC request: {}
I0506 00:49:17.784374       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0506 00:49:17.784399       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 00:49:17.785673       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:17.785692       1 utils.go:199] ID: 8 GRPC request: {}
I0506 00:49:17.785821       1 utils.go:205] ID: 8 GRPC response: {}
I0506 00:49:17.786278       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0506 00:49:17.786325       1 utils.go:199] ID: 9 GRPC request: {}
I0506 00:49:17.786460       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0506 00:49:17.787099       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 00:49:17.787122       1 utils.go:199] ID: 10 GRPC request: {}
I0506 00:49:17.787131       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0506 00:49:17.787205       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}}}]}
I0506 00:49:17.787737       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 00:49:17.787763       1 utils.go:199] ID: 11 GRPC request: {}
I0506 00:49:17.787769       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0506 00:49:17.787819       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}}}]}
I0506 00:49:17.788120       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 00:49:17.788150       1 utils.go:199] ID: 12 GRPC request: {}
I0506 00:49:17.788160       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0506 00:49:17.788247       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}}}]}
I0506 00:49:18.997132       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 00:49:18.997186       1 utils.go:199] ID: 13 GRPC request: {}
I0506 00:49:18.997196       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0506 00:49:18.997237       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 00:49:19.003364       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:19.003388       1 utils.go:199] ID: 14 GRPC request: {}
I0506 00:49:19.003409       1 utils.go:205] ID: 14 GRPC response: {}
I0506 00:49:19.004029       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 00:49:19.004075       1 utils.go:199] ID: 15 GRPC request: {}
I0506 00:49:19.004086       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0506 00:49:19.004167       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}}}]}
I0506 00:49:20.181615       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:20.181652       1 utils.go:199] ID: 16 GRPC request: {}
I0506 00:49:20.181668       1 utils.go:205] ID: 16 GRPC response: {}
I0506 00:49:20.183656       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0506 00:49:20.183734       1 utils.go:199] ID: 17 GRPC request: {}
I0506 00:49:20.183759       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0506 00:49:20.183799       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0506 00:49:20.187338       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0506 00:49:20.187408       1 utils.go:199] ID: 18 GRPC request: {}
I0506 00:49:20.187441       1 utils.go:205] ID: 18 GRPC response: {}
I0506 00:49:20.187790       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0506 00:49:20.187828       1 utils.go:199] ID: 19 GRPC request: {}
I0506 00:49:20.187917       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0506 00:49:20.189605       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0506 00:49:20.189670       1 utils.go:199] ID: 20 GRPC request: {}
I0506 00:49:20.189739       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0506 00:49:20.189880       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}}}]}
I0506 00:50:20.393152       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0506 00:50:20.393223       1 utils.go:199] ID: 21 GRPC request: {}
I0506 00:50:20.393246       1 utils.go:205] ID: 21 GRPC response: {}
I0506 00:51:20.356698       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0506 00:51:20.356997       1 utils.go:199] ID: 22 GRPC request: {}
I0506 00:51:20.357014       1 utils.go:205] ID: 22 GRPC response: {}
I0506 00:52:20.410754       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0506 00:52:20.410811       1 utils.go:199] ID: 23 GRPC request: {}
I0506 00:52:20.410834       1 utils.go:205] ID: 23 GRPC response: {}
I0506 00:52:23.300483       1 utils.go:198] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 GRPC call: /csi.v1.Controller/CreateVolume
I0506 00:52:23.301567       1 utils.go:199] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2","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}}]}
I0506 00:52:23.301907       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0506 00:52:23.320428       1 omap.go:80] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0506 00:52:23.337193       1 omap.go:159] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2:0d21c72a-4418-40c0-9c11-39c353ecf496])
I0506 00:52:23.343595       1 omap.go:159] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 set omap keys (pool="kube", namespace="", name="csi.volume.0d21c72a-4418-40c0-9c11-39c353ecf496"): map[csi.imagename:csi-vol-0d21c72a-4418-40c0-9c11-39c353ecf496 csi.volname:pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 csi.volume.owner:default])
I0506 00:52:23.343725       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-0d21c72a-4418-40c0-9c11-39c353ecf496) and image name (csi-vol-0d21c72a-4418-40c0-9c11-39c353ecf496) for request name (pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2)
I0506 00:52:23.343881       1 rbd_util.go:423] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 rbd: create kube/csi-vol-0d21c72a-4418-40c0-9c11-39c353ecf496 size 5120M (features: [layering]) using mon 199.19.213.209
I0506 00:52:23.344042       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 setting image options on kube/csi-vol-0d21c72a-4418-40c0-9c11-39c353ecf496
I0506 00:52:24.458556       1 controllerserver.go:763] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 created image kube/csi-vol-0d21c72a-4418-40c0-9c11-39c353ecf496 backed for request name pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2
I0506 00:52:24.491026       1 omap.go:159] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 set omap keys (pool="kube", namespace="", name="csi.volume.0d21c72a-4418-40c0-9c11-39c353ecf496"): map[csi.imageid:37d9de0e2857])
I0506 00:52:24.587005       1 utils.go:205] ID: 24 Req-ID: pvc-0ffa112f-0745-49ff-bf81-5a0b6b126ab2 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-0d21c72a-4418-40c0-9c11-39c353ecf496","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-0d21c72a-4418-40c0-9c11-39c353ecf496"}}