I0806 07:06:40.548876       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0806 07:06:40.549027       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0806 07:06:40.549039       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0806 07:06:40.549044       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0806 07:06:40.549047       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0806 07:06:40.549049       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0806 07:06:40.549052       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0806 07:06:40.549055       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0806 07:06:40.549058       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0806 07:06:40.549060       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0806 07:06:40.549382       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0806 07:06:40.549436       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0806 07:06:47.690364       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:47.692207       1 utils.go:199] ID: 1 GRPC request: {}
I0806 07:06:47.692292       1 utils.go:205] ID: 1 GRPC response: {}
I0806 07:06:47.694411       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0806 07:06:47.694500       1 utils.go:199] ID: 2 GRPC request: {}
I0806 07:06:47.694516       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0806 07:06:47.694564       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0806 07:06:47.696519       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:47.696551       1 utils.go:199] ID: 3 GRPC request: {}
I0806 07:06:47.696579       1 utils.go:205] ID: 3 GRPC response: {}
I0806 07:06:47.697166       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0806 07:06:47.697227       1 utils.go:199] ID: 4 GRPC request: {}
I0806 07:06:47.697433       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0806 07:06:47.698282       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0806 07:06:47.698353       1 utils.go:199] ID: 5 GRPC request: {}
I0806 07:06:47.698391       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0806 07:06:47.698513       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}}}]}
I0806 07:06:49.096940       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:49.096989       1 utils.go:199] ID: 6 GRPC request: {}
I0806 07:06:49.097001       1 utils.go:205] ID: 6 GRPC response: {}
I0806 07:06:49.098410       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0806 07:06:49.098432       1 utils.go:199] ID: 7 GRPC request: {}
I0806 07:06:49.098438       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0806 07:06:49.098456       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0806 07:06:49.101578       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:49.101616       1 utils.go:199] ID: 8 GRPC request: {}
I0806 07:06:49.101637       1 utils.go:205] ID: 8 GRPC response: {}
I0806 07:06:49.102094       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0806 07:06:49.102226       1 utils.go:199] ID: 9 GRPC request: {}
I0806 07:06:49.102355       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0806 07:06:49.103259       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0806 07:06:49.103335       1 utils.go:199] ID: 10 GRPC request: {}
I0806 07:06:49.103354       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0806 07:06:49.103489       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}}}]}
I0806 07:06:49.104041       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0806 07:06:49.104064       1 utils.go:199] ID: 11 GRPC request: {}
I0806 07:06:49.104070       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0806 07:06:49.104139       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}}}]}
I0806 07:06:49.104459       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0806 07:06:49.104480       1 utils.go:199] ID: 12 GRPC request: {}
I0806 07:06:49.104486       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0806 07:06:49.104556       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}}}]}
I0806 07:06:50.418916       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0806 07:06:50.418966       1 utils.go:199] ID: 13 GRPC request: {}
I0806 07:06:50.418974       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0806 07:06:50.418997       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0806 07:06:50.422563       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:50.422603       1 utils.go:199] ID: 14 GRPC request: {}
I0806 07:06:50.422622       1 utils.go:205] ID: 14 GRPC response: {}
I0806 07:06:50.423124       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0806 07:06:50.423155       1 utils.go:199] ID: 15 GRPC request: {}
I0806 07:06:50.423163       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0806 07:06:50.423273       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}}}]}
I0806 07:06:51.754730       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:51.754770       1 utils.go:199] ID: 16 GRPC request: {}
I0806 07:06:51.754786       1 utils.go:205] ID: 16 GRPC response: {}
I0806 07:06:51.758283       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0806 07:06:51.758305       1 utils.go:199] ID: 17 GRPC request: {}
I0806 07:06:51.758318       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0806 07:06:51.758341       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0806 07:06:51.763437       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0806 07:06:51.763471       1 utils.go:199] ID: 18 GRPC request: {}
I0806 07:06:51.763492       1 utils.go:205] ID: 18 GRPC response: {}
I0806 07:06:51.763871       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0806 07:06:51.763922       1 utils.go:199] ID: 19 GRPC request: {}
I0806 07:06:51.764048       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0806 07:06:51.766072       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0806 07:06:51.766105       1 utils.go:199] ID: 20 GRPC request: {}
I0806 07:06:51.766117       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0806 07:06:51.769057       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}}}]}
I0806 07:07:51.949056       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0806 07:07:51.949109       1 utils.go:199] ID: 21 GRPC request: {}
I0806 07:07:51.949131       1 utils.go:205] ID: 21 GRPC response: {}
I0806 07:08:51.950291       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0806 07:08:51.950458       1 utils.go:199] ID: 22 GRPC request: {}
I0806 07:08:51.950490       1 utils.go:205] ID: 22 GRPC response: {}
I0806 07:09:51.948409       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0806 07:09:51.948462       1 utils.go:199] ID: 23 GRPC request: {}
I0806 07:09:51.948486       1 utils.go:205] ID: 23 GRPC response: {}
I0806 07:10:51.961214       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0806 07:10:51.961272       1 utils.go:199] ID: 24 GRPC request: {}
I0806 07:10:51.961307       1 utils.go:205] ID: 24 GRPC response: {}
I0806 07:11:06.032885       1 utils.go:198] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c GRPC call: /csi.v1.Controller/CreateVolume
I0806 07:11:06.033992       1 utils.go:199] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c","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}}]}
I0806 07:11:06.034279       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c setting disableInUseChecks: false image features: [layering] mounter: rbd
E0806 07:11:06.051521       1 omap.go:80] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0806 07:11:06.068530       1 omap.go:159] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c:fb73ff12-2877-4bfc-b077-9a01ec811533])
I0806 07:11:06.077817       1 omap.go:159] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c set omap keys (pool="kube", namespace="", name="csi.volume.fb73ff12-2877-4bfc-b077-9a01ec811533"): map[csi.imagename:csi-vol-fb73ff12-2877-4bfc-b077-9a01ec811533 csi.volname:pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c csi.volume.owner:default])
I0806 07:11:06.077903       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-fb73ff12-2877-4bfc-b077-9a01ec811533) and image name (csi-vol-fb73ff12-2877-4bfc-b077-9a01ec811533) for request name (pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c)
I0806 07:11:06.078071       1 rbd_util.go:423] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c rbd: create kube/csi-vol-fb73ff12-2877-4bfc-b077-9a01ec811533 size 5120M (features: [layering]) using mon 199.204.45.77
I0806 07:11:06.078154       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c setting image options on kube/csi-vol-fb73ff12-2877-4bfc-b077-9a01ec811533
I0806 07:11:06.118561       1 controllerserver.go:763] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c created image kube/csi-vol-fb73ff12-2877-4bfc-b077-9a01ec811533 backed for request name pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c
I0806 07:11:06.153916       1 omap.go:159] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c set omap keys (pool="kube", namespace="", name="csi.volume.fb73ff12-2877-4bfc-b077-9a01ec811533"): map[csi.imageid:37f482b0035c])
I0806 07:11:06.264222       1 utils.go:205] ID: 25 Req-ID: pvc-5603fad5-d336-4f8a-a39f-e5005b80ad5c GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-fb73ff12-2877-4bfc-b077-9a01ec811533","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-fb73ff12-2877-4bfc-b077-9a01ec811533"}}
I0806 07:11:51.948769       1 utils.go:198] ID: 26 GRPC call: /csi.v1.Identity/Probe
I0806 07:11:51.948823       1 utils.go:199] ID: 26 GRPC request: {}
I0806 07:11:51.948867       1 utils.go:205] ID: 26 GRPC response: {}