I0505 13:46:42.906078       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0505 13:46:42.906205       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 13:46:42.906221       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 13:46:42.906224       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 13:46:42.906255       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0505 13:46:42.906259       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0505 13:46:42.906262       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 13:46:42.906265       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 13:46:42.906268       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 13:46:42.906271       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 13:46:42.906955       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 13:46:42.907057       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 13:46:44.921125       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:44.922063       1 utils.go:199] ID: 1 GRPC request: {}
I0505 13:46:44.922104       1 utils.go:205] ID: 1 GRPC response: {}
I0505 13:46:44.924473       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:46:44.924590       1 utils.go:199] ID: 2 GRPC request: {}
I0505 13:46:44.924618       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0505 13:46:44.924691       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:46:44.928337       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:44.928385       1 utils.go:199] ID: 3 GRPC request: {}
I0505 13:46:44.928413       1 utils.go:205] ID: 3 GRPC response: {}
I0505 13:46:44.928872       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 13:46:44.928962       1 utils.go:199] ID: 4 GRPC request: {}
I0505 13:46:44.929153       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 13:46:44.929952       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:46:44.930028       1 utils.go:199] ID: 5 GRPC request: {}
I0505 13:46:44.930047       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0505 13:46:44.930210       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 13:46:47.039689       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:47.039736       1 utils.go:199] ID: 6 GRPC request: {}
I0505 13:46:47.039929       1 utils.go:205] ID: 6 GRPC response: {}
I0505 13:46:47.041678       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:46:47.041710       1 utils.go:199] ID: 7 GRPC request: {}
I0505 13:46:47.041718       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0505 13:46:47.041765       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:46:47.046655       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:47.046683       1 utils.go:199] ID: 8 GRPC request: {}
I0505 13:46:47.046787       1 utils.go:205] ID: 8 GRPC response: {}
I0505 13:46:47.047423       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 13:46:47.047446       1 utils.go:199] ID: 9 GRPC request: {}
I0505 13:46:47.047618       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 13:46:47.052159       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:46:47.052177       1 utils.go:199] ID: 10 GRPC request: {}
I0505 13:46:47.052183       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0505 13:46:47.052232       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 13:46:47.052854       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:46:47.052872       1 utils.go:199] ID: 11 GRPC request: {}
I0505 13:46:47.052876       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0505 13:46:47.052914       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 13:46:47.053159       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:46:47.053171       1 utils.go:199] ID: 12 GRPC request: {}
I0505 13:46:47.053175       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0505 13:46:47.053209       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 13:46:48.486436       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:46:48.486466       1 utils.go:199] ID: 13 GRPC request: {}
I0505 13:46:48.486472       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0505 13:46:48.486520       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:46:48.490006       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:48.490039       1 utils.go:199] ID: 14 GRPC request: {}
I0505 13:46:48.490087       1 utils.go:205] ID: 14 GRPC response: {}
I0505 13:46:48.490551       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:46:48.490568       1 utils.go:199] ID: 15 GRPC request: {}
I0505 13:46:48.490614       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0505 13:46:48.490714       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 13:46:49.709157       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:49.709196       1 utils.go:199] ID: 16 GRPC request: {}
I0505 13:46:49.709266       1 utils.go:205] ID: 16 GRPC response: {}
I0505 13:46:49.711086       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:46:49.711114       1 utils.go:199] ID: 17 GRPC request: {}
I0505 13:46:49.711179       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0505 13:46:49.711234       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:46:49.713960       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0505 13:46:49.714006       1 utils.go:199] ID: 18 GRPC request: {}
I0505 13:46:49.714028       1 utils.go:205] ID: 18 GRPC response: {}
I0505 13:46:49.714570       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 13:46:49.714598       1 utils.go:199] ID: 19 GRPC request: {}
I0505 13:46:49.714720       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 13:46:49.717389       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:46:49.717417       1 utils.go:199] ID: 20 GRPC request: {}
I0505 13:46:49.717432       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0505 13:46:49.717530       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 13:47:49.939526       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:49.939568       1 utils.go:199] ID: 21 GRPC request: {}
I0505 13:47:49.939584       1 utils.go:205] ID: 21 GRPC response: {}
I0505 13:48:49.891671       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 13:48:49.891734       1 utils.go:199] ID: 22 GRPC request: {}
I0505 13:48:49.891764       1 utils.go:205] ID: 22 GRPC response: {}
I0505 13:49:49.890984       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0505 13:49:49.891036       1 utils.go:199] ID: 23 GRPC request: {}
I0505 13:49:49.891052       1 utils.go:205] ID: 23 GRPC response: {}
I0505 13:49:55.262554       1 utils.go:198] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b GRPC call: /csi.v1.Controller/CreateVolume
I0505 13:49:55.263289       1 utils.go:199] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-206d034f-daae-4849-b48d-96966aa3ff5b","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-206d034f-daae-4849-b48d-96966aa3ff5b","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 13:49:55.263701       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b setting disableInUseChecks: false image features: [layering] mounter: rbd
E0505 13:49:55.282784       1 omap.go:80] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 13:49:55.300884       1 omap.go:159] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-206d034f-daae-4849-b48d-96966aa3ff5b:be36f567-7d1c-41ba-aab7-6b4b29f46925])
I0505 13:49:55.307810       1 omap.go:159] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b set omap keys (pool="kube", namespace="", name="csi.volume.be36f567-7d1c-41ba-aab7-6b4b29f46925"): map[csi.imagename:csi-vol-be36f567-7d1c-41ba-aab7-6b4b29f46925 csi.volname:pvc-206d034f-daae-4849-b48d-96966aa3ff5b csi.volume.owner:default])
I0505 13:49:55.307925       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-be36f567-7d1c-41ba-aab7-6b4b29f46925) and image name (csi-vol-be36f567-7d1c-41ba-aab7-6b4b29f46925) for request name (pvc-206d034f-daae-4849-b48d-96966aa3ff5b)
I0505 13:49:55.308085       1 rbd_util.go:423] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b rbd: create kube/csi-vol-be36f567-7d1c-41ba-aab7-6b4b29f46925 size 5120M (features: [layering]) using mon 199.204.45.129
I0505 13:49:55.308180       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b setting image options on kube/csi-vol-be36f567-7d1c-41ba-aab7-6b4b29f46925
I0505 13:49:57.078404       1 controllerserver.go:763] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b created image kube/csi-vol-be36f567-7d1c-41ba-aab7-6b4b29f46925 backed for request name pvc-206d034f-daae-4849-b48d-96966aa3ff5b
I0505 13:49:57.115206       1 omap.go:159] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b set omap keys (pool="kube", namespace="", name="csi.volume.be36f567-7d1c-41ba-aab7-6b4b29f46925"): map[csi.imageid:37d939efd30b])
I0505 13:49:57.203306       1 utils.go:205] ID: 24 Req-ID: pvc-206d034f-daae-4849-b48d-96966aa3ff5b GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-be36f567-7d1c-41ba-aab7-6b4b29f46925","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-be36f567-7d1c-41ba-aab7-6b4b29f46925"}}
I0505 13:50:49.901540       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0505 13:50:49.901588       1 utils.go:199] ID: 25 GRPC request: {}
I0505 13:50:49.901603       1 utils.go:205] ID: 25 GRPC response: {}