I0817 10:20:39.328700       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0817 10:20:39.328846       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0817 10:20:39.328863       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0817 10:20:39.328866       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0817 10:20:39.328871       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0817 10:20:39.328874       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0817 10:20:39.328878       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0817 10:20:39.328882       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0817 10:20:39.328885       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0817 10:20:39.328888       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0817 10:20:39.329147       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0817 10:20:39.329291       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0817 10:20:42.665075       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:42.666107       1 utils.go:199] ID: 1 GRPC request: {}
I0817 10:20:42.666144       1 utils.go:205] ID: 1 GRPC response: {}
I0817 10:20:42.667648       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0817 10:20:42.667738       1 utils.go:199] ID: 2 GRPC request: {}
I0817 10:20:42.667756       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0817 10:20:42.667815       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0817 10:20:42.670159       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:42.670208       1 utils.go:199] ID: 3 GRPC request: {}
I0817 10:20:42.670235       1 utils.go:205] ID: 3 GRPC response: {}
I0817 10:20:42.670586       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0817 10:20:42.670665       1 utils.go:199] ID: 4 GRPC request: {}
I0817 10:20:42.670979       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0817 10:20:42.671775       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0817 10:20:42.671841       1 utils.go:199] ID: 5 GRPC request: {}
I0817 10:20:42.671858       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0817 10:20:42.672011       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}}}]}
I0817 10:20:45.120099       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:45.120131       1 utils.go:199] ID: 6 GRPC request: {}
I0817 10:20:45.120181       1 utils.go:205] ID: 6 GRPC response: {}
I0817 10:20:45.122015       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0817 10:20:45.122034       1 utils.go:199] ID: 7 GRPC request: {}
I0817 10:20:45.122041       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0817 10:20:45.122070       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0817 10:20:45.123628       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:45.123651       1 utils.go:199] ID: 8 GRPC request: {}
I0817 10:20:45.123661       1 utils.go:205] ID: 8 GRPC response: {}
I0817 10:20:45.124231       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0817 10:20:45.124339       1 utils.go:199] ID: 9 GRPC request: {}
I0817 10:20:45.124592       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0817 10:20:45.125248       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0817 10:20:45.125265       1 utils.go:199] ID: 10 GRPC request: {}
I0817 10:20:45.125272       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0817 10:20:45.125397       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}}}]}
I0817 10:20:45.126010       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0817 10:20:45.126071       1 utils.go:199] ID: 11 GRPC request: {}
I0817 10:20:45.126090       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0817 10:20:45.126210       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}}}]}
I0817 10:20:45.126552       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0817 10:20:45.126567       1 utils.go:199] ID: 12 GRPC request: {}
I0817 10:20:45.126572       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0817 10:20:45.126645       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}}}]}
I0817 10:20:46.564116       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0817 10:20:46.564165       1 utils.go:199] ID: 13 GRPC request: {}
I0817 10:20:46.564182       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0817 10:20:46.564231       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0817 10:20:46.567193       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:46.567257       1 utils.go:199] ID: 14 GRPC request: {}
I0817 10:20:46.567279       1 utils.go:205] ID: 14 GRPC response: {}
I0817 10:20:46.567796       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0817 10:20:46.567817       1 utils.go:199] ID: 15 GRPC request: {}
I0817 10:20:46.567827       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0817 10:20:46.567933       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}}}]}
I0817 10:20:48.000914       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:48.000947       1 utils.go:199] ID: 16 GRPC request: {}
I0817 10:20:48.000966       1 utils.go:205] ID: 16 GRPC response: {}
I0817 10:20:48.007843       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0817 10:20:48.007881       1 utils.go:199] ID: 17 GRPC request: {}
I0817 10:20:48.007889       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0817 10:20:48.007913       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0817 10:20:48.010684       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0817 10:20:48.010722       1 utils.go:199] ID: 18 GRPC request: {}
I0817 10:20:48.010742       1 utils.go:205] ID: 18 GRPC response: {}
I0817 10:20:48.011226       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0817 10:20:48.011249       1 utils.go:199] ID: 19 GRPC request: {}
I0817 10:20:48.014469       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0817 10:20:48.015384       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0817 10:20:48.015447       1 utils.go:199] ID: 20 GRPC request: {}
I0817 10:20:48.015460       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0817 10:20:48.015570       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}}}]}
I0817 10:21:48.218181       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0817 10:21:48.218316       1 utils.go:199] ID: 21 GRPC request: {}
I0817 10:21:48.218404       1 utils.go:205] ID: 21 GRPC response: {}
I0817 10:22:48.168112       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0817 10:22:48.168279       1 utils.go:199] ID: 22 GRPC request: {}
I0817 10:22:48.168367       1 utils.go:205] ID: 22 GRPC response: {}
I0817 10:23:32.217652       1 utils.go:198] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 GRPC call: /csi.v1.Controller/CreateVolume
I0817 10:23:32.218418       1 utils.go:199] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-142959bd-4a30-495b-9d4c-954b942e6f21","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-142959bd-4a30-495b-9d4c-954b942e6f21","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}}]}
I0817 10:23:32.218813       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0817 10:23:32.235952       1 omap.go:80] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0817 10:23:32.243886       1 omap.go:159] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-142959bd-4a30-495b-9d4c-954b942e6f21:08d67924-dfe3-457d-afd4-c1d0ba4bea56])
I0817 10:23:32.248204       1 omap.go:159] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 set omap keys (pool="kube", namespace="", name="csi.volume.08d67924-dfe3-457d-afd4-c1d0ba4bea56"): map[csi.imagename:csi-vol-08d67924-dfe3-457d-afd4-c1d0ba4bea56 csi.volname:pvc-142959bd-4a30-495b-9d4c-954b942e6f21 csi.volume.owner:default])
I0817 10:23:32.248241       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-08d67924-dfe3-457d-afd4-c1d0ba4bea56) and image name (csi-vol-08d67924-dfe3-457d-afd4-c1d0ba4bea56) for request name (pvc-142959bd-4a30-495b-9d4c-954b942e6f21)
I0817 10:23:32.248463       1 rbd_util.go:423] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 rbd: create kube/csi-vol-08d67924-dfe3-457d-afd4-c1d0ba4bea56 size 5120M (features: [layering]) using mon 199.19.213.82
I0817 10:23:32.248587       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 setting image options on kube/csi-vol-08d67924-dfe3-457d-afd4-c1d0ba4bea56
I0817 10:23:32.268109       1 controllerserver.go:763] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 created image kube/csi-vol-08d67924-dfe3-457d-afd4-c1d0ba4bea56 backed for request name pvc-142959bd-4a30-495b-9d4c-954b942e6f21
I0817 10:23:32.285465       1 omap.go:159] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 set omap keys (pool="kube", namespace="", name="csi.volume.08d67924-dfe3-457d-afd4-c1d0ba4bea56"): map[csi.imageid:380419f6576b])
I0817 10:23:32.345796       1 utils.go:205] ID: 23 Req-ID: pvc-142959bd-4a30-495b-9d4c-954b942e6f21 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-08d67924-dfe3-457d-afd4-c1d0ba4bea56","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-08d67924-dfe3-457d-afd4-c1d0ba4bea56"}}
I0817 10:23:48.166885       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0817 10:23:48.166960       1 utils.go:199] ID: 24 GRPC request: {}
I0817 10:23:48.166984       1 utils.go:205] ID: 24 GRPC response: {}