I0714 17:43:25.671667       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0714 17:43:25.671775       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0714 17:43:25.671791       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0714 17:43:25.671794       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0714 17:43:25.671797       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0714 17:43:25.671800       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0714 17:43:25.671804       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0714 17:43:25.671807       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0714 17:43:25.671810       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0714 17:43:25.671813       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0714 17:43:25.672132       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0714 17:43:25.672151       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0714 17:43:30.397403       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:30.398756       1 utils.go:199] ID: 1 GRPC request: {}
I0714 17:43:30.398835       1 utils.go:205] ID: 1 GRPC response: {}
I0714 17:43:30.403866       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0714 17:43:30.403977       1 utils.go:199] ID: 2 GRPC request: {}
I0714 17:43:30.404002       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0714 17:43:30.404057       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0714 17:43:30.409456       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:30.409491       1 utils.go:199] ID: 3 GRPC request: {}
I0714 17:43:30.409508       1 utils.go:205] ID: 3 GRPC response: {}
I0714 17:43:30.410110       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0714 17:43:30.410202       1 utils.go:199] ID: 4 GRPC request: {}
I0714 17:43:30.410432       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0714 17:43:30.411576       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:43:30.411643       1 utils.go:199] ID: 5 GRPC request: {}
I0714 17:43:30.411657       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0714 17:43:30.411801       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}}}]}
I0714 17:43:32.858896       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:32.858935       1 utils.go:199] ID: 6 GRPC request: {}
I0714 17:43:32.858970       1 utils.go:205] ID: 6 GRPC response: {}
I0714 17:43:32.866997       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0714 17:43:32.867051       1 utils.go:199] ID: 7 GRPC request: {}
I0714 17:43:32.867063       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0714 17:43:32.867105       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0714 17:43:32.868809       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:32.868833       1 utils.go:199] ID: 8 GRPC request: {}
I0714 17:43:32.868848       1 utils.go:205] ID: 8 GRPC response: {}
I0714 17:43:32.869528       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0714 17:43:32.869595       1 utils.go:199] ID: 9 GRPC request: {}
I0714 17:43:32.869803       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0714 17:43:32.870678       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:43:32.870712       1 utils.go:199] ID: 10 GRPC request: {}
I0714 17:43:32.870719       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0714 17:43:32.870838       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}}}]}
I0714 17:43:32.871411       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:43:32.871461       1 utils.go:199] ID: 11 GRPC request: {}
I0714 17:43:32.871477       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0714 17:43:32.871630       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}}}]}
I0714 17:43:32.872004       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:43:32.872021       1 utils.go:199] ID: 12 GRPC request: {}
I0714 17:43:32.872028       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0714 17:43:32.872093       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}}}]}
I0714 17:43:36.455672       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0714 17:43:36.455847       1 utils.go:199] ID: 13 GRPC request: {}
I0714 17:43:36.455937       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0714 17:43:36.456286       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0714 17:43:36.465441       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:36.465479       1 utils.go:199] ID: 14 GRPC request: {}
I0714 17:43:36.465496       1 utils.go:205] ID: 14 GRPC response: {}
I0714 17:43:36.466377       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:43:36.466422       1 utils.go:199] ID: 15 GRPC request: {}
I0714 17:43:36.466438       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0714 17:43:36.466538       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}}}]}
I0714 17:43:39.441195       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:39.441368       1 utils.go:199] ID: 16 GRPC request: {}
I0714 17:43:39.441461       1 utils.go:205] ID: 16 GRPC response: {}
I0714 17:43:39.445038       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0714 17:43:39.445075       1 utils.go:199] ID: 17 GRPC request: {}
I0714 17:43:39.445136       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0714 17:43:39.445948       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0714 17:43:39.448739       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0714 17:43:39.448784       1 utils.go:199] ID: 18 GRPC request: {}
I0714 17:43:39.448813       1 utils.go:205] ID: 18 GRPC response: {}
I0714 17:43:39.449296       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0714 17:43:39.449328       1 utils.go:199] ID: 19 GRPC request: {}
I0714 17:43:39.449530       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0714 17:43:39.450934       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:43:39.450964       1 utils.go:199] ID: 20 GRPC request: {}
I0714 17:43:39.450977       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0714 17:43:39.451054       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}}}]}
I0714 17:44:39.878873       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0714 17:44:39.878919       1 utils.go:199] ID: 21 GRPC request: {}
I0714 17:44:39.878937       1 utils.go:205] ID: 21 GRPC response: {}
I0714 17:45:39.857001       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0714 17:45:39.857069       1 utils.go:199] ID: 22 GRPC request: {}
I0714 17:45:39.857098       1 utils.go:205] ID: 22 GRPC response: {}
I0714 17:46:39.911953       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0714 17:46:39.912023       1 utils.go:199] ID: 23 GRPC request: {}
I0714 17:46:39.912055       1 utils.go:205] ID: 23 GRPC response: {}
I0714 17:47:39.866274       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0714 17:47:39.866358       1 utils.go:199] ID: 24 GRPC request: {}
I0714 17:47:39.866380       1 utils.go:205] ID: 24 GRPC response: {}
I0714 17:47:52.699338       1 utils.go:198] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 GRPC call: /csi.v1.Controller/CreateVolume
I0714 17:47:52.699937       1 utils.go:199] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77","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}}]}
I0714 17:47:52.700379       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0714 17:47:52.719239       1 omap.go:80] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0714 17:47:52.757333       1 omap.go:159] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77:aef8ccfd-aba2-4c7e-8f8e-b823e64869d5])
I0714 17:47:52.768646       1 omap.go:159] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 set omap keys (pool="kube", namespace="", name="csi.volume.aef8ccfd-aba2-4c7e-8f8e-b823e64869d5"): map[csi.imagename:csi-vol-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5 csi.volname:pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 csi.volume.owner:default])
I0714 17:47:52.768687       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5) and image name (csi-vol-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5) for request name (pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77)
I0714 17:47:52.768785       1 rbd_util.go:423] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 rbd: create kube/csi-vol-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5 size 5120M (features: [layering]) using mon 199.19.213.139
I0714 17:47:52.768912       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 setting image options on kube/csi-vol-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5
I0714 17:47:52.863539       1 controllerserver.go:763] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 created image kube/csi-vol-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5 backed for request name pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77
I0714 17:47:52.930622       1 omap.go:159] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 set omap keys (pool="kube", namespace="", name="csi.volume.aef8ccfd-aba2-4c7e-8f8e-b823e64869d5"): map[csi.imageid:37f4f8688e8f])
I0714 17:47:53.135605       1 utils.go:205] ID: 25 Req-ID: pvc-c6c3273f-4df7-490f-ab9f-8e9dfc8b1e77 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-aef8ccfd-aba2-4c7e-8f8e-b823e64869d5"}}
I0714 17:48:39.856069       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe
I0714 17:48:39.856166       1 utils.go:199] ID: 26 GRPC request: {}
I0714 17:48:39.856194       1 utils.go:205] ID: 26 GRPC response: {}