I0505 09:49:31.407277       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0505 09:49:31.407489       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 09:49:31.407513       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 09:49:31.407523       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 09:49:31.407531       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0505 09:49:31.407539       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0505 09:49:31.407548       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 09:49:31.407562       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 09:49:31.407568       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 09:49:31.407575       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 09:49:31.407915       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 09:49:31.407932       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 09:49:34.248124       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:34.249680       1 utils.go:199] ID: 1 GRPC request: {}
I0505 09:49:34.249750       1 utils.go:205] ID: 1 GRPC response: {}
I0505 09:49:34.252092       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 09:49:34.252215       1 utils.go:199] ID: 2 GRPC request: {}
I0505 09:49:34.252239       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0505 09:49:34.252325       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 09:49:34.253751       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:34.253791       1 utils.go:199] ID: 3 GRPC request: {}
I0505 09:49:34.253818       1 utils.go:205] ID: 3 GRPC response: {}
I0505 09:49:34.254403       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 09:49:34.254645       1 utils.go:199] ID: 4 GRPC request: {}
I0505 09:49:34.255034       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 09:49:34.258047       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 09:49:34.258131       1 utils.go:199] ID: 5 GRPC request: {}
I0505 09:49:34.258179       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0505 09:49:34.258349       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}}}]}
I0505 09:49:37.408929       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:37.408967       1 utils.go:199] ID: 6 GRPC request: {}
I0505 09:49:37.408983       1 utils.go:205] ID: 6 GRPC response: {}
I0505 09:49:37.412142       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 09:49:37.412190       1 utils.go:199] ID: 7 GRPC request: {}
I0505 09:49:37.412202       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0505 09:49:37.412238       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 09:49:37.415684       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:37.415708       1 utils.go:199] ID: 8 GRPC request: {}
I0505 09:49:37.415751       1 utils.go:205] ID: 8 GRPC response: {}
I0505 09:49:37.416318       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 09:49:37.416452       1 utils.go:199] ID: 9 GRPC request: {}
I0505 09:49:37.416673       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 09:49:37.417502       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 09:49:37.417541       1 utils.go:199] ID: 10 GRPC request: {}
I0505 09:49:37.417550       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0505 09:49:37.417691       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}}}]}
I0505 09:49:37.418292       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 09:49:37.418369       1 utils.go:199] ID: 11 GRPC request: {}
I0505 09:49:37.418406       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0505 09:49:37.418557       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}}}]}
I0505 09:49:37.419080       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 09:49:37.419152       1 utils.go:199] ID: 12 GRPC request: {}
I0505 09:49:37.419203       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0505 09:49:37.419355       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}}}]}
I0505 09:49:38.633953       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 09:49:38.634007       1 utils.go:199] ID: 13 GRPC request: {}
I0505 09:49:38.634023       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0505 09:49:38.634062       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 09:49:38.639339       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:38.639372       1 utils.go:199] ID: 14 GRPC request: {}
I0505 09:49:38.639396       1 utils.go:205] ID: 14 GRPC response: {}
I0505 09:49:38.640133       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 09:49:38.640223       1 utils.go:199] ID: 15 GRPC request: {}
I0505 09:49:38.640242       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0505 09:49:38.640499       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}}}]}
I0505 09:49:40.606762       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:40.606797       1 utils.go:199] ID: 16 GRPC request: {}
I0505 09:49:40.606812       1 utils.go:205] ID: 16 GRPC response: {}
I0505 09:49:40.615633       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 09:49:40.615670       1 utils.go:199] ID: 17 GRPC request: {}
I0505 09:49:40.615678       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0505 09:49:40.615705       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 09:49:40.617554       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0505 09:49:40.617621       1 utils.go:199] ID: 18 GRPC request: {}
I0505 09:49:40.617659       1 utils.go:205] ID: 18 GRPC response: {}
I0505 09:49:40.618072       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 09:49:40.618107       1 utils.go:199] ID: 19 GRPC request: {}
I0505 09:49:40.618230       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 09:49:40.619159       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 09:49:40.619205       1 utils.go:199] ID: 20 GRPC request: {}
I0505 09:49:40.619227       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0505 09:49:40.619352       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}}}]}
I0505 09:50:40.789930       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0505 09:50:40.789975       1 utils.go:199] ID: 21 GRPC request: {}
I0505 09:50:40.789996       1 utils.go:205] ID: 21 GRPC response: {}
I0505 09:51:40.797443       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 09:51:40.797501       1 utils.go:199] ID: 22 GRPC request: {}
I0505 09:51:40.797523       1 utils.go:205] ID: 22 GRPC response: {}
I0505 09:52:40.829372       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0505 09:52:40.829417       1 utils.go:199] ID: 23 GRPC request: {}
I0505 09:52:40.829434       1 utils.go:205] ID: 23 GRPC response: {}
I0505 09:52:44.625050       1 utils.go:198] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 GRPC call: /csi.v1.Controller/CreateVolume
I0505 09:52:44.625487       1 utils.go:199] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22","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}}]}
I0505 09:52:44.625768       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0505 09:52:44.645636       1 omap.go:80] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 09:52:44.660456       1 omap.go:159] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22:ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f])
I0505 09:52:44.668147       1 omap.go:159] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 set omap keys (pool="kube", namespace="", name="csi.volume.ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f"): map[csi.imagename:csi-vol-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f csi.volname:pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 csi.volume.owner:default])
I0505 09:52:44.668183       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f) and image name (csi-vol-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f) for request name (pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22)
I0505 09:52:44.668255       1 rbd_util.go:423] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 rbd: create kube/csi-vol-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f size 5120M (features: [layering]) using mon 199.19.213.91
I0505 09:52:44.668311       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 setting image options on kube/csi-vol-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f
I0505 09:52:46.337998       1 controllerserver.go:763] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 created image kube/csi-vol-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f backed for request name pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22
I0505 09:52:46.375289       1 omap.go:159] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 set omap keys (pool="kube", namespace="", name="csi.volume.ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f"): map[csi.imageid:37d9a2add7c6])
I0505 09:52:46.470023       1 utils.go:205] ID: 24 Req-ID: pvc-d413a0c4-b355-47a4-be99-4f6dfa9d6d22 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ea2de0b2-e01a-46cd-ace4-2ec1d7c3420f"}}