I0226 01:42:53.057222       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0226 01:42:53.057414       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0226 01:42:53.057434       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0226 01:42:53.057439       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0226 01:42:53.057442       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0226 01:42:53.057446       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0226 01:42:53.057450       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0226 01:42:53.057456       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0226 01:42:53.057459       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0226 01:42:53.057462       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0226 01:42:53.057863       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0226 01:42:53.057920       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0226 01:42:55.483514       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0226 01:42:55.485004       1 utils.go:199] ID: 1 GRPC request: {}
I0226 01:42:55.485067       1 utils.go:205] ID: 1 GRPC response: {}
I0226 01:42:55.488044       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 01:42:55.488224       1 utils.go:199] ID: 2 GRPC request: {}
I0226 01:42:55.488267       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0226 01:42:55.488369       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 01:42:55.492780       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0226 01:42:55.492831       1 utils.go:199] ID: 3 GRPC request: {}
I0226 01:42:55.492860       1 utils.go:205] ID: 3 GRPC response: {}
I0226 01:42:55.493254       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0226 01:42:55.493334       1 utils.go:199] ID: 4 GRPC request: {}
I0226 01:42:55.493536       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0226 01:42:55.495345       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 01:42:55.495433       1 utils.go:199] ID: 5 GRPC request: {}
I0226 01:42:55.495450       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0226 01:42:55.495610       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}}}]}
I0226 01:42:57.960195       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0226 01:42:57.960256       1 utils.go:199] ID: 6 GRPC request: {}
I0226 01:42:57.960285       1 utils.go:205] ID: 6 GRPC response: {}
I0226 01:42:57.962221       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 01:42:57.962308       1 utils.go:199] ID: 7 GRPC request: {}
I0226 01:42:57.962322       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0226 01:42:57.962380       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 01:42:57.964675       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0226 01:42:57.964754       1 utils.go:199] ID: 8 GRPC request: {}
I0226 01:42:57.964802       1 utils.go:205] ID: 8 GRPC response: {}
I0226 01:42:57.965473       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0226 01:42:57.965518       1 utils.go:199] ID: 9 GRPC request: {}
I0226 01:42:57.965762       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0226 01:42:57.966484       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 01:42:57.966569       1 utils.go:199] ID: 10 GRPC request: {}
I0226 01:42:57.966588       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0226 01:42:57.966708       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}}}]}
I0226 01:42:57.967512       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 01:42:57.967536       1 utils.go:199] ID: 11 GRPC request: {}
I0226 01:42:57.967545       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0226 01:42:57.967626       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}}}]}
I0226 01:42:57.967888       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 01:42:57.967905       1 utils.go:199] ID: 12 GRPC request: {}
I0226 01:42:57.967911       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0226 01:42:57.967970       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}}}]}
I0226 01:42:59.183194       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 01:42:59.183237       1 utils.go:199] ID: 13 GRPC request: {}
I0226 01:42:59.183245       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0226 01:42:59.183272       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 01:42:59.185998       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0226 01:42:59.186022       1 utils.go:199] ID: 14 GRPC request: {}
I0226 01:42:59.186037       1 utils.go:205] ID: 14 GRPC response: {}
I0226 01:42:59.186965       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 01:42:59.186988       1 utils.go:199] ID: 15 GRPC request: {}
I0226 01:42:59.187001       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0226 01:42:59.187084       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}}}]}
I0226 01:43:00.385604       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0226 01:43:00.385655       1 utils.go:199] ID: 16 GRPC request: {}
I0226 01:43:00.385677       1 utils.go:205] ID: 16 GRPC response: {}
I0226 01:43:00.390130       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 01:43:00.390153       1 utils.go:199] ID: 17 GRPC request: {}
I0226 01:43:00.390160       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0226 01:43:00.390184       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 01:43:00.391760       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0226 01:43:00.391790       1 utils.go:199] ID: 18 GRPC request: {}
I0226 01:43:00.391806       1 utils.go:205] ID: 18 GRPC response: {}
I0226 01:43:00.392293       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0226 01:43:00.392334       1 utils.go:199] ID: 19 GRPC request: {}
I0226 01:43:00.392457       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0226 01:43:00.393627       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 01:43:00.393679       1 utils.go:199] ID: 20 GRPC request: {}
I0226 01:43:00.393698       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0226 01:43:00.393787       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}}}]}
I0226 01:44:00.612124       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0226 01:44:00.612169       1 utils.go:199] ID: 21 GRPC request: {}
I0226 01:44:00.612187       1 utils.go:205] ID: 21 GRPC response: {}
I0226 01:45:00.622621       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0226 01:45:00.622710       1 utils.go:199] ID: 22 GRPC request: {}
I0226 01:45:00.622772       1 utils.go:205] ID: 22 GRPC response: {}
I0226 01:46:00.612373       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0226 01:46:00.612436       1 utils.go:199] ID: 23 GRPC request: {}
I0226 01:46:00.612460       1 utils.go:205] ID: 23 GRPC response: {}
I0226 01:46:03.950124       1 utils.go:198] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 GRPC call: /csi.v1.Controller/CreateVolume
I0226 01:46:03.950682       1 utils.go:199] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-dc1cbb3a-4101-4224-9509-07270116eb85","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-dc1cbb3a-4101-4224-9509-07270116eb85","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}}]}
I0226 01:46:03.950950       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0226 01:46:03.972204       1 omap.go:80] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0226 01:46:03.989466       1 omap.go:159] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-dc1cbb3a-4101-4224-9509-07270116eb85:d419373a-2332-496b-8d6c-5da0970c4102])
I0226 01:46:03.995233       1 omap.go:159] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 set omap keys (pool="kube", namespace="", name="csi.volume.d419373a-2332-496b-8d6c-5da0970c4102"): map[csi.imagename:csi-vol-d419373a-2332-496b-8d6c-5da0970c4102 csi.volname:pvc-dc1cbb3a-4101-4224-9509-07270116eb85 csi.volume.owner:default])
I0226 01:46:03.995296       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d419373a-2332-496b-8d6c-5da0970c4102) and image name (csi-vol-d419373a-2332-496b-8d6c-5da0970c4102) for request name (pvc-dc1cbb3a-4101-4224-9509-07270116eb85)
I0226 01:46:03.995373       1 rbd_util.go:423] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 rbd: create kube/csi-vol-d419373a-2332-496b-8d6c-5da0970c4102 size 5120M (features: [layering]) using mon 199.204.45.216
I0226 01:46:03.995444       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 setting image options on kube/csi-vol-d419373a-2332-496b-8d6c-5da0970c4102
I0226 01:46:05.433623       1 controllerserver.go:763] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 created image kube/csi-vol-d419373a-2332-496b-8d6c-5da0970c4102 backed for request name pvc-dc1cbb3a-4101-4224-9509-07270116eb85
I0226 01:46:05.477022       1 omap.go:159] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 set omap keys (pool="kube", namespace="", name="csi.volume.d419373a-2332-496b-8d6c-5da0970c4102"): map[csi.imageid:37d96d3e45a4])
I0226 01:46:05.573598       1 utils.go:205] ID: 24 Req-ID: pvc-dc1cbb3a-4101-4224-9509-07270116eb85 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-d419373a-2332-496b-8d6c-5da0970c4102","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d419373a-2332-496b-8d6c-5da0970c4102"}}