I0401 05:12:37.404888       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0401 05:12:37.404979       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0401 05:12:37.404988       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0401 05:12:37.404991       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0401 05:12:37.404993       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0401 05:12:37.404996       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0401 05:12:37.405001       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0401 05:12:37.405003       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0401 05:12:37.405010       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0401 05:12:37.405013       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0401 05:12:37.405466       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0401 05:12:37.405482       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0401 05:12:39.298415       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:39.299710       1 utils.go:199] ID: 1 GRPC request: {}
I0401 05:12:39.299764       1 utils.go:205] ID: 1 GRPC response: {}
I0401 05:12:39.301687       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:12:39.301791       1 utils.go:199] ID: 2 GRPC request: {}
I0401 05:12:39.301810       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0401 05:12:39.301875       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:12:39.304808       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:39.304848       1 utils.go:199] ID: 3 GRPC request: {}
I0401 05:12:39.304869       1 utils.go:205] ID: 3 GRPC response: {}
I0401 05:12:39.305513       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0401 05:12:39.305628       1 utils.go:199] ID: 4 GRPC request: {}
I0401 05:12:39.305889       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0401 05:12:39.306780       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:12:39.306890       1 utils.go:199] ID: 5 GRPC request: {}
I0401 05:12:39.306941       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0401 05:12:39.307147       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}}}]}
I0401 05:12:45.350886       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:45.351076       1 utils.go:199] ID: 6 GRPC request: {}
I0401 05:12:45.351208       1 utils.go:205] ID: 6 GRPC response: {}
I0401 05:12:45.355084       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:12:45.355156       1 utils.go:199] ID: 7 GRPC request: {}
I0401 05:12:45.355164       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0401 05:12:45.355189       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:12:45.357541       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:45.357677       1 utils.go:199] ID: 8 GRPC request: {}
I0401 05:12:45.357830       1 utils.go:205] ID: 8 GRPC response: {}
I0401 05:12:45.358544       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0401 05:12:45.358561       1 utils.go:199] ID: 9 GRPC request: {}
I0401 05:12:45.358628       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0401 05:12:45.359560       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:12:45.359599       1 utils.go:199] ID: 10 GRPC request: {}
I0401 05:12:45.359609       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0401 05:12:45.359697       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}}}]}
I0401 05:12:45.360328       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:12:45.360534       1 utils.go:199] ID: 11 GRPC request: {}
I0401 05:12:45.360718       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0401 05:12:45.360990       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}}}]}
I0401 05:12:45.361558       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:12:45.361575       1 utils.go:199] ID: 12 GRPC request: {}
I0401 05:12:45.361631       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0401 05:12:45.361742       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}}}]}
I0401 05:12:48.040288       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:12:48.040325       1 utils.go:199] ID: 13 GRPC request: {}
I0401 05:12:48.040334       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0401 05:12:48.040357       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:12:48.044530       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:48.044548       1 utils.go:199] ID: 14 GRPC request: {}
I0401 05:12:48.044560       1 utils.go:205] ID: 14 GRPC response: {}
I0401 05:12:48.045186       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:12:48.045202       1 utils.go:199] ID: 15 GRPC request: {}
I0401 05:12:48.045209       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0401 05:12:48.045617       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}}}]}
I0401 05:12:50.558149       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:50.558194       1 utils.go:199] ID: 16 GRPC request: {}
I0401 05:12:50.558213       1 utils.go:205] ID: 16 GRPC response: {}
I0401 05:12:50.559875       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:12:50.559902       1 utils.go:199] ID: 17 GRPC request: {}
I0401 05:12:50.559912       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0401 05:12:50.559946       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:12:50.562935       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:50.562955       1 utils.go:199] ID: 18 GRPC request: {}
I0401 05:12:50.562967       1 utils.go:205] ID: 18 GRPC response: {}
I0401 05:12:50.563268       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0401 05:12:50.563286       1 utils.go:199] ID: 19 GRPC request: {}
I0401 05:12:50.563359       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0401 05:12:50.564069       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:12:50.564086       1 utils.go:199] ID: 20 GRPC request: {}
I0401 05:12:50.564093       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0401 05:12:50.564148       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}}}]}
I0401 05:13:50.913270       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0401 05:13:50.913360       1 utils.go:199] ID: 21 GRPC request: {}
I0401 05:13:50.913403       1 utils.go:205] ID: 21 GRPC response: {}
I0401 05:14:50.902993       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0401 05:14:50.903056       1 utils.go:199] ID: 22 GRPC request: {}
I0401 05:14:50.903076       1 utils.go:205] ID: 22 GRPC response: {}
I0401 05:15:50.902586       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0401 05:15:50.902653       1 utils.go:199] ID: 23 GRPC request: {}
I0401 05:15:50.902675       1 utils.go:205] ID: 23 GRPC response: {}
I0401 05:15:51.604851       1 utils.go:198] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f GRPC call: /csi.v1.Controller/CreateVolume
I0401 05:15:51.605231       1 utils.go:199] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f","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}}]}
I0401 05:15:51.605468       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f setting disableInUseChecks: false image features: [layering] mounter: rbd
E0401 05:15:51.629012       1 omap.go:80] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0401 05:15:51.642579       1 omap.go:159] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f:22ab3713-2b14-4063-a5f3-6df38cea6e94])
I0401 05:15:51.648132       1 omap.go:159] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f set omap keys (pool="kube", namespace="", name="csi.volume.22ab3713-2b14-4063-a5f3-6df38cea6e94"): map[csi.imagename:csi-vol-22ab3713-2b14-4063-a5f3-6df38cea6e94 csi.volname:pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f csi.volume.owner:default])
I0401 05:15:51.648234       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-22ab3713-2b14-4063-a5f3-6df38cea6e94) and image name (csi-vol-22ab3713-2b14-4063-a5f3-6df38cea6e94) for request name (pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f)
I0401 05:15:51.648346       1 rbd_util.go:423] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f rbd: create kube/csi-vol-22ab3713-2b14-4063-a5f3-6df38cea6e94 size 5120M (features: [layering]) using mon 162.253.55.200
I0401 05:15:51.648419       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f setting image options on kube/csi-vol-22ab3713-2b14-4063-a5f3-6df38cea6e94
I0401 05:15:53.300482       1 controllerserver.go:763] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f created image kube/csi-vol-22ab3713-2b14-4063-a5f3-6df38cea6e94 backed for request name pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f
I0401 05:15:53.334136       1 omap.go:159] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f set omap keys (pool="kube", namespace="", name="csi.volume.22ab3713-2b14-4063-a5f3-6df38cea6e94"): map[csi.imageid:37d8f86a7469])
I0401 05:15:53.445427       1 utils.go:205] ID: 24 Req-ID: pvc-9dae1d6f-627f-47e9-8a36-01e2d546b74f GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-22ab3713-2b14-4063-a5f3-6df38cea6e94","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-22ab3713-2b14-4063-a5f3-6df38cea6e94"}}