I0420 08:12:54.889117       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0420 08:12:54.889222       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0420 08:12:54.889235       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0420 08:12:54.889238       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0420 08:12:54.889241       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0420 08:12:54.889244       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0420 08:12:54.889248       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0420 08:12:54.889251       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0420 08:12:54.889254       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0420 08:12:54.889259       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0420 08:12:54.889593       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0420 08:12:54.889639       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0420 08:12:56.872165       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0420 08:12:56.874801       1 utils.go:199] ID: 1 GRPC request: {}
I0420 08:12:56.875040       1 utils.go:205] ID: 1 GRPC response: {}
I0420 08:12:56.877800       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0420 08:12:56.878047       1 utils.go:199] ID: 2 GRPC request: {}
I0420 08:12:56.878083       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0420 08:12:56.878409       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0420 08:12:56.881174       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0420 08:12:56.881194       1 utils.go:199] ID: 3 GRPC request: {}
I0420 08:12:56.881205       1 utils.go:205] ID: 3 GRPC response: {}
I0420 08:12:56.882535       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0420 08:12:56.882705       1 utils.go:199] ID: 4 GRPC request: {}
I0420 08:12:56.882921       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0420 08:12:56.883971       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 08:12:56.884079       1 utils.go:199] ID: 5 GRPC request: {}
I0420 08:12:56.884091       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0420 08:12:56.884232       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}}}]}
I0420 08:12:59.232753       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0420 08:12:59.232780       1 utils.go:199] ID: 6 GRPC request: {}
I0420 08:12:59.232791       1 utils.go:205] ID: 6 GRPC response: {}
I0420 08:12:59.236769       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0420 08:12:59.236867       1 utils.go:199] ID: 7 GRPC request: {}
I0420 08:12:59.236886       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0420 08:12:59.236961       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0420 08:12:59.239212       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0420 08:12:59.239228       1 utils.go:199] ID: 8 GRPC request: {}
I0420 08:12:59.239274       1 utils.go:205] ID: 8 GRPC response: {}
I0420 08:12:59.239709       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0420 08:12:59.239727       1 utils.go:199] ID: 9 GRPC request: {}
I0420 08:12:59.239890       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0420 08:12:59.241524       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 08:12:59.241542       1 utils.go:199] ID: 10 GRPC request: {}
I0420 08:12:59.241550       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0420 08:12:59.241698       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}}}]}
I0420 08:12:59.242296       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 08:12:59.242331       1 utils.go:199] ID: 11 GRPC request: {}
I0420 08:12:59.242343       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0420 08:12:59.242434       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}}}]}
I0420 08:12:59.242784       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 08:12:59.242801       1 utils.go:199] ID: 12 GRPC request: {}
I0420 08:12:59.242806       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0420 08:12:59.242861       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}}}]}
I0420 08:13:00.948523       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0420 08:13:00.948565       1 utils.go:199] ID: 13 GRPC request: {}
I0420 08:13:00.948585       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0420 08:13:00.948610       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0420 08:13:00.952597       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0420 08:13:00.952644       1 utils.go:199] ID: 14 GRPC request: {}
I0420 08:13:00.952669       1 utils.go:205] ID: 14 GRPC response: {}
I0420 08:13:00.953147       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 08:13:00.953187       1 utils.go:199] ID: 15 GRPC request: {}
I0420 08:13:00.953196       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0420 08:13:00.953290       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}}}]}
I0420 08:13:02.159662       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0420 08:13:02.159702       1 utils.go:199] ID: 16 GRPC request: {}
I0420 08:13:02.159722       1 utils.go:205] ID: 16 GRPC response: {}
I0420 08:13:02.163104       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0420 08:13:02.163140       1 utils.go:199] ID: 17 GRPC request: {}
I0420 08:13:02.163148       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0420 08:13:02.163173       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0420 08:13:02.164608       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0420 08:13:02.164630       1 utils.go:199] ID: 18 GRPC request: {}
I0420 08:13:02.164662       1 utils.go:205] ID: 18 GRPC response: {}
I0420 08:13:02.165084       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0420 08:13:02.165103       1 utils.go:199] ID: 19 GRPC request: {}
I0420 08:13:02.165278       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0420 08:13:02.165866       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 08:13:02.165887       1 utils.go:199] ID: 20 GRPC request: {}
I0420 08:13:02.165938       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0420 08:13:02.166023       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}}}]}
I0420 08:14:02.361703       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0420 08:14:02.361744       1 utils.go:199] ID: 21 GRPC request: {}
I0420 08:14:02.361762       1 utils.go:205] ID: 21 GRPC response: {}
I0420 08:15:02.362026       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0420 08:15:02.362073       1 utils.go:199] ID: 22 GRPC request: {}
I0420 08:15:02.362094       1 utils.go:205] ID: 22 GRPC response: {}
I0420 08:16:02.373403       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0420 08:16:02.373465       1 utils.go:199] ID: 23 GRPC request: {}
I0420 08:16:02.373481       1 utils.go:205] ID: 23 GRPC response: {}
I0420 08:16:07.715070       1 utils.go:198] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a GRPC call: /csi.v1.Controller/CreateVolume
I0420 08:16:07.715517       1 utils.go:199] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-92ccd437-ae36-4a72-847e-16653db3589a","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-92ccd437-ae36-4a72-847e-16653db3589a","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}}]}
I0420 08:16:07.715849       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a setting disableInUseChecks: false image features: [layering] mounter: rbd
E0420 08:16:07.734508       1 omap.go:80] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0420 08:16:07.749732       1 omap.go:159] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-92ccd437-ae36-4a72-847e-16653db3589a:1bc052ac-f2fd-4490-99b9-298e8de27883])
I0420 08:16:07.756957       1 omap.go:159] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a set omap keys (pool="kube", namespace="", name="csi.volume.1bc052ac-f2fd-4490-99b9-298e8de27883"): map[csi.imagename:csi-vol-1bc052ac-f2fd-4490-99b9-298e8de27883 csi.volname:pvc-92ccd437-ae36-4a72-847e-16653db3589a csi.volume.owner:default])
I0420 08:16:07.756995       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1bc052ac-f2fd-4490-99b9-298e8de27883) and image name (csi-vol-1bc052ac-f2fd-4490-99b9-298e8de27883) for request name (pvc-92ccd437-ae36-4a72-847e-16653db3589a)
I0420 08:16:07.757070       1 rbd_util.go:423] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a rbd: create kube/csi-vol-1bc052ac-f2fd-4490-99b9-298e8de27883 size 5120M (features: [layering]) using mon 199.19.213.174
I0420 08:16:07.757123       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a setting image options on kube/csi-vol-1bc052ac-f2fd-4490-99b9-298e8de27883
I0420 08:16:09.192115       1 controllerserver.go:763] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a created image kube/csi-vol-1bc052ac-f2fd-4490-99b9-298e8de27883 backed for request name pvc-92ccd437-ae36-4a72-847e-16653db3589a
I0420 08:16:09.228173       1 omap.go:159] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a set omap keys (pool="kube", namespace="", name="csi.volume.1bc052ac-f2fd-4490-99b9-298e8de27883"): map[csi.imageid:37d9df8985e9])
I0420 08:16:09.314141       1 utils.go:205] ID: 24 Req-ID: pvc-92ccd437-ae36-4a72-847e-16653db3589a GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-1bc052ac-f2fd-4490-99b9-298e8de27883","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-1bc052ac-f2fd-4490-99b9-298e8de27883"}}
I0420 08:17:02.361246       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0420 08:17:02.361300       1 utils.go:199] ID: 25 GRPC request: {}
I0420 08:17:02.361570       1 utils.go:205] ID: 25 GRPC response: {}