I0407 00:14:25.251073       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0407 00:14:25.251177       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0407 00:14:25.251187       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0407 00:14:25.251193       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0407 00:14:25.251196       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0407 00:14:25.251198       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0407 00:14:25.251202       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0407 00:14:25.251204       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0407 00:14:25.251207       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0407 00:14:25.251210       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0407 00:14:25.251598       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0407 00:14:25.251656       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0407 00:14:31.326226       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:31.327471       1 utils.go:199] ID: 1 GRPC request: {}
I0407 00:14:31.327521       1 utils.go:205] ID: 1 GRPC response: {}
I0407 00:14:31.329907       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 00:14:31.330014       1 utils.go:199] ID: 2 GRPC request: {}
I0407 00:14:31.330069       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0407 00:14:31.330147       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 00:14:31.331604       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:31.331675       1 utils.go:199] ID: 3 GRPC request: {}
I0407 00:14:31.331708       1 utils.go:205] ID: 3 GRPC response: {}
I0407 00:14:31.332162       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 00:14:31.332271       1 utils.go:199] ID: 4 GRPC request: {}
I0407 00:14:31.332514       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 00:14:31.334397       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 00:14:31.334545       1 utils.go:199] ID: 5 GRPC request: {}
I0407 00:14:31.334560       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0407 00:14:31.334750       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}}}]}
I0407 00:14:32.620279       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:32.620326       1 utils.go:199] ID: 6 GRPC request: {}
I0407 00:14:32.620350       1 utils.go:205] ID: 6 GRPC response: {}
I0407 00:14:32.623986       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 00:14:32.624017       1 utils.go:199] ID: 7 GRPC request: {}
I0407 00:14:32.624025       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0407 00:14:32.624052       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 00:14:32.626312       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:32.626331       1 utils.go:199] ID: 8 GRPC request: {}
I0407 00:14:32.626342       1 utils.go:205] ID: 8 GRPC response: {}
I0407 00:14:32.626725       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 00:14:32.626744       1 utils.go:199] ID: 9 GRPC request: {}
I0407 00:14:32.626816       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 00:14:32.627320       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 00:14:32.627361       1 utils.go:199] ID: 10 GRPC request: {}
I0407 00:14:32.627368       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0407 00:14:32.627448       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}}}]}
I0407 00:14:32.628132       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 00:14:32.628149       1 utils.go:199] ID: 11 GRPC request: {}
I0407 00:14:32.628155       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0407 00:14:32.628208       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}}}]}
I0407 00:14:32.628509       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 00:14:32.628529       1 utils.go:199] ID: 12 GRPC request: {}
I0407 00:14:32.628537       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0407 00:14:32.628601       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}}}]}
I0407 00:14:33.821867       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 00:14:33.821908       1 utils.go:199] ID: 13 GRPC request: {}
I0407 00:14:33.821916       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0407 00:14:33.821942       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 00:14:33.825525       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:33.825546       1 utils.go:199] ID: 14 GRPC request: {}
I0407 00:14:33.825561       1 utils.go:205] ID: 14 GRPC response: {}
I0407 00:14:33.825994       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 00:14:33.826015       1 utils.go:199] ID: 15 GRPC request: {}
I0407 00:14:33.826092       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0407 00:14:33.826287       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}}}]}
I0407 00:14:35.119808       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:35.119863       1 utils.go:199] ID: 16 GRPC request: {}
I0407 00:14:35.120053       1 utils.go:205] ID: 16 GRPC response: {}
I0407 00:14:35.123933       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 00:14:35.124083       1 utils.go:199] ID: 17 GRPC request: {}
I0407 00:14:35.124109       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0407 00:14:35.124297       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 00:14:35.128250       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0407 00:14:35.128287       1 utils.go:199] ID: 18 GRPC request: {}
I0407 00:14:35.128305       1 utils.go:205] ID: 18 GRPC response: {}
I0407 00:14:35.128678       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 00:14:35.128698       1 utils.go:199] ID: 19 GRPC request: {}
I0407 00:14:35.128780       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 00:14:35.129702       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 00:14:35.129750       1 utils.go:199] ID: 20 GRPC request: {}
I0407 00:14:35.129762       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0407 00:14:35.129879       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}}}]}
I0407 00:15:35.318041       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0407 00:15:35.318085       1 utils.go:199] ID: 21 GRPC request: {}
I0407 00:15:35.318100       1 utils.go:205] ID: 21 GRPC response: {}
I0407 00:16:35.283946       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0407 00:16:35.284212       1 utils.go:199] ID: 22 GRPC request: {}
I0407 00:16:35.284314       1 utils.go:205] ID: 22 GRPC response: {}
I0407 00:17:35.282700       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0407 00:17:35.282786       1 utils.go:199] ID: 23 GRPC request: {}
I0407 00:17:35.282811       1 utils.go:205] ID: 23 GRPC response: {}
I0407 00:17:35.439158       1 utils.go:198] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 GRPC call: /csi.v1.Controller/CreateVolume
I0407 00:17:35.439593       1 utils.go:199] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-169e45aa-0ea2-4a79-94c2-563316576360","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-169e45aa-0ea2-4a79-94c2-563316576360","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}}]}
I0407 00:17:35.439898       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0407 00:17:35.457609       1 omap.go:80] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0407 00:17:35.472141       1 omap.go:159] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-169e45aa-0ea2-4a79-94c2-563316576360:8d9b95da-5e4c-499d-974c-1b11fde1bb93])
I0407 00:17:35.479496       1 omap.go:159] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 set omap keys (pool="kube", namespace="", name="csi.volume.8d9b95da-5e4c-499d-974c-1b11fde1bb93"): map[csi.imagename:csi-vol-8d9b95da-5e4c-499d-974c-1b11fde1bb93 csi.volname:pvc-169e45aa-0ea2-4a79-94c2-563316576360 csi.volume.owner:default])
I0407 00:17:35.479535       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8d9b95da-5e4c-499d-974c-1b11fde1bb93) and image name (csi-vol-8d9b95da-5e4c-499d-974c-1b11fde1bb93) for request name (pvc-169e45aa-0ea2-4a79-94c2-563316576360)
I0407 00:17:35.479627       1 rbd_util.go:423] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 rbd: create kube/csi-vol-8d9b95da-5e4c-499d-974c-1b11fde1bb93 size 5120M (features: [layering]) using mon 199.19.213.81
I0407 00:17:35.479678       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 setting image options on kube/csi-vol-8d9b95da-5e4c-499d-974c-1b11fde1bb93
I0407 00:17:36.722012       1 controllerserver.go:763] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 created image kube/csi-vol-8d9b95da-5e4c-499d-974c-1b11fde1bb93 backed for request name pvc-169e45aa-0ea2-4a79-94c2-563316576360
I0407 00:17:36.760879       1 omap.go:159] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 set omap keys (pool="kube", namespace="", name="csi.volume.8d9b95da-5e4c-499d-974c-1b11fde1bb93"): map[csi.imageid:37d9a408abf])
I0407 00:17:36.860366       1 utils.go:205] ID: 24 Req-ID: pvc-169e45aa-0ea2-4a79-94c2-563316576360 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-8d9b95da-5e4c-499d-974c-1b11fde1bb93","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-8d9b95da-5e4c-499d-974c-1b11fde1bb93"}}