I0203 17:27:35.100507       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0203 17:27:35.100710       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0203 17:27:35.100753       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0203 17:27:35.100795       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0203 17:27:35.100812       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0203 17:27:35.101042       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0203 17:27:35.101074       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0203 17:27:35.101090       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0203 17:27:35.101135       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0203 17:27:35.101242       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0203 17:27:35.102009       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0203 17:27:35.102092       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0203 17:27:39.019258       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:39.020521       1 utils.go:199] ID: 1 GRPC request: {}
I0203 17:27:39.020751       1 utils.go:205] ID: 1 GRPC response: {}
I0203 17:27:39.040695       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 17:27:39.040919       1 utils.go:199] ID: 2 GRPC request: {}
I0203 17:27:39.041013       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0203 17:27:39.041144       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 17:27:39.042553       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:39.042646       1 utils.go:199] ID: 3 GRPC request: {}
I0203 17:27:39.042734       1 utils.go:205] ID: 3 GRPC response: {}
I0203 17:27:39.049874       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0203 17:27:39.050051       1 utils.go:199] ID: 4 GRPC request: {}
I0203 17:27:39.050294       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0203 17:27:39.051090       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 17:27:39.051263       1 utils.go:199] ID: 5 GRPC request: {}
I0203 17:27:39.051325       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0203 17:27:39.051492       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}}}]}
I0203 17:27:40.949422       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:40.949518       1 utils.go:199] ID: 6 GRPC request: {}
I0203 17:27:40.949570       1 utils.go:205] ID: 6 GRPC response: {}
I0203 17:27:40.951779       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 17:27:40.951830       1 utils.go:199] ID: 7 GRPC request: {}
I0203 17:27:40.951854       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0203 17:27:40.951907       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 17:27:40.958878       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:40.958953       1 utils.go:199] ID: 8 GRPC request: {}
I0203 17:27:40.959034       1 utils.go:205] ID: 8 GRPC response: {}
I0203 17:27:40.962214       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0203 17:27:40.962369       1 utils.go:199] ID: 9 GRPC request: {}
I0203 17:27:40.962537       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0203 17:27:40.963241       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 17:27:40.963332       1 utils.go:199] ID: 10 GRPC request: {}
I0203 17:27:40.963411       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0203 17:27:40.963573       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}}}]}
I0203 17:27:40.964111       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 17:27:40.964199       1 utils.go:199] ID: 11 GRPC request: {}
I0203 17:27:40.964260       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0203 17:27:40.964393       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}}}]}
I0203 17:27:40.964828       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 17:27:40.964934       1 utils.go:199] ID: 12 GRPC request: {}
I0203 17:27:40.965003       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0203 17:27:40.965118       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}}}]}
I0203 17:27:42.965358       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 17:27:42.965416       1 utils.go:199] ID: 13 GRPC request: {}
I0203 17:27:42.965425       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0203 17:27:42.965447       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 17:27:42.969221       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:42.969271       1 utils.go:199] ID: 14 GRPC request: {}
I0203 17:27:42.969328       1 utils.go:205] ID: 14 GRPC response: {}
I0203 17:27:42.969903       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 17:27:42.969973       1 utils.go:199] ID: 15 GRPC request: {}
I0203 17:27:42.970003       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0203 17:27:42.970159       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}}}]}
I0203 17:27:44.896025       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:44.896062       1 utils.go:199] ID: 16 GRPC request: {}
I0203 17:27:44.896087       1 utils.go:205] ID: 16 GRPC response: {}
I0203 17:27:44.899392       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 17:27:44.899408       1 utils.go:199] ID: 17 GRPC request: {}
I0203 17:27:44.899420       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0203 17:27:44.899444       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 17:27:44.902404       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0203 17:27:44.902753       1 utils.go:199] ID: 18 GRPC request: {}
I0203 17:27:44.902819       1 utils.go:205] ID: 18 GRPC response: {}
I0203 17:27:44.903169       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0203 17:27:44.903202       1 utils.go:199] ID: 19 GRPC request: {}
I0203 17:27:44.903324       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0203 17:27:44.903850       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 17:27:44.903893       1 utils.go:199] ID: 20 GRPC request: {}
I0203 17:27:44.903910       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0203 17:27:44.903982       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}}}]}
I0203 17:28:45.180332       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0203 17:28:45.180697       1 utils.go:199] ID: 21 GRPC request: {}
I0203 17:28:45.180820       1 utils.go:205] ID: 21 GRPC response: {}
I0203 17:29:45.120366       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0203 17:29:45.120394       1 utils.go:199] ID: 22 GRPC request: {}
I0203 17:29:45.120405       1 utils.go:205] ID: 22 GRPC response: {}
I0203 17:30:43.509035       1 utils.go:198] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad GRPC call: /csi.v1.Controller/CreateVolume
I0203 17:30:43.519246       1 utils.go:199] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad","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}}]}
I0203 17:30:43.519405       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad setting disableInUseChecks: false image features: [layering] mounter: rbd
E0203 17:30:43.572897       1 omap.go:80] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0203 17:30:43.588775       1 omap.go:159] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad:3c453b2a-b723-405c-8f6c-72c445c47a01])
I0203 17:30:43.597039       1 omap.go:159] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad set omap keys (pool="kube", namespace="", name="csi.volume.3c453b2a-b723-405c-8f6c-72c445c47a01"): map[csi.imagename:csi-vol-3c453b2a-b723-405c-8f6c-72c445c47a01 csi.volname:pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad csi.volume.owner:default])
I0203 17:30:43.597059       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3c453b2a-b723-405c-8f6c-72c445c47a01) and image name (csi-vol-3c453b2a-b723-405c-8f6c-72c445c47a01) for request name (pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad)
I0203 17:30:43.597135       1 rbd_util.go:423] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad rbd: create kube/csi-vol-3c453b2a-b723-405c-8f6c-72c445c47a01 size 5120M (features: [layering]) using mon 199.19.213.154
I0203 17:30:43.597156       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad setting image options on kube/csi-vol-3c453b2a-b723-405c-8f6c-72c445c47a01
I0203 17:30:45.120373       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0203 17:30:45.120432       1 utils.go:199] ID: 24 GRPC request: {}
I0203 17:30:45.120458       1 utils.go:205] ID: 24 GRPC response: {}
I0203 17:30:45.591389       1 controllerserver.go:763] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad created image kube/csi-vol-3c453b2a-b723-405c-8f6c-72c445c47a01 backed for request name pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad
I0203 17:30:45.623068       1 omap.go:159] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad set omap keys (pool="kube", namespace="", name="csi.volume.3c453b2a-b723-405c-8f6c-72c445c47a01"): map[csi.imageid:37d97d9af8f5])
I0203 17:30:45.716037       1 utils.go:205] ID: 23 Req-ID: pvc-c18d01b4-1724-4818-bdcf-d21f0ab4aaad GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-3c453b2a-b723-405c-8f6c-72c445c47a01","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3c453b2a-b723-405c-8f6c-72c445c47a01"}}