I0401 05:09:05.437742       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0401 05:09:05.437876       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0401 05:09:05.437904       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0401 05:09:05.437908       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0401 05:09:05.437912       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0401 05:09:05.437915       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0401 05:09:05.437919       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0401 05:09:05.437923       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0401 05:09:05.437926       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0401 05:09:05.437929       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0401 05:09:05.438237       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0401 05:09:05.438281       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0401 05:09:07.466675       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:07.469129       1 utils.go:199] ID: 1 GRPC request: {}
I0401 05:09:07.469259       1 utils.go:205] ID: 1 GRPC response: {}
I0401 05:09:07.474082       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:09:07.474171       1 utils.go:199] ID: 2 GRPC request: {}
I0401 05:09:07.474188       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0401 05:09:07.474260       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:09:07.476607       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:07.476707       1 utils.go:199] ID: 3 GRPC request: {}
I0401 05:09:07.476735       1 utils.go:205] ID: 3 GRPC response: {}
I0401 05:09:07.477373       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0401 05:09:07.477537       1 utils.go:199] ID: 4 GRPC request: {}
I0401 05:09:07.477898       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0401 05:09:07.478970       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:09:07.479051       1 utils.go:199] ID: 5 GRPC request: {}
I0401 05:09:07.479063       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0401 05:09:07.479222       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}}}]}
I0401 05:09:10.194779       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:10.194827       1 utils.go:199] ID: 6 GRPC request: {}
I0401 05:09:10.194841       1 utils.go:205] ID: 6 GRPC response: {}
I0401 05:09:10.199805       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:09:10.200026       1 utils.go:199] ID: 7 GRPC request: {}
I0401 05:09:10.200051       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0401 05:09:10.200197       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:09:10.202995       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:10.203030       1 utils.go:199] ID: 8 GRPC request: {}
I0401 05:09:10.203091       1 utils.go:205] ID: 8 GRPC response: {}
I0401 05:09:10.204096       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0401 05:09:10.204273       1 utils.go:199] ID: 9 GRPC request: {}
I0401 05:09:10.204509       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0401 05:09:10.205624       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:09:10.205760       1 utils.go:199] ID: 10 GRPC request: {}
I0401 05:09:10.205819       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0401 05:09:10.205918       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}}}]}
I0401 05:09:10.206497       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:09:10.206546       1 utils.go:199] ID: 11 GRPC request: {}
I0401 05:09:10.206570       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0401 05:09:10.206654       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}}}]}
I0401 05:09:10.207149       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:09:10.207193       1 utils.go:199] ID: 12 GRPC request: {}
I0401 05:09:10.207239       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0401 05:09:10.207412       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}}}]}
I0401 05:09:11.364770       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:09:11.364817       1 utils.go:199] ID: 13 GRPC request: {}
I0401 05:09:11.364825       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0401 05:09:11.364848       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:09:11.368556       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:11.368613       1 utils.go:199] ID: 14 GRPC request: {}
I0401 05:09:11.368678       1 utils.go:205] ID: 14 GRPC response: {}
I0401 05:09:11.369800       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:09:11.369825       1 utils.go:199] ID: 15 GRPC request: {}
I0401 05:09:11.369837       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0401 05:09:11.370011       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}}}]}
I0401 05:09:12.708663       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:12.708783       1 utils.go:199] ID: 16 GRPC request: {}
I0401 05:09:12.708810       1 utils.go:205] ID: 16 GRPC response: {}
I0401 05:09:12.716016       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0401 05:09:12.716073       1 utils.go:199] ID: 17 GRPC request: {}
I0401 05:09:12.716090       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0401 05:09:12.716123       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0401 05:09:12.718441       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0401 05:09:12.718477       1 utils.go:199] ID: 18 GRPC request: {}
I0401 05:09:12.718499       1 utils.go:205] ID: 18 GRPC response: {}
I0401 05:09:12.718949       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0401 05:09:12.718977       1 utils.go:199] ID: 19 GRPC request: {}
I0401 05:09:12.719075       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0401 05:09:12.719583       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0401 05:09:12.719602       1 utils.go:199] ID: 20 GRPC request: {}
I0401 05:09:12.719611       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0401 05:09:12.719672       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}}}]}
I0401 05:10:12.883863       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0401 05:10:12.883927       1 utils.go:199] ID: 21 GRPC request: {}
I0401 05:10:12.883959       1 utils.go:205] ID: 21 GRPC response: {}
I0401 05:11:12.886543       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0401 05:11:12.886640       1 utils.go:199] ID: 22 GRPC request: {}
I0401 05:11:12.886857       1 utils.go:205] ID: 22 GRPC response: {}
I0401 05:12:12.909691       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0401 05:12:12.909786       1 utils.go:199] ID: 23 GRPC request: {}
I0401 05:12:12.909811       1 utils.go:205] ID: 23 GRPC response: {}
I0401 05:12:21.495318       1 utils.go:198] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 GRPC call: /csi.v1.Controller/CreateVolume
I0401 05:12:21.495707       1 utils.go:199] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16","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}}]}
I0401 05:12:21.496001       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0401 05:12:21.536904       1 omap.go:80] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0401 05:12:21.548611       1 omap.go:159] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16:a2c9ce91-6c5c-4c33-85ec-c6312de62e4a])
I0401 05:12:21.555603       1 omap.go:159] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 set omap keys (pool="kube", namespace="", name="csi.volume.a2c9ce91-6c5c-4c33-85ec-c6312de62e4a"): map[csi.imagename:csi-vol-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a csi.volname:pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 csi.volume.owner:default])
I0401 05:12:21.555652       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a) and image name (csi-vol-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a) for request name (pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16)
I0401 05:12:21.555742       1 rbd_util.go:423] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 rbd: create kube/csi-vol-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a size 5120M (features: [layering]) using mon 162.253.55.207
I0401 05:12:21.555887       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 setting image options on kube/csi-vol-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a
I0401 05:12:22.792158       1 controllerserver.go:763] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 created image kube/csi-vol-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a backed for request name pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16
I0401 05:12:22.829004       1 omap.go:159] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 set omap keys (pool="kube", namespace="", name="csi.volume.a2c9ce91-6c5c-4c33-85ec-c6312de62e4a"): map[csi.imageid:37d92585040])
I0401 05:12:22.914456       1 utils.go:205] ID: 24 Req-ID: pvc-1ce6d0b7-c79c-4f17-a0b3-9165b3959b16 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-a2c9ce91-6c5c-4c33-85ec-c6312de62e4a"}}