I0519 17:58:57.625078       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0519 17:58:57.625204       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0519 17:58:57.625214       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0519 17:58:57.625218       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0519 17:58:57.625226       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0519 17:58:57.625229       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0519 17:58:57.625232       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0519 17:58:57.625235       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0519 17:58:57.625238       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0519 17:58:57.625240       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0519 17:58:57.625613       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0519 17:58:57.625635       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0519 17:59:18.982451       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:18.985026       1 utils.go:199] ID: 1 GRPC request: {}
I0519 17:59:18.985107       1 utils.go:205] ID: 1 GRPC response: {}
I0519 17:59:18.988941       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0519 17:59:18.989078       1 utils.go:199] ID: 2 GRPC request: {}
I0519 17:59:18.989100       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0519 17:59:18.989210       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0519 17:59:18.990867       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:18.990924       1 utils.go:199] ID: 3 GRPC request: {}
I0519 17:59:18.990948       1 utils.go:205] ID: 3 GRPC response: {}
I0519 17:59:18.991530       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0519 17:59:18.991617       1 utils.go:199] ID: 4 GRPC request: {}
I0519 17:59:18.991822       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0519 17:59:18.992628       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 17:59:18.992746       1 utils.go:199] ID: 5 GRPC request: {}
I0519 17:59:18.992772       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0519 17:59:18.992962       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}}}]}
I0519 17:59:20.490386       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:20.490439       1 utils.go:199] ID: 6 GRPC request: {}
I0519 17:59:20.490470       1 utils.go:205] ID: 6 GRPC response: {}
I0519 17:59:20.492818       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0519 17:59:20.492854       1 utils.go:199] ID: 7 GRPC request: {}
I0519 17:59:20.492884       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0519 17:59:20.492915       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0519 17:59:20.495044       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:20.495132       1 utils.go:199] ID: 8 GRPC request: {}
I0519 17:59:20.495200       1 utils.go:205] ID: 8 GRPC response: {}
I0519 17:59:20.496923       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0519 17:59:20.496986       1 utils.go:199] ID: 9 GRPC request: {}
I0519 17:59:20.497166       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0519 17:59:20.498222       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 17:59:20.498256       1 utils.go:199] ID: 10 GRPC request: {}
I0519 17:59:20.498270       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0519 17:59:20.498383       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}}}]}
I0519 17:59:20.499442       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 17:59:20.499482       1 utils.go:199] ID: 11 GRPC request: {}
I0519 17:59:20.499493       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0519 17:59:20.499628       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}}}]}
I0519 17:59:20.500061       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 17:59:20.500100       1 utils.go:199] ID: 12 GRPC request: {}
I0519 17:59:20.500110       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0519 17:59:20.500279       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}}}]}
I0519 17:59:23.167050       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0519 17:59:23.167116       1 utils.go:199] ID: 13 GRPC request: {}
I0519 17:59:23.167136       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0519 17:59:23.167205       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0519 17:59:23.183823       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:23.183869       1 utils.go:199] ID: 14 GRPC request: {}
I0519 17:59:23.183902       1 utils.go:205] ID: 14 GRPC response: {}
I0519 17:59:23.184748       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 17:59:23.184793       1 utils.go:199] ID: 15 GRPC request: {}
I0519 17:59:23.184807       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0519 17:59:23.184918       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}}}]}
I0519 17:59:24.837290       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:24.837425       1 utils.go:199] ID: 16 GRPC request: {}
I0519 17:59:24.837489       1 utils.go:205] ID: 16 GRPC response: {}
I0519 17:59:24.841947       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0519 17:59:24.841970       1 utils.go:199] ID: 17 GRPC request: {}
I0519 17:59:24.841977       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0519 17:59:24.842000       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0519 17:59:24.845237       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0519 17:59:24.845295       1 utils.go:199] ID: 18 GRPC request: {}
I0519 17:59:24.845323       1 utils.go:205] ID: 18 GRPC response: {}
I0519 17:59:24.845913       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0519 17:59:24.846013       1 utils.go:199] ID: 19 GRPC request: {}
I0519 17:59:24.846152       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0519 17:59:24.847181       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 17:59:24.847249       1 utils.go:199] ID: 20 GRPC request: {}
I0519 17:59:24.847264       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0519 17:59:24.847406       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}}}]}
I0519 18:00:25.095291       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0519 18:00:25.095428       1 utils.go:199] ID: 21 GRPC request: {}
I0519 18:00:25.095492       1 utils.go:205] ID: 21 GRPC response: {}
I0519 18:01:25.057318       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0519 18:01:25.057397       1 utils.go:199] ID: 22 GRPC request: {}
I0519 18:01:25.057472       1 utils.go:205] ID: 22 GRPC response: {}
I0519 18:02:25.110470       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0519 18:02:25.110566       1 utils.go:199] ID: 23 GRPC request: {}
I0519 18:02:25.110613       1 utils.go:205] ID: 23 GRPC response: {}
I0519 18:02:25.581486       1 utils.go:198] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf GRPC call: /csi.v1.Controller/CreateVolume
I0519 18:02:25.582000       1 utils.go:199] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-f529b86d-06db-41a6-9185-d718580c6edf","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-f529b86d-06db-41a6-9185-d718580c6edf","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}}]}
I0519 18:02:25.582265       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf setting disableInUseChecks: false image features: [layering] mounter: rbd
E0519 18:02:25.597292       1 omap.go:80] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0519 18:02:25.615001       1 omap.go:159] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-f529b86d-06db-41a6-9185-d718580c6edf:ce08028f-62fb-40f8-a1cc-a66af1441022])
I0519 18:02:25.621812       1 omap.go:159] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf set omap keys (pool="kube", namespace="", name="csi.volume.ce08028f-62fb-40f8-a1cc-a66af1441022"): map[csi.imagename:csi-vol-ce08028f-62fb-40f8-a1cc-a66af1441022 csi.volname:pvc-f529b86d-06db-41a6-9185-d718580c6edf csi.volume.owner:default])
I0519 18:02:25.621857       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ce08028f-62fb-40f8-a1cc-a66af1441022) and image name (csi-vol-ce08028f-62fb-40f8-a1cc-a66af1441022) for request name (pvc-f529b86d-06db-41a6-9185-d718580c6edf)
I0519 18:02:25.621927       1 rbd_util.go:423] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf rbd: create kube/csi-vol-ce08028f-62fb-40f8-a1cc-a66af1441022 size 5120M (features: [layering]) using mon 199.19.213.83
I0519 18:02:25.621991       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf setting image options on kube/csi-vol-ce08028f-62fb-40f8-a1cc-a66af1441022
I0519 18:02:26.834981       1 controllerserver.go:763] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf created image kube/csi-vol-ce08028f-62fb-40f8-a1cc-a66af1441022 backed for request name pvc-f529b86d-06db-41a6-9185-d718580c6edf
I0519 18:02:26.869046       1 omap.go:159] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf set omap keys (pool="kube", namespace="", name="csi.volume.ce08028f-62fb-40f8-a1cc-a66af1441022"): map[csi.imageid:37d72cc9a677])
I0519 18:02:26.965774       1 utils.go:205] ID: 24 Req-ID: pvc-f529b86d-06db-41a6-9185-d718580c6edf GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-ce08028f-62fb-40f8-a1cc-a66af1441022","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ce08028f-62fb-40f8-a1cc-a66af1441022"}}