I0402 00:52:48.488095       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0402 00:52:48.488205       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0402 00:52:48.488215       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0402 00:52:48.488218       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0402 00:52:48.488221       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0402 00:52:48.488224       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0402 00:52:48.488227       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0402 00:52:48.488229       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0402 00:52:48.488236       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0402 00:52:48.488238       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0402 00:52:48.488618       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0402 00:52:48.488675       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0402 00:52:59.726827       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0402 00:52:59.729651       1 utils.go:199] ID: 1 GRPC request: {}
I0402 00:52:59.729726       1 utils.go:205] ID: 1 GRPC response: {}
I0402 00:52:59.734511       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 00:52:59.734741       1 utils.go:199] ID: 2 GRPC request: {}
I0402 00:52:59.734877       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0402 00:52:59.735044       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 00:52:59.736761       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0402 00:52:59.736806       1 utils.go:199] ID: 3 GRPC request: {}
I0402 00:52:59.736832       1 utils.go:205] ID: 3 GRPC response: {}
I0402 00:52:59.737325       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 00:52:59.737391       1 utils.go:199] ID: 4 GRPC request: {}
I0402 00:52:59.737527       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 00:52:59.738333       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 00:52:59.738508       1 utils.go:199] ID: 5 GRPC request: {}
I0402 00:52:59.738669       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0402 00:52:59.739021       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 00:53:06.293415       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0402 00:53:06.293452       1 utils.go:199] ID: 6 GRPC request: {}
I0402 00:53:06.293467       1 utils.go:205] ID: 6 GRPC response: {}
I0402 00:53:06.296743       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 00:53:06.296788       1 utils.go:199] ID: 7 GRPC request: {}
I0402 00:53:06.296806       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0402 00:53:06.296920       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 00:53:06.298956       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0402 00:53:06.298977       1 utils.go:199] ID: 8 GRPC request: {}
I0402 00:53:06.298987       1 utils.go:205] ID: 8 GRPC response: {}
I0402 00:53:06.299400       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 00:53:06.299418       1 utils.go:199] ID: 9 GRPC request: {}
I0402 00:53:06.299500       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 00:53:06.300148       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 00:53:06.300171       1 utils.go:199] ID: 10 GRPC request: {}
I0402 00:53:06.300189       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0402 00:53:06.300267       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 00:53:06.300876       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 00:53:06.300977       1 utils.go:199] ID: 11 GRPC request: {}
I0402 00:53:06.301162       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0402 00:53:06.301286       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 00:53:06.301613       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 00:53:06.301652       1 utils.go:199] ID: 12 GRPC request: {}
I0402 00:53:06.301739       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0402 00:53:06.301810       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 00:53:08.158372       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 00:53:08.158409       1 utils.go:199] ID: 13 GRPC request: {}
I0402 00:53:08.158417       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0402 00:53:08.158461       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 00:53:08.162096       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0402 00:53:08.162114       1 utils.go:199] ID: 14 GRPC request: {}
I0402 00:53:08.162134       1 utils.go:205] ID: 14 GRPC response: {}
I0402 00:53:08.162650       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 00:53:08.162704       1 utils.go:199] ID: 15 GRPC request: {}
I0402 00:53:08.162721       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0402 00:53:08.162857       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 00:53:09.801089       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0402 00:53:09.801151       1 utils.go:199] ID: 16 GRPC request: {}
I0402 00:53:09.801184       1 utils.go:205] ID: 16 GRPC response: {}
I0402 00:53:09.804004       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 00:53:09.804038       1 utils.go:199] ID: 17 GRPC request: {}
I0402 00:53:09.804049       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0402 00:53:09.804081       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0402 00:53:09.806601       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0402 00:53:09.806628       1 utils.go:199] ID: 18 GRPC request: {}
I0402 00:53:09.806648       1 utils.go:205] ID: 18 GRPC response: {}
I0402 00:53:09.807008       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 00:53:09.807046       1 utils.go:199] ID: 19 GRPC request: {}
I0402 00:53:09.807138       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 00:53:09.807780       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 00:53:09.807816       1 utils.go:199] ID: 20 GRPC request: {}
I0402 00:53:09.807831       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0402 00:53:09.807963       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 00:54:10.359028       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0402 00:54:10.359112       1 utils.go:199] ID: 21 GRPC request: {}
I0402 00:54:10.359336       1 utils.go:205] ID: 21 GRPC response: {}
I0402 00:55:10.322174       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0402 00:55:10.322287       1 utils.go:199] ID: 22 GRPC request: {}
I0402 00:55:10.322345       1 utils.go:205] ID: 22 GRPC response: {}
I0402 00:56:10.106830       1 utils.go:198] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 GRPC call: /csi.v1.Controller/CreateVolume
I0402 00:56:10.107398       1 utils.go:199] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36","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 00:56:10.108151       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0402 00:56:10.127253       1 omap.go:80] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0402 00:56:10.141609       1 omap.go:159] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36:6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc])
I0402 00:56:10.148248       1 omap.go:159] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 set omap keys (pool="kube", namespace="", name="csi.volume.6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc"): map[csi.imagename:csi-vol-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc csi.volname:pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 csi.volume.owner:default])
I0402 00:56:10.148290       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc) and image name (csi-vol-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc) for request name (pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36)
I0402 00:56:10.148387       1 rbd_util.go:423] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 rbd: create kube/csi-vol-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc size 5120M (features: [layering]) using mon 199.204.45.22
I0402 00:56:10.148422       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 setting image options on kube/csi-vol-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc
I0402 00:56:10.318941       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0402 00:56:10.318988       1 utils.go:199] ID: 24 GRPC request: {}
I0402 00:56:10.319029       1 utils.go:205] ID: 24 GRPC response: {}
I0402 00:56:11.611539       1 controllerserver.go:763] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 created image kube/csi-vol-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc backed for request name pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36
I0402 00:56:11.644223       1 omap.go:159] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 set omap keys (pool="kube", namespace="", name="csi.volume.6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc"): map[csi.imageid:37d9746306cf])
I0402 00:56:11.736131       1 utils.go:205] ID: 23 Req-ID: pvc-5ca7d50a-e4bf-4184-a7a9-f12ea5d22b36 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6053ad20-b9ca-4f2a-b863-cd4d5a7a36cc"}}