I0203 22:40:28.568988       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0203 22:40:28.569275       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0203 22:40:28.569393       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0203 22:40:28.569444       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0203 22:40:28.569474       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0203 22:40:28.569529       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0203 22:40:28.569559       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0203 22:40:28.569603       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0203 22:40:28.569637       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0203 22:40:28.569668       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0203 22:40:28.569982       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0203 22:40:28.570100       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0203 22:40:32.229799       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:32.235527       1 utils.go:199] ID: 1 GRPC request: {}
I0203 22:40:32.235631       1 utils.go:205] ID: 1 GRPC response: {}
I0203 22:40:32.243605       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 22:40:32.243759       1 utils.go:199] ID: 2 GRPC request: {}
I0203 22:40:32.243784       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0203 22:40:32.243854       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 22:40:32.249616       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:32.249639       1 utils.go:199] ID: 3 GRPC request: {}
I0203 22:40:32.249663       1 utils.go:205] ID: 3 GRPC response: {}
I0203 22:40:32.252934       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0203 22:40:32.253048       1 utils.go:199] ID: 4 GRPC request: {}
I0203 22:40:32.253195       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0203 22:40:32.254379       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 22:40:32.254446       1 utils.go:199] ID: 5 GRPC request: {}
I0203 22:40:32.254456       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0203 22:40:32.254531       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 22:40:34.400306       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:34.400340       1 utils.go:199] ID: 6 GRPC request: {}
I0203 22:40:34.400353       1 utils.go:205] ID: 6 GRPC response: {}
I0203 22:40:34.402055       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 22:40:34.402071       1 utils.go:199] ID: 7 GRPC request: {}
I0203 22:40:34.402077       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0203 22:40:34.402094       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 22:40:34.403291       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:34.403304       1 utils.go:199] ID: 8 GRPC request: {}
I0203 22:40:34.403313       1 utils.go:205] ID: 8 GRPC response: {}
I0203 22:40:34.405283       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0203 22:40:34.405299       1 utils.go:199] ID: 9 GRPC request: {}
I0203 22:40:34.405374       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0203 22:40:34.405909       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 22:40:34.405993       1 utils.go:199] ID: 10 GRPC request: {}
I0203 22:40:34.406013       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0203 22:40:34.406140       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 22:40:34.406507       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 22:40:34.406521       1 utils.go:199] ID: 11 GRPC request: {}
I0203 22:40:34.406583       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0203 22:40:34.406674       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 22:40:34.406903       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 22:40:34.406916       1 utils.go:199] ID: 12 GRPC request: {}
I0203 22:40:34.406920       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0203 22:40:34.407034       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 22:40:36.124920       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 22:40:36.125013       1 utils.go:199] ID: 13 GRPC request: {}
I0203 22:40:36.125035       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0203 22:40:36.125099       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 22:40:36.130215       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:36.130250       1 utils.go:199] ID: 14 GRPC request: {}
I0203 22:40:36.130266       1 utils.go:205] ID: 14 GRPC response: {}
I0203 22:40:36.131262       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 22:40:36.131286       1 utils.go:199] ID: 15 GRPC request: {}
I0203 22:40:36.131294       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0203 22:40:36.131414       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 22:40:37.890385       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:37.890475       1 utils.go:199] ID: 16 GRPC request: {}
I0203 22:40:37.890491       1 utils.go:205] ID: 16 GRPC response: {}
I0203 22:40:37.893125       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0203 22:40:37.893155       1 utils.go:199] ID: 17 GRPC request: {}
I0203 22:40:37.893167       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0203 22:40:37.893189       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0203 22:40:37.897485       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0203 22:40:37.897611       1 utils.go:199] ID: 18 GRPC request: {}
I0203 22:40:37.897669       1 utils.go:205] ID: 18 GRPC response: {}
I0203 22:40:37.898001       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0203 22:40:37.899983       1 utils.go:199] ID: 19 GRPC request: {}
I0203 22:40:37.900234       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0203 22:40:37.904289       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0203 22:40:37.904330       1 utils.go:199] ID: 20 GRPC request: {}
I0203 22:40:37.904403       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0203 22:40:37.906335       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 22:41:38.141669       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0203 22:41:38.141855       1 utils.go:199] ID: 21 GRPC request: {}
I0203 22:41:38.141991       1 utils.go:205] ID: 21 GRPC response: {}
I0203 22:42:38.140953       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0203 22:42:38.141006       1 utils.go:199] ID: 22 GRPC request: {}
I0203 22:42:38.141020       1 utils.go:205] ID: 22 GRPC response: {}
I0203 22:43:32.733356       1 utils.go:198] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a GRPC call: /csi.v1.Controller/CreateVolume
I0203 22:43:32.733795       1 utils.go:199] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-e6745fab-506b-491e-802c-9f06e4e8af4a","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-e6745fab-506b-491e-802c-9f06e4e8af4a","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 22:43:32.734063       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a setting disableInUseChecks: false image features: [layering] mounter: rbd
E0203 22:43:32.792447       1 omap.go:80] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0203 22:43:32.809032       1 omap.go:159] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-e6745fab-506b-491e-802c-9f06e4e8af4a:8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785])
I0203 22:43:32.816388       1 omap.go:159] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a set omap keys (pool="kube", namespace="", name="csi.volume.8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785"): map[csi.imagename:csi-vol-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785 csi.volname:pvc-e6745fab-506b-491e-802c-9f06e4e8af4a csi.volume.owner:default])
I0203 22:43:32.816411       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785) and image name (csi-vol-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785) for request name (pvc-e6745fab-506b-491e-802c-9f06e4e8af4a)
I0203 22:43:32.816473       1 rbd_util.go:423] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a rbd: create kube/csi-vol-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785 size 5120M (features: [layering]) using mon 199.204.45.156
I0203 22:43:32.816496       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a setting image options on kube/csi-vol-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785
I0203 22:43:34.219252       1 controllerserver.go:763] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a created image kube/csi-vol-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785 backed for request name pvc-e6745fab-506b-491e-802c-9f06e4e8af4a
I0203 22:43:34.248643       1 omap.go:159] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a set omap keys (pool="kube", namespace="", name="csi.volume.8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785"): map[csi.imageid:37d9466def9a])
I0203 22:43:34.345874       1 utils.go:205] ID: 23 Req-ID: pvc-e6745fab-506b-491e-802c-9f06e4e8af4a GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8fcb1d6e-6ac4-424a-b3ee-b2a6c9f0f785"}}
I0203 22:43:38.139174       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0203 22:43:38.139211       1 utils.go:199] ID: 24 GRPC request: {}
I0203 22:43:38.139230       1 utils.go:205] ID: 24 GRPC response: {}