I0407 06:45:11.213920       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0407 06:45:11.214059       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0407 06:45:11.214075       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0407 06:45:11.214079       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0407 06:45:11.214082       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0407 06:45:11.214084       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0407 06:45:11.214088       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0407 06:45:11.214091       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0407 06:45:11.214094       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0407 06:45:11.214097       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0407 06:45:11.214391       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0407 06:45:11.214427       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0407 06:45:12.669945       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:12.671281       1 utils.go:199] ID: 1 GRPC request: {}
I0407 06:45:12.671378       1 utils.go:205] ID: 1 GRPC response: {}
I0407 06:45:12.673442       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:45:12.673523       1 utils.go:199] ID: 2 GRPC request: {}
I0407 06:45:12.673539       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0407 06:45:12.673578       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:45:12.675245       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:12.675299       1 utils.go:199] ID: 3 GRPC request: {}
I0407 06:45:12.675328       1 utils.go:205] ID: 3 GRPC response: {}
I0407 06:45:12.675712       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:45:12.675788       1 utils.go:199] ID: 4 GRPC request: {}
I0407 06:45:12.675932       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:45:12.676738       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:45:12.676812       1 utils.go:199] ID: 5 GRPC request: {}
I0407 06:45:12.676838       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0407 06:45:12.676954       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}}}]}
I0407 06:45:16.961155       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:16.961223       1 utils.go:199] ID: 6 GRPC request: {}
I0407 06:45:16.961254       1 utils.go:205] ID: 6 GRPC response: {}
I0407 06:45:16.964829       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:45:16.964860       1 utils.go:199] ID: 7 GRPC request: {}
I0407 06:45:16.964871       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0407 06:45:16.964904       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:45:16.967143       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:16.967171       1 utils.go:199] ID: 8 GRPC request: {}
I0407 06:45:16.967187       1 utils.go:205] ID: 8 GRPC response: {}
I0407 06:45:16.967841       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:45:16.967860       1 utils.go:199] ID: 9 GRPC request: {}
I0407 06:45:16.967996       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:45:16.968735       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:45:16.968751       1 utils.go:199] ID: 10 GRPC request: {}
I0407 06:45:16.968760       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0407 06:45:16.968828       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}}}]}
I0407 06:45:16.969509       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:45:16.969528       1 utils.go:199] ID: 11 GRPC request: {}
I0407 06:45:16.969533       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0407 06:45:16.969662       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}}}]}
I0407 06:45:16.970063       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:45:16.970079       1 utils.go:199] ID: 12 GRPC request: {}
I0407 06:45:16.970140       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0407 06:45:16.970244       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}}}]}
I0407 06:45:18.191816       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:45:18.191860       1 utils.go:199] ID: 13 GRPC request: {}
I0407 06:45:18.191869       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0407 06:45:18.191895       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:45:18.196759       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:18.196898       1 utils.go:199] ID: 14 GRPC request: {}
I0407 06:45:18.196945       1 utils.go:205] ID: 14 GRPC response: {}
I0407 06:45:18.197474       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:45:18.197524       1 utils.go:199] ID: 15 GRPC request: {}
I0407 06:45:18.197542       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0407 06:45:18.197636       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}}}]}
I0407 06:45:19.388191       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:19.388419       1 utils.go:199] ID: 16 GRPC request: {}
I0407 06:45:19.388453       1 utils.go:205] ID: 16 GRPC response: {}
I0407 06:45:19.391032       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:45:19.391051       1 utils.go:199] ID: 17 GRPC request: {}
I0407 06:45:19.391088       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0407 06:45:19.391118       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:45:19.393136       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0407 06:45:19.393282       1 utils.go:199] ID: 18 GRPC request: {}
I0407 06:45:19.393308       1 utils.go:205] ID: 18 GRPC response: {}
I0407 06:45:19.393840       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:45:19.393879       1 utils.go:199] ID: 19 GRPC request: {}
I0407 06:45:19.393964       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:45:19.394474       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:45:19.394492       1 utils.go:199] ID: 20 GRPC request: {}
I0407 06:45:19.394499       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0407 06:45:19.394591       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}}}]}
I0407 06:46:19.588288       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0407 06:46:19.588333       1 utils.go:199] ID: 21 GRPC request: {}
I0407 06:46:19.588359       1 utils.go:205] ID: 21 GRPC response: {}
I0407 06:47:19.568118       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0407 06:47:19.568203       1 utils.go:199] ID: 22 GRPC request: {}
I0407 06:47:19.568271       1 utils.go:205] ID: 22 GRPC response: {}
I0407 06:48:18.740920       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:18.740967       1 utils.go:199] ID: 23 GRPC request: {}
I0407 06:48:18.740983       1 utils.go:205] ID: 23 GRPC response: {}
I0407 06:48:18.743034       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:48:18.743052       1 utils.go:199] ID: 24 GRPC request: {}
I0407 06:48:18.743059       1 identityserver-default.go:40] ID: 24 Using default GetPluginInfo
I0407 06:48:18.743080       1 utils.go:205] ID: 24 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:48:18.745934       1 utils.go:198] ID: 25 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:18.745982       1 utils.go:199] ID: 25 GRPC request: {}
I0407 06:48:18.746003       1 utils.go:205] ID: 25 GRPC response: {}
I0407 06:48:18.747078       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:48:18.747097       1 utils.go:199] ID: 26 GRPC request: {}
I0407 06:48:18.747174       1 utils.go:205] ID: 26 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:48:18.748380       1 utils.go:198] ID: 27 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:48:18.748399       1 utils.go:199] ID: 27 GRPC request: {}
I0407 06:48:18.748407       1 controllerserver-default.go:42] ID: 27 Using default ControllerGetCapabilities
I0407 06:48:18.748465       1 utils.go:205] ID: 27 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:48:18.748807       1 utils.go:198] ID: 28 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:48:18.748818       1 utils.go:199] ID: 28 GRPC request: {}
I0407 06:48:18.748821       1 controllerserver-default.go:42] ID: 28 Using default ControllerGetCapabilities
I0407 06:48:18.748874       1 utils.go:205] ID: 28 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:48:18.751411       1 utils.go:198] ID: 29 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:48:18.751429       1 utils.go:199] ID: 29 GRPC request: {}
I0407 06:48:18.751437       1 controllerserver-default.go:42] ID: 29 Using default ControllerGetCapabilities
I0407 06:48:18.751535       1 utils.go:205] ID: 29 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:48:18.834773       1 utils.go:198] ID: 30 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:18.834827       1 utils.go:199] ID: 30 GRPC request: {}
I0407 06:48:18.834851       1 utils.go:205] ID: 30 GRPC response: {}
I0407 06:48:18.837621       1 utils.go:198] ID: 31 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:48:18.837638       1 utils.go:199] ID: 31 GRPC request: {}
I0407 06:48:18.837644       1 identityserver-default.go:40] ID: 31 Using default GetPluginInfo
I0407 06:48:18.837666       1 utils.go:205] ID: 31 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:48:18.838772       1 utils.go:198] ID: 32 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:18.838782       1 utils.go:199] ID: 32 GRPC request: {}
I0407 06:48:18.838789       1 utils.go:205] ID: 32 GRPC response: {}
I0407 06:48:18.839011       1 utils.go:198] ID: 33 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:48:18.839030       1 utils.go:199] ID: 33 GRPC request: {}
I0407 06:48:18.839109       1 utils.go:205] ID: 33 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:48:18.839726       1 utils.go:198] ID: 34 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:48:18.839737       1 utils.go:199] ID: 34 GRPC request: {}
I0407 06:48:18.839744       1 controllerserver-default.go:42] ID: 34 Using default ControllerGetCapabilities
I0407 06:48:18.839786       1 utils.go:205] ID: 34 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:48:19.583067       1 utils.go:198] ID: 35 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:19.583116       1 utils.go:199] ID: 35 GRPC request: {}
I0407 06:48:19.583133       1 utils.go:205] ID: 35 GRPC response: {}
I0407 06:48:37.586322       1 utils.go:198] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 GRPC call: /csi.v1.Controller/CreateVolume
I0407 06:48:37.586775       1 utils.go:199] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197","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}}]}
I0407 06:48:37.587068       1 rbd_util.go:1315] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0407 06:48:37.606430       1 omap.go:80] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0407 06:48:37.621953       1 omap.go:159] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197:2cc2575c-a589-4779-8f4b-e2cc51e75706])
I0407 06:48:37.633303       1 omap.go:159] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 set omap keys (pool="kube", namespace="", name="csi.volume.2cc2575c-a589-4779-8f4b-e2cc51e75706"): map[csi.imagename:csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706 csi.volname:pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 csi.volume.owner:default])
I0407 06:48:37.633337       1 rbd_journal.go:491] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-2cc2575c-a589-4779-8f4b-e2cc51e75706) and image name (csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706) for request name (pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197)
I0407 06:48:37.633425       1 rbd_util.go:423] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 rbd: create kube/csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706 size 5120M (features: [layering]) using mon 162.253.55.204
I0407 06:48:37.633482       1 rbd_util.go:1563] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 setting image options on kube/csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706
I0407 06:48:54.073455       1 controllerserver.go:763] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 created image kube/csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706 backed for request name pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197
I0407 06:48:54.111111       1 omap.go:159] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 set omap keys (pool="kube", namespace="", name="csi.volume.2cc2575c-a589-4779-8f4b-e2cc51e75706"): map[csi.imageid:37d96d95b988])
I0407 06:48:54.206679       1 utils.go:205] ID: 36 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-2cc2575c-a589-4779-8f4b-e2cc51e75706"}}
I0407 06:48:54.900396       1 utils.go:198] ID: 37 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:54.900461       1 utils.go:199] ID: 37 GRPC request: {}
I0407 06:48:54.900483       1 utils.go:205] ID: 37 GRPC response: {}
I0407 06:48:54.904093       1 utils.go:198] ID: 38 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:48:54.904118       1 utils.go:199] ID: 38 GRPC request: {}
I0407 06:48:54.904128       1 identityserver-default.go:40] ID: 38 Using default GetPluginInfo
I0407 06:48:54.904191       1 utils.go:205] ID: 38 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:48:54.906033       1 utils.go:198] ID: 39 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:54.906056       1 utils.go:199] ID: 39 GRPC request: {}
I0407 06:48:54.906069       1 utils.go:205] ID: 39 GRPC response: {}
I0407 06:48:54.906664       1 utils.go:198] ID: 40 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:48:54.906687       1 utils.go:199] ID: 40 GRPC request: {}
I0407 06:48:54.906781       1 utils.go:205] ID: 40 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:48:54.910631       1 utils.go:198] ID: 41 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:48:54.910663       1 utils.go:199] ID: 41 GRPC request: {}
I0407 06:48:54.910673       1 controllerserver-default.go:42] ID: 41 Using default ControllerGetCapabilities
I0407 06:48:54.910743       1 utils.go:205] ID: 41 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:48:54.980629       1 utils.go:198] ID: 42 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:48:54.980686       1 utils.go:199] ID: 42 GRPC request: {}
I0407 06:48:54.980697       1 identityserver-default.go:40] ID: 42 Using default GetPluginInfo
I0407 06:48:54.980727       1 utils.go:205] ID: 42 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:48:54.983870       1 utils.go:198] ID: 43 GRPC call: /csi.v1.Identity/Probe
I0407 06:48:54.983891       1 utils.go:199] ID: 43 GRPC request: {}
I0407 06:48:54.983913       1 utils.go:205] ID: 43 GRPC response: {}
I0407 06:48:54.984456       1 utils.go:198] ID: 44 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:48:54.984474       1 utils.go:199] ID: 44 GRPC request: {}
I0407 06:48:54.984483       1 controllerserver-default.go:42] ID: 44 Using default ControllerGetCapabilities
I0407 06:48:54.984587       1 utils.go:205] ID: 44 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:49:08.452781       1 utils.go:198] ID: 45 GRPC call: /csi.v1.Identity/Probe
I0407 06:49:08.452829       1 utils.go:199] ID: 45 GRPC request: {}
I0407 06:49:08.452849       1 utils.go:205] ID: 45 GRPC response: {}
I0407 06:49:08.455548       1 utils.go:198] ID: 46 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:49:08.455596       1 utils.go:199] ID: 46 GRPC request: {}
I0407 06:49:08.455603       1 identityserver-default.go:40] ID: 46 Using default GetPluginInfo
I0407 06:49:08.455629       1 utils.go:205] ID: 46 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:49:08.461021       1 utils.go:198] ID: 47 GRPC call: /csi.v1.Identity/Probe
I0407 06:49:08.461060       1 utils.go:199] ID: 47 GRPC request: {}
I0407 06:49:08.461078       1 utils.go:205] ID: 47 GRPC response: {}
I0407 06:49:08.463129       1 utils.go:198] ID: 48 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:49:08.463147       1 utils.go:199] ID: 48 GRPC request: {}
I0407 06:49:08.463228       1 utils.go:205] ID: 48 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:49:08.464757       1 utils.go:198] ID: 49 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:49:08.464776       1 utils.go:199] ID: 49 GRPC request: {}
I0407 06:49:08.464786       1 controllerserver-default.go:42] ID: 49 Using default ControllerGetCapabilities
I0407 06:49:08.464860       1 utils.go:205] ID: 49 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:49:08.466979       1 utils.go:198] ID: 50 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:49:08.466994       1 utils.go:199] ID: 50 GRPC request: {}
I0407 06:49:08.467014       1 controllerserver-default.go:42] ID: 50 Using default ControllerGetCapabilities
I0407 06:49:08.467068       1 utils.go:205] ID: 50 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:49:08.468316       1 utils.go:198] ID: 51 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:49:08.468335       1 utils.go:199] ID: 51 GRPC request: {}
I0407 06:49:08.468339       1 controllerserver-default.go:42] ID: 51 Using default ControllerGetCapabilities
I0407 06:49:08.468398       1 utils.go:205] ID: 51 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:49:08.519018       1 utils.go:198] ID: 52 GRPC call: /csi.v1.Identity/Probe
I0407 06:49:08.519192       1 utils.go:199] ID: 52 GRPC request: {}
I0407 06:49:08.519219       1 utils.go:205] ID: 52 GRPC response: {}
I0407 06:49:08.521999       1 utils.go:198] ID: 53 GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 06:49:08.522049       1 utils.go:199] ID: 53 GRPC request: {}
I0407 06:49:08.522059       1 identityserver-default.go:40] ID: 53 Using default GetPluginInfo
I0407 06:49:08.522093       1 utils.go:205] ID: 53 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0407 06:49:08.524430       1 utils.go:198] ID: 54 GRPC call: /csi.v1.Identity/Probe
I0407 06:49:08.524477       1 utils.go:199] ID: 54 GRPC request: {}
I0407 06:49:08.524504       1 utils.go:205] ID: 54 GRPC response: {}
I0407 06:49:08.524966       1 utils.go:198] ID: 55 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 06:49:08.524985       1 utils.go:199] ID: 55 GRPC request: {}
I0407 06:49:08.525092       1 utils.go:205] ID: 55 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 06:49:08.525916       1 utils.go:198] ID: 56 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 06:49:08.525937       1 utils.go:199] ID: 56 GRPC request: {}
I0407 06:49:08.525947       1 controllerserver-default.go:42] ID: 56 Using default ControllerGetCapabilities
I0407 06:49:08.526029       1 utils.go:205] ID: 56 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 06:49:12.259972       1 utils.go:198] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 GRPC call: /csi.v1.Controller/CreateVolume
I0407 06:49:12.260476       1 utils.go:199] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197","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}}]}
I0407 06:49:12.260828       1 rbd_util.go:1315] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 setting disableInUseChecks: false image features: [layering] mounter: rbd
I0407 06:49:12.262431       1 omap.go:89] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 got omap values: (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197:2cc2575c-a589-4779-8f4b-e2cc51e75706]
I0407 06:49:12.263458       1 omap.go:89] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 got omap values: (pool="kube", namespace="", name="csi.volume.2cc2575c-a589-4779-8f4b-e2cc51e75706"): map[csi.imageid:37d96d95b988 csi.imagename:csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706 csi.volname:pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 csi.volume.owner:default]
I0407 06:49:12.290405       1 rbd_journal.go:349] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 found existing volume (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-2cc2575c-a589-4779-8f4b-e2cc51e75706) with image name (csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706) for request (pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197)
I0407 06:49:12.376529       1 utils.go:205] ID: 57 Req-ID: pvc-c2c5ee95-fa21-485d-8bc9-db85df3ad197 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-2cc2575c-a589-4779-8f4b-e2cc51e75706","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-2cc2575c-a589-4779-8f4b-e2cc51e75706"}}
I0407 06:49:19.578880       1 utils.go:198] ID: 58 GRPC call: /csi.v1.Identity/Probe
I0407 06:49:19.578977       1 utils.go:199] ID: 58 GRPC request: {}
I0407 06:49:19.579009       1 utils.go:205] ID: 58 GRPC response: {}