I0201 18:56:02.863302       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0201 18:56:02.863523       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0201 18:56:02.863598       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0201 18:56:02.863640       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0201 18:56:02.863674       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0201 18:56:02.863724       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0201 18:56:02.863761       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0201 18:56:02.863805       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0201 18:56:02.863841       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0201 18:56:02.863883       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0201 18:56:02.864202       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0201 18:56:02.864284       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0201 18:56:06.032178       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:06.042546       1 utils.go:199] ID: 1 GRPC request: {}
I0201 18:56:06.042673       1 utils.go:205] ID: 1 GRPC response: {}
I0201 18:56:06.050849       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0201 18:56:06.050954       1 utils.go:199] ID: 2 GRPC request: {}
I0201 18:56:06.051021       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0201 18:56:06.051108       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0201 18:56:06.053895       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:06.054030       1 utils.go:199] ID: 3 GRPC request: {}
I0201 18:56:06.054085       1 utils.go:205] ID: 3 GRPC response: {}
I0201 18:56:06.061580       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0201 18:56:06.061723       1 utils.go:199] ID: 4 GRPC request: {}
I0201 18:56:06.061895       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0201 18:56:06.062540       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0201 18:56:06.062623       1 utils.go:199] ID: 5 GRPC request: {}
I0201 18:56:06.062693       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0201 18:56:06.062843       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}}}]}
I0201 18:56:07.974981       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:07.975124       1 utils.go:199] ID: 6 GRPC request: {}
I0201 18:56:07.975192       1 utils.go:205] ID: 6 GRPC response: {}
I0201 18:56:07.978136       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0201 18:56:07.978204       1 utils.go:199] ID: 7 GRPC request: {}
I0201 18:56:07.978267       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0201 18:56:07.978377       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0201 18:56:07.979453       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:07.979531       1 utils.go:199] ID: 8 GRPC request: {}
I0201 18:56:07.979595       1 utils.go:205] ID: 8 GRPC response: {}
I0201 18:56:07.979936       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0201 18:56:07.980021       1 utils.go:199] ID: 9 GRPC request: {}
I0201 18:56:07.980176       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0201 18:56:07.980704       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0201 18:56:07.980807       1 utils.go:199] ID: 10 GRPC request: {}
I0201 18:56:07.980851       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0201 18:56:07.980992       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}}}]}
I0201 18:56:07.981380       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0201 18:56:07.981454       1 utils.go:199] ID: 11 GRPC request: {}
I0201 18:56:07.981554       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0201 18:56:07.981693       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}}}]}
I0201 18:56:07.982003       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0201 18:56:07.982063       1 utils.go:199] ID: 12 GRPC request: {}
I0201 18:56:07.982124       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0201 18:56:07.982216       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}}}]}
I0201 18:56:09.761062       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0201 18:56:09.761139       1 utils.go:199] ID: 13 GRPC request: {}
I0201 18:56:09.761145       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0201 18:56:09.761182       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0201 18:56:09.763989       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:09.764028       1 utils.go:199] ID: 14 GRPC request: {}
I0201 18:56:09.764044       1 utils.go:205] ID: 14 GRPC response: {}
I0201 18:56:09.764657       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0201 18:56:09.764699       1 utils.go:199] ID: 15 GRPC request: {}
I0201 18:56:09.764711       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0201 18:56:09.764804       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}}}]}
I0201 18:56:11.696793       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:11.696835       1 utils.go:199] ID: 16 GRPC request: {}
I0201 18:56:11.696876       1 utils.go:205] ID: 16 GRPC response: {}
I0201 18:56:11.700178       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0201 18:56:11.700220       1 utils.go:199] ID: 17 GRPC request: {}
I0201 18:56:11.700226       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0201 18:56:11.700246       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0201 18:56:11.701727       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0201 18:56:11.701738       1 utils.go:199] ID: 18 GRPC request: {}
I0201 18:56:11.701748       1 utils.go:205] ID: 18 GRPC response: {}
I0201 18:56:11.702022       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0201 18:56:11.702034       1 utils.go:199] ID: 19 GRPC request: {}
I0201 18:56:11.702124       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0201 18:56:11.702655       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0201 18:56:11.702672       1 utils.go:199] ID: 20 GRPC request: {}
I0201 18:56:11.702704       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0201 18:56:11.702750       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}}}]}
I0201 18:57:12.015854       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0201 18:57:12.015951       1 utils.go:199] ID: 21 GRPC request: {}
I0201 18:57:12.016005       1 utils.go:205] ID: 21 GRPC response: {}
I0201 18:58:11.956947       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0201 18:58:11.956978       1 utils.go:199] ID: 22 GRPC request: {}
I0201 18:58:11.956994       1 utils.go:205] ID: 22 GRPC response: {}
I0201 18:59:11.625069       1 utils.go:198] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff GRPC call: /csi.v1.Controller/CreateVolume
I0201 18:59:11.625303       1 utils.go:199] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff","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}}]}
I0201 18:59:11.625512       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff setting disableInUseChecks: false image features: [layering] mounter: rbd
E0201 18:59:11.664574       1 omap.go:80] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0201 18:59:11.688982       1 omap.go:159] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff:f27c4ac6-1405-47cd-b419-4b8bdf63180e])
I0201 18:59:11.699416       1 omap.go:159] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff set omap keys (pool="kube", namespace="", name="csi.volume.f27c4ac6-1405-47cd-b419-4b8bdf63180e"): map[csi.imagename:csi-vol-f27c4ac6-1405-47cd-b419-4b8bdf63180e csi.volname:pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff csi.volume.owner:default])
I0201 18:59:11.699441       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f27c4ac6-1405-47cd-b419-4b8bdf63180e) and image name (csi-vol-f27c4ac6-1405-47cd-b419-4b8bdf63180e) for request name (pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff)
I0201 18:59:11.699499       1 rbd_util.go:423] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff rbd: create kube/csi-vol-f27c4ac6-1405-47cd-b419-4b8bdf63180e size 5120M (features: [layering]) using mon 199.204.45.65
I0201 18:59:11.699524       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff setting image options on kube/csi-vol-f27c4ac6-1405-47cd-b419-4b8bdf63180e
I0201 18:59:11.956967       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0201 18:59:11.957217       1 utils.go:199] ID: 24 GRPC request: {}
I0201 18:59:11.957410       1 utils.go:205] ID: 24 GRPC response: {}
I0201 18:59:13.861065       1 controllerserver.go:763] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff created image kube/csi-vol-f27c4ac6-1405-47cd-b419-4b8bdf63180e backed for request name pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff
I0201 18:59:13.957205       1 omap.go:159] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff set omap keys (pool="kube", namespace="", name="csi.volume.f27c4ac6-1405-47cd-b419-4b8bdf63180e"): map[csi.imageid:37d9658d4311])
I0201 18:59:14.089351       1 utils.go:205] ID: 23 Req-ID: pvc-b7f24fad-217c-4ba7-af7e-8adeb2c787ff GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-f27c4ac6-1405-47cd-b419-4b8bdf63180e","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f27c4ac6-1405-47cd-b419-4b8bdf63180e"}}
I0201 19:00:11.982064       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0201 19:00:11.984360       1 utils.go:199] ID: 25 GRPC request: {}
I0201 19:00:11.984457       1 utils.go:205] ID: 25 GRPC response: {}