I0814 09:10:17.564277       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0814 09:10:17.564391       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0814 09:10:17.564408       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0814 09:10:17.564419       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0814 09:10:17.564422       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0814 09:10:17.564425       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0814 09:10:17.564428       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0814 09:10:17.564431       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0814 09:10:17.564434       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0814 09:10:17.564437       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0814 09:10:17.564714       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0814 09:10:17.564781       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0814 09:10:22.470627       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:22.472384       1 utils.go:199] ID: 1 GRPC request: {}
I0814 09:10:22.472440       1 utils.go:205] ID: 1 GRPC response: {}
I0814 09:10:22.474314       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0814 09:10:22.474568       1 utils.go:199] ID: 2 GRPC request: {}
I0814 09:10:22.474600       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0814 09:10:22.474743       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0814 09:10:22.476690       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:22.476743       1 utils.go:199] ID: 3 GRPC request: {}
I0814 09:10:22.476781       1 utils.go:205] ID: 3 GRPC response: {}
I0814 09:10:22.477163       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0814 09:10:22.477288       1 utils.go:199] ID: 4 GRPC request: {}
I0814 09:10:22.477509       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0814 09:10:22.478514       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0814 09:10:22.478623       1 utils.go:199] ID: 5 GRPC request: {}
I0814 09:10:22.478660       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0814 09:10:22.478834       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}}}]}
I0814 09:10:31.194800       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:31.194832       1 utils.go:199] ID: 6 GRPC request: {}
I0814 09:10:31.194846       1 utils.go:205] ID: 6 GRPC response: {}
I0814 09:10:31.196652       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0814 09:10:31.196664       1 utils.go:199] ID: 7 GRPC request: {}
I0814 09:10:31.196669       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0814 09:10:31.196689       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0814 09:10:31.202009       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:31.202052       1 utils.go:199] ID: 8 GRPC request: {}
I0814 09:10:31.202075       1 utils.go:205] ID: 8 GRPC response: {}
I0814 09:10:31.202432       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0814 09:10:31.202453       1 utils.go:199] ID: 9 GRPC request: {}
I0814 09:10:31.202522       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0814 09:10:31.203065       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0814 09:10:31.203089       1 utils.go:199] ID: 10 GRPC request: {}
I0814 09:10:31.203096       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0814 09:10:31.203158       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}}}]}
I0814 09:10:31.203831       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0814 09:10:31.203873       1 utils.go:199] ID: 11 GRPC request: {}
I0814 09:10:31.203901       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0814 09:10:31.204017       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}}}]}
I0814 09:10:31.204412       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0814 09:10:31.204444       1 utils.go:199] ID: 12 GRPC request: {}
I0814 09:10:31.204457       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0814 09:10:31.204549       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}}}]}
I0814 09:10:33.051684       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0814 09:10:33.051729       1 utils.go:199] ID: 13 GRPC request: {}
I0814 09:10:33.051739       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0814 09:10:33.051773       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0814 09:10:33.054228       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:33.054280       1 utils.go:199] ID: 14 GRPC request: {}
I0814 09:10:33.054298       1 utils.go:205] ID: 14 GRPC response: {}
I0814 09:10:33.056014       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0814 09:10:33.056060       1 utils.go:199] ID: 15 GRPC request: {}
I0814 09:10:33.056071       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0814 09:10:33.056185       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}}}]}
I0814 09:10:35.081396       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:35.081452       1 utils.go:199] ID: 16 GRPC request: {}
I0814 09:10:35.081482       1 utils.go:205] ID: 16 GRPC response: {}
I0814 09:10:35.083080       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0814 09:10:35.083101       1 utils.go:199] ID: 17 GRPC request: {}
I0814 09:10:35.083108       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0814 09:10:35.083144       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0814 09:10:35.084850       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0814 09:10:35.084869       1 utils.go:199] ID: 18 GRPC request: {}
I0814 09:10:35.084890       1 utils.go:205] ID: 18 GRPC response: {}
I0814 09:10:35.085582       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0814 09:10:35.085620       1 utils.go:199] ID: 19 GRPC request: {}
I0814 09:10:35.085788       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0814 09:10:35.086604       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0814 09:10:35.086639       1 utils.go:199] ID: 20 GRPC request: {}
I0814 09:10:35.086648       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0814 09:10:35.086735       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}}}]}
I0814 09:11:35.848552       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0814 09:11:35.848591       1 utils.go:199] ID: 21 GRPC request: {}
I0814 09:11:35.848606       1 utils.go:205] ID: 21 GRPC response: {}
I0814 09:12:35.851761       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0814 09:12:35.851816       1 utils.go:199] ID: 22 GRPC request: {}
I0814 09:12:35.851843       1 utils.go:205] ID: 22 GRPC response: {}
I0814 09:13:24.900365       1 utils.go:198] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 GRPC call: /csi.v1.Controller/CreateVolume
I0814 09:13:24.900732       1 utils.go:199] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-77445022-4d16-43fd-818c-4c85421c12f7","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-77445022-4d16-43fd-818c-4c85421c12f7","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}}]}
I0814 09:13:24.901021       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0814 09:13:24.922024       1 omap.go:80] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0814 09:13:24.931737       1 omap.go:159] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-77445022-4d16-43fd-818c-4c85421c12f7:d5b46589-c6f0-4e5f-ba6e-14ba8684cd98])
I0814 09:13:24.938858       1 omap.go:159] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 set omap keys (pool="kube", namespace="", name="csi.volume.d5b46589-c6f0-4e5f-ba6e-14ba8684cd98"): map[csi.imagename:csi-vol-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98 csi.volname:pvc-77445022-4d16-43fd-818c-4c85421c12f7 csi.volume.owner:default])
I0814 09:13:24.938900       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98) and image name (csi-vol-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98) for request name (pvc-77445022-4d16-43fd-818c-4c85421c12f7)
I0814 09:13:24.938991       1 rbd_util.go:423] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 rbd: create kube/csi-vol-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98 size 5120M (features: [layering]) using mon 199.19.213.201
I0814 09:13:24.939042       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 setting image options on kube/csi-vol-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98
I0814 09:13:24.972594       1 controllerserver.go:763] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 created image kube/csi-vol-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98 backed for request name pvc-77445022-4d16-43fd-818c-4c85421c12f7
I0814 09:13:25.007476       1 omap.go:159] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 set omap keys (pool="kube", namespace="", name="csi.volume.d5b46589-c6f0-4e5f-ba6e-14ba8684cd98"): map[csi.imageid:3802e82298b1])
I0814 09:13:25.095617       1 utils.go:205] ID: 23 Req-ID: pvc-77445022-4d16-43fd-818c-4c85421c12f7 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d5b46589-c6f0-4e5f-ba6e-14ba8684cd98"}}
I0814 09:13:35.848078       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0814 09:13:35.848122       1 utils.go:199] ID: 24 GRPC request: {}
I0814 09:13:35.848141       1 utils.go:205] ID: 24 GRPC response: {}