I0210 05:54:51.154550       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0210 05:54:51.159411       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0210 05:54:51.159460       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0210 05:54:51.159470       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0210 05:54:51.159474       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0210 05:54:51.159478       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0210 05:54:51.159484       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0210 05:54:51.159491       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0210 05:54:51.159497       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0210 05:54:51.159500       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0210 05:54:51.159828       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0210 05:54:51.164042       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0210 05:54:55.856242       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0210 05:54:55.857174       1 utils.go:199] ID: 1 GRPC request: {}
I0210 05:54:55.857216       1 utils.go:205] ID: 1 GRPC response: {}
I0210 05:54:55.859476       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0210 05:54:55.859705       1 utils.go:199] ID: 2 GRPC request: {}
I0210 05:54:55.859793       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0210 05:54:55.859974       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0210 05:54:55.863326       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0210 05:54:55.863345       1 utils.go:199] ID: 3 GRPC request: {}
I0210 05:54:55.863355       1 utils.go:205] ID: 3 GRPC response: {}
I0210 05:54:55.864115       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0210 05:54:55.864187       1 utils.go:199] ID: 4 GRPC request: {}
I0210 05:54:55.864330       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0210 05:54:55.868288       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0210 05:54:55.868334       1 utils.go:199] ID: 5 GRPC request: {}
I0210 05:54:55.868340       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0210 05:54:55.868422       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}}}]}
I0210 05:54:58.369278       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0210 05:54:58.369307       1 utils.go:199] ID: 6 GRPC request: {}
I0210 05:54:58.369335       1 utils.go:205] ID: 6 GRPC response: {}
I0210 05:54:58.370965       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0210 05:54:58.370982       1 utils.go:199] ID: 7 GRPC request: {}
I0210 05:54:58.370987       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0210 05:54:58.371040       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0210 05:54:58.372058       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0210 05:54:58.372072       1 utils.go:199] ID: 8 GRPC request: {}
I0210 05:54:58.372122       1 utils.go:205] ID: 8 GRPC response: {}
I0210 05:54:58.372410       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0210 05:54:58.372425       1 utils.go:199] ID: 9 GRPC request: {}
I0210 05:54:58.372541       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0210 05:54:58.372928       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0210 05:54:58.372944       1 utils.go:199] ID: 10 GRPC request: {}
I0210 05:54:58.372951       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0210 05:54:58.373024       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}}}]}
I0210 05:54:58.373359       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0210 05:54:58.373373       1 utils.go:199] ID: 11 GRPC request: {}
I0210 05:54:58.373377       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0210 05:54:58.373448       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}}}]}
I0210 05:54:58.373710       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0210 05:54:58.373724       1 utils.go:199] ID: 12 GRPC request: {}
I0210 05:54:58.373729       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0210 05:54:58.373796       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}}}]}
I0210 05:55:00.687284       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0210 05:55:00.687329       1 utils.go:199] ID: 13 GRPC request: {}
I0210 05:55:00.687341       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0210 05:55:00.687369       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0210 05:55:00.688825       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0210 05:55:00.688843       1 utils.go:199] ID: 14 GRPC request: {}
I0210 05:55:00.688857       1 utils.go:205] ID: 14 GRPC response: {}
I0210 05:55:00.689145       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0210 05:55:00.689221       1 utils.go:199] ID: 15 GRPC request: {}
I0210 05:55:00.689232       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0210 05:55:00.689327       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}}}]}
I0210 05:55:03.192609       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0210 05:55:03.192662       1 utils.go:199] ID: 16 GRPC request: {}
I0210 05:55:03.192685       1 utils.go:205] ID: 16 GRPC response: {}
I0210 05:55:03.220114       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0210 05:55:03.220153       1 utils.go:199] ID: 17 GRPC request: {}
I0210 05:55:03.220160       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0210 05:55:03.220180       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0210 05:55:03.224953       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0210 05:55:03.224981       1 utils.go:199] ID: 18 GRPC request: {}
I0210 05:55:03.225003       1 utils.go:205] ID: 18 GRPC response: {}
I0210 05:55:03.237068       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0210 05:55:03.237189       1 utils.go:199] ID: 19 GRPC request: {}
I0210 05:55:03.237294       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0210 05:55:03.238527       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0210 05:55:03.238546       1 utils.go:199] ID: 20 GRPC request: {}
I0210 05:55:03.238573       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0210 05:55:03.238670       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}}}]}
I0210 05:56:03.446330       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0210 05:56:03.446366       1 utils.go:199] ID: 21 GRPC request: {}
I0210 05:56:03.446422       1 utils.go:205] ID: 21 GRPC response: {}
I0210 05:57:03.446603       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0210 05:57:03.446664       1 utils.go:199] ID: 22 GRPC request: {}
I0210 05:57:03.446686       1 utils.go:205] ID: 22 GRPC response: {}
I0210 05:57:54.912703       1 utils.go:198] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a GRPC call: /csi.v1.Controller/CreateVolume
I0210 05:57:54.913193       1 utils.go:199] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a","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}}]}
I0210 05:57:54.913681       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a setting disableInUseChecks: false image features: [layering] mounter: rbd
E0210 05:57:54.958618       1 omap.go:80] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0210 05:57:54.975748       1 omap.go:159] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a:051c6a9c-ad82-4c75-a257-e31b27d63e19])
I0210 05:57:54.983616       1 omap.go:159] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a set omap keys (pool="kube", namespace="", name="csi.volume.051c6a9c-ad82-4c75-a257-e31b27d63e19"): map[csi.imagename:csi-vol-051c6a9c-ad82-4c75-a257-e31b27d63e19 csi.volname:pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a csi.volume.owner:default])
I0210 05:57:54.983641       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-051c6a9c-ad82-4c75-a257-e31b27d63e19) and image name (csi-vol-051c6a9c-ad82-4c75-a257-e31b27d63e19) for request name (pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a)
I0210 05:57:54.983706       1 rbd_util.go:423] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a rbd: create kube/csi-vol-051c6a9c-ad82-4c75-a257-e31b27d63e19 size 5120M (features: [layering]) using mon 162.253.55.195
I0210 05:57:54.983747       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a setting image options on kube/csi-vol-051c6a9c-ad82-4c75-a257-e31b27d63e19
I0210 05:57:56.543446       1 controllerserver.go:763] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a created image kube/csi-vol-051c6a9c-ad82-4c75-a257-e31b27d63e19 backed for request name pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a
I0210 05:57:56.581446       1 omap.go:159] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a set omap keys (pool="kube", namespace="", name="csi.volume.051c6a9c-ad82-4c75-a257-e31b27d63e19"): map[csi.imageid:37d7cda919ee])
I0210 05:57:56.684254       1 utils.go:205] ID: 23 Req-ID: pvc-b6f23796-a179-4eaf-80a9-b1e6f20ad14a GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-051c6a9c-ad82-4c75-a257-e31b27d63e19","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-051c6a9c-ad82-4c75-a257-e31b27d63e19"}}
I0210 05:58:03.446618       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0210 05:58:03.446671       1 utils.go:199] ID: 24 GRPC request: {}
I0210 05:58:03.446690       1 utils.go:205] ID: 24 GRPC response: {}