I0510 00:51:18.394601       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0510 00:51:18.394713       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0510 00:51:18.394725       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0510 00:51:18.394730       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0510 00:51:18.394733       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0510 00:51:18.394737       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0510 00:51:18.394740       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0510 00:51:18.394744       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0510 00:51:18.394747       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0510 00:51:18.394750       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0510 00:51:18.394988       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0510 00:51:18.395029       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0510 00:51:20.630170       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:20.632257       1 utils.go:199] ID: 1 GRPC request: {}
I0510 00:51:20.632448       1 utils.go:205] ID: 1 GRPC response: {}
I0510 00:51:20.635429       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:51:20.635729       1 utils.go:199] ID: 2 GRPC request: {}
I0510 00:51:20.635838       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0510 00:51:20.635983       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:51:20.637625       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:20.637657       1 utils.go:199] ID: 3 GRPC request: {}
I0510 00:51:20.637836       1 utils.go:205] ID: 3 GRPC response: {}
I0510 00:51:20.638548       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0510 00:51:20.638714       1 utils.go:199] ID: 4 GRPC request: {}
I0510 00:51:20.639024       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0510 00:51:20.640002       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:51:20.640217       1 utils.go:199] ID: 5 GRPC request: {}
I0510 00:51:20.640237       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0510 00:51:20.640534       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}}}]}
I0510 00:51:22.226843       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:22.226929       1 utils.go:199] ID: 6 GRPC request: {}
I0510 00:51:22.226960       1 utils.go:205] ID: 6 GRPC response: {}
I0510 00:51:22.230341       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:51:22.230428       1 utils.go:199] ID: 7 GRPC request: {}
I0510 00:51:22.230446       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0510 00:51:22.230509       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:51:22.232823       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:22.232900       1 utils.go:199] ID: 8 GRPC request: {}
I0510 00:51:22.232931       1 utils.go:205] ID: 8 GRPC response: {}
I0510 00:51:22.233530       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0510 00:51:22.233784       1 utils.go:199] ID: 9 GRPC request: {}
I0510 00:51:22.233953       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0510 00:51:22.234774       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:51:22.234812       1 utils.go:199] ID: 10 GRPC request: {}
I0510 00:51:22.234830       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0510 00:51:22.234921       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}}}]}
I0510 00:51:22.235622       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:51:22.235688       1 utils.go:199] ID: 11 GRPC request: {}
I0510 00:51:22.235725       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0510 00:51:22.235854       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}}}]}
I0510 00:51:22.236269       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:51:22.236296       1 utils.go:199] ID: 12 GRPC request: {}
I0510 00:51:22.236305       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0510 00:51:22.236477       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}}}]}
I0510 00:51:24.087568       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:51:24.087602       1 utils.go:199] ID: 13 GRPC request: {}
I0510 00:51:24.087610       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0510 00:51:24.087639       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:51:24.091325       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:24.091349       1 utils.go:199] ID: 14 GRPC request: {}
I0510 00:51:24.091375       1 utils.go:205] ID: 14 GRPC response: {}
I0510 00:51:24.092287       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:51:24.092307       1 utils.go:199] ID: 15 GRPC request: {}
I0510 00:51:24.092315       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0510 00:51:24.092420       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}}}]}
I0510 00:51:26.313924       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:26.313995       1 utils.go:199] ID: 16 GRPC request: {}
I0510 00:51:26.314329       1 utils.go:205] ID: 16 GRPC response: {}
I0510 00:51:26.315928       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0510 00:51:26.315978       1 utils.go:199] ID: 17 GRPC request: {}
I0510 00:51:26.316006       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0510 00:51:26.316044       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0510 00:51:26.319459       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0510 00:51:26.319525       1 utils.go:199] ID: 18 GRPC request: {}
I0510 00:51:26.319578       1 utils.go:205] ID: 18 GRPC response: {}
I0510 00:51:26.320077       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0510 00:51:26.320111       1 utils.go:199] ID: 19 GRPC request: {}
I0510 00:51:26.320197       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0510 00:51:26.320728       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0510 00:51:26.320852       1 utils.go:199] ID: 20 GRPC request: {}
I0510 00:51:26.320887       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0510 00:51:26.321049       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}}}]}
I0510 00:52:26.469980       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0510 00:52:26.470044       1 utils.go:199] ID: 21 GRPC request: {}
I0510 00:52:26.470074       1 utils.go:205] ID: 21 GRPC response: {}
I0510 00:53:26.475451       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0510 00:53:26.475509       1 utils.go:199] ID: 22 GRPC request: {}
I0510 00:53:26.475576       1 utils.go:205] ID: 22 GRPC response: {}
I0510 00:54:26.525622       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0510 00:54:26.525692       1 utils.go:199] ID: 23 GRPC request: {}
I0510 00:54:26.525718       1 utils.go:205] ID: 23 GRPC response: {}
I0510 00:54:32.164495       1 utils.go:198] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b GRPC call: /csi.v1.Controller/CreateVolume
I0510 00:54:32.164906       1 utils.go:199] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c07be783-f17d-465d-b06b-b426ad06559b","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c07be783-f17d-465d-b06b-b426ad06559b","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}}]}
I0510 00:54:32.165215       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b setting disableInUseChecks: false image features: [layering] mounter: rbd
E0510 00:54:32.182373       1 omap.go:80] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0510 00:54:32.198237       1 omap.go:159] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c07be783-f17d-465d-b06b-b426ad06559b:9f4aa772-8c39-4181-8aab-8f7c0e0171e1])
I0510 00:54:32.204745       1 omap.go:159] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b set omap keys (pool="kube", namespace="", name="csi.volume.9f4aa772-8c39-4181-8aab-8f7c0e0171e1"): map[csi.imagename:csi-vol-9f4aa772-8c39-4181-8aab-8f7c0e0171e1 csi.volname:pvc-c07be783-f17d-465d-b06b-b426ad06559b csi.volume.owner:default])
I0510 00:54:32.204770       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9f4aa772-8c39-4181-8aab-8f7c0e0171e1) and image name (csi-vol-9f4aa772-8c39-4181-8aab-8f7c0e0171e1) for request name (pvc-c07be783-f17d-465d-b06b-b426ad06559b)
I0510 00:54:32.204839       1 rbd_util.go:423] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b rbd: create kube/csi-vol-9f4aa772-8c39-4181-8aab-8f7c0e0171e1 size 5120M (features: [layering]) using mon 199.204.45.132
I0510 00:54:32.204926       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b setting image options on kube/csi-vol-9f4aa772-8c39-4181-8aab-8f7c0e0171e1
I0510 00:54:33.731984       1 controllerserver.go:763] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b created image kube/csi-vol-9f4aa772-8c39-4181-8aab-8f7c0e0171e1 backed for request name pvc-c07be783-f17d-465d-b06b-b426ad06559b
I0510 00:54:33.767522       1 omap.go:159] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b set omap keys (pool="kube", namespace="", name="csi.volume.9f4aa772-8c39-4181-8aab-8f7c0e0171e1"): map[csi.imageid:37d9f2b83548])
I0510 00:54:33.864341       1 utils.go:205] ID: 24 Req-ID: pvc-c07be783-f17d-465d-b06b-b426ad06559b GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-9f4aa772-8c39-4181-8aab-8f7c0e0171e1","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9f4aa772-8c39-4181-8aab-8f7c0e0171e1"}}
I0510 00:55:26.479627       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0510 00:55:26.479683       1 utils.go:199] ID: 25 GRPC request: {}
I0510 00:55:26.479699       1 utils.go:205] ID: 25 GRPC response: {}