I0505 22:17:42.311021       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0505 22:17:42.311156       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 22:17:42.311169       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 22:17:42.311175       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 22:17:42.311178       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0505 22:17:42.311190       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0505 22:17:42.311194       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 22:17:42.311197       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 22:17:42.311200       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 22:17:42.311202       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 22:17:42.312338       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 22:17:42.312847       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 22:17:48.411796       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:48.413466       1 utils.go:199] ID: 1 GRPC request: {}
I0505 22:17:48.413601       1 utils.go:205] ID: 1 GRPC response: {}
I0505 22:17:48.415822       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 22:17:48.415984       1 utils.go:199] ID: 2 GRPC request: {}
I0505 22:17:48.416005       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0505 22:17:48.416070       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 22:17:48.417175       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:48.417214       1 utils.go:199] ID: 3 GRPC request: {}
I0505 22:17:48.417236       1 utils.go:205] ID: 3 GRPC response: {}
I0505 22:17:48.417657       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 22:17:48.417749       1 utils.go:199] ID: 4 GRPC request: {}
I0505 22:17:48.417926       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 22:17:48.418850       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 22:17:48.418924       1 utils.go:199] ID: 5 GRPC request: {}
I0505 22:17:48.418938       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0505 22:17:48.419069       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}}}]}
I0505 22:17:51.494901       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:51.494951       1 utils.go:199] ID: 6 GRPC request: {}
I0505 22:17:51.495113       1 utils.go:205] ID: 6 GRPC response: {}
I0505 22:17:51.496882       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 22:17:51.496947       1 utils.go:199] ID: 7 GRPC request: {}
I0505 22:17:51.496965       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0505 22:17:51.496999       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 22:17:51.500872       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:51.500913       1 utils.go:199] ID: 8 GRPC request: {}
I0505 22:17:51.500933       1 utils.go:205] ID: 8 GRPC response: {}
I0505 22:17:51.502807       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 22:17:51.502863       1 utils.go:199] ID: 9 GRPC request: {}
I0505 22:17:51.503072       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 22:17:51.503933       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 22:17:51.503980       1 utils.go:199] ID: 10 GRPC request: {}
I0505 22:17:51.503992       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0505 22:17:51.504131       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}}}]}
I0505 22:17:51.504593       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 22:17:51.504615       1 utils.go:199] ID: 11 GRPC request: {}
I0505 22:17:51.504625       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0505 22:17:51.504765       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}}}]}
I0505 22:17:51.505303       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 22:17:51.505331       1 utils.go:199] ID: 12 GRPC request: {}
I0505 22:17:51.505411       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0505 22:17:51.505563       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}}}]}
I0505 22:17:52.972214       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 22:17:52.972264       1 utils.go:199] ID: 13 GRPC request: {}
I0505 22:17:52.972274       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0505 22:17:52.972312       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 22:17:52.976366       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:52.976397       1 utils.go:199] ID: 14 GRPC request: {}
I0505 22:17:52.976418       1 utils.go:205] ID: 14 GRPC response: {}
I0505 22:17:52.977196       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 22:17:52.977334       1 utils.go:199] ID: 15 GRPC request: {}
I0505 22:17:52.977397       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0505 22:17:52.977596       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}}}]}
I0505 22:17:54.300231       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:54.300314       1 utils.go:199] ID: 16 GRPC request: {}
I0505 22:17:54.300351       1 utils.go:205] ID: 16 GRPC response: {}
I0505 22:17:54.303517       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 22:17:54.303558       1 utils.go:199] ID: 17 GRPC request: {}
I0505 22:17:54.303570       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0505 22:17:54.303600       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 22:17:54.305468       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0505 22:17:54.305601       1 utils.go:199] ID: 18 GRPC request: {}
I0505 22:17:54.305670       1 utils.go:205] ID: 18 GRPC response: {}
I0505 22:17:54.306249       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 22:17:54.306271       1 utils.go:199] ID: 19 GRPC request: {}
I0505 22:17:54.306366       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 22:17:54.307323       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 22:17:54.307341       1 utils.go:199] ID: 20 GRPC request: {}
I0505 22:17:54.307350       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0505 22:17:54.307413       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}}}]}
I0505 22:18:54.518166       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0505 22:18:54.518227       1 utils.go:199] ID: 21 GRPC request: {}
I0505 22:18:54.518268       1 utils.go:205] ID: 21 GRPC response: {}
I0505 22:19:54.481683       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 22:19:54.481746       1 utils.go:199] ID: 22 GRPC request: {}
I0505 22:19:54.481779       1 utils.go:205] ID: 22 GRPC response: {}
I0505 22:20:54.480752       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0505 22:20:54.480808       1 utils.go:199] ID: 23 GRPC request: {}
I0505 22:20:54.480838       1 utils.go:205] ID: 23 GRPC response: {}
I0505 22:20:58.086086       1 utils.go:198] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 GRPC call: /csi.v1.Controller/CreateVolume
I0505 22:20:58.089243       1 utils.go:199] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-e62408f1-f3b0-4ede-9746-6e4993fec847","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-e62408f1-f3b0-4ede-9746-6e4993fec847","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}}]}
I0505 22:20:58.089713       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0505 22:20:58.110542       1 omap.go:80] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 22:20:58.125169       1 omap.go:159] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-e62408f1-f3b0-4ede-9746-6e4993fec847:1f9be4c6-7376-45ca-9801-de676f76b070])
I0505 22:20:58.135197       1 omap.go:159] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 set omap keys (pool="kube", namespace="", name="csi.volume.1f9be4c6-7376-45ca-9801-de676f76b070"): map[csi.imagename:csi-vol-1f9be4c6-7376-45ca-9801-de676f76b070 csi.volname:pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 csi.volume.owner:default])
I0505 22:20:58.135239       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1f9be4c6-7376-45ca-9801-de676f76b070) and image name (csi-vol-1f9be4c6-7376-45ca-9801-de676f76b070) for request name (pvc-e62408f1-f3b0-4ede-9746-6e4993fec847)
I0505 22:20:58.135517       1 rbd_util.go:423] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 rbd: create kube/csi-vol-1f9be4c6-7376-45ca-9801-de676f76b070 size 5120M (features: [layering]) using mon 199.204.45.242
I0505 22:20:58.135636       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 setting image options on kube/csi-vol-1f9be4c6-7376-45ca-9801-de676f76b070
I0505 22:21:00.217849       1 controllerserver.go:763] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 created image kube/csi-vol-1f9be4c6-7376-45ca-9801-de676f76b070 backed for request name pvc-e62408f1-f3b0-4ede-9746-6e4993fec847
I0505 22:21:00.254224       1 omap.go:159] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 set omap keys (pool="kube", namespace="", name="csi.volume.1f9be4c6-7376-45ca-9801-de676f76b070"): map[csi.imageid:37d71a91a8ea])
I0505 22:21:00.351285       1 utils.go:205] ID: 24 Req-ID: pvc-e62408f1-f3b0-4ede-9746-6e4993fec847 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-1f9be4c6-7376-45ca-9801-de676f76b070","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1f9be4c6-7376-45ca-9801-de676f76b070"}}