I0416 06:01:42.689130       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0416 06:01:42.689340       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0416 06:01:42.689385       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0416 06:01:42.689397       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0416 06:01:42.689417       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0416 06:01:42.689428       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0416 06:01:42.689433       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0416 06:01:42.689438       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0416 06:01:42.689442       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0416 06:01:42.689447       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0416 06:01:42.689922       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0416 06:01:42.689966       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0416 06:01:44.919018       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:44.920373       1 utils.go:199] ID: 1 GRPC request: {}
I0416 06:01:44.920469       1 utils.go:205] ID: 1 GRPC response: {}
I0416 06:01:44.923781       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0416 06:01:44.923955       1 utils.go:199] ID: 2 GRPC request: {}
I0416 06:01:44.923982       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0416 06:01:44.924085       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0416 06:01:44.926580       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:44.926684       1 utils.go:199] ID: 3 GRPC request: {}
I0416 06:01:44.926729       1 utils.go:205] ID: 3 GRPC response: {}
I0416 06:01:44.927189       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0416 06:01:44.927319       1 utils.go:199] ID: 4 GRPC request: {}
I0416 06:01:44.927532       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0416 06:01:44.928382       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0416 06:01:44.928464       1 utils.go:199] ID: 5 GRPC request: {}
I0416 06:01:44.928480       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0416 06:01:44.928601       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}}}]}
I0416 06:01:46.038816       1 utils.go:198] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 GRPC call: /csi.v1.Controller/CreateVolume
I0416 06:01:46.039284       1 utils.go:199] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2","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}}]}
I0416 06:01:46.039584       1 rbd_util.go:1315] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0416 06:01:46.208820       1 omap.go:80] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0416 06:01:46.224017       1 omap.go:159] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2:f988e015-a22f-4b49-ac8d-2f63d2738295])
I0416 06:01:46.231609       1 omap.go:159] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 set omap keys (pool="kube", namespace="", name="csi.volume.f988e015-a22f-4b49-ac8d-2f63d2738295"): map[csi.imagename:csi-vol-f988e015-a22f-4b49-ac8d-2f63d2738295 csi.volname:pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 csi.volume.owner:default])
I0416 06:01:46.231645       1 rbd_journal.go:491] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f988e015-a22f-4b49-ac8d-2f63d2738295) and image name (csi-vol-f988e015-a22f-4b49-ac8d-2f63d2738295) for request name (pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2)
I0416 06:01:46.231723       1 rbd_util.go:423] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 rbd: create kube/csi-vol-f988e015-a22f-4b49-ac8d-2f63d2738295 size 5120M (features: [layering]) using mon 199.204.45.115
I0416 06:01:46.231772       1 rbd_util.go:1563] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 setting image options on kube/csi-vol-f988e015-a22f-4b49-ac8d-2f63d2738295
I0416 06:01:47.263811       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:47.263904       1 utils.go:199] ID: 7 GRPC request: {}
I0416 06:01:47.263929       1 utils.go:205] ID: 7 GRPC response: {}
I0416 06:01:47.267087       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/GetPluginInfo
I0416 06:01:47.267115       1 utils.go:199] ID: 8 GRPC request: {}
I0416 06:01:47.267126       1 identityserver-default.go:40] ID: 8 Using default GetPluginInfo
I0416 06:01:47.267158       1 utils.go:205] ID: 8 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0416 06:01:47.269857       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:47.269967       1 utils.go:199] ID: 9 GRPC request: {}
I0416 06:01:47.270069       1 utils.go:205] ID: 9 GRPC response: {}
I0416 06:01:47.270930       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0416 06:01:47.271009       1 utils.go:199] ID: 10 GRPC request: {}
I0416 06:01:47.271270       1 utils.go:205] ID: 10 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0416 06:01:47.272366       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0416 06:01:47.272409       1 utils.go:199] ID: 11 GRPC request: {}
I0416 06:01:47.272422       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0416 06:01:47.272545       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}}}]}
I0416 06:01:47.273212       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0416 06:01:47.273237       1 utils.go:199] ID: 12 GRPC request: {}
I0416 06:01:47.273245       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0416 06:01:47.273327       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}}}]}
I0416 06:01:47.275294       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0416 06:01:47.275321       1 utils.go:199] ID: 13 GRPC request: {}
I0416 06:01:47.275330       1 controllerserver-default.go:42] ID: 13 Using default ControllerGetCapabilities
I0416 06:01:47.275417       1 utils.go:205] ID: 13 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0416 06:01:48.273548       1 controllerserver.go:763] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 created image kube/csi-vol-f988e015-a22f-4b49-ac8d-2f63d2738295 backed for request name pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2
I0416 06:01:48.301947       1 omap.go:159] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 set omap keys (pool="kube", namespace="", name="csi.volume.f988e015-a22f-4b49-ac8d-2f63d2738295"): map[csi.imageid:37b4532cb25])
I0416 06:01:48.399857       1 utils.go:205] ID: 6 Req-ID: pvc-624547bd-90f6-4195-aaff-f47d94a5c5e2 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-f988e015-a22f-4b49-ac8d-2f63d2738295","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f988e015-a22f-4b49-ac8d-2f63d2738295"}}
I0416 06:01:48.565657       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/GetPluginInfo
I0416 06:01:48.565716       1 utils.go:199] ID: 14 GRPC request: {}
I0416 06:01:48.565729       1 identityserver-default.go:40] ID: 14 Using default GetPluginInfo
I0416 06:01:48.565795       1 utils.go:205] ID: 14 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0416 06:01:48.570156       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:48.570182       1 utils.go:199] ID: 15 GRPC request: {}
I0416 06:01:48.570200       1 utils.go:205] ID: 15 GRPC response: {}
I0416 06:01:48.571050       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0416 06:01:48.571125       1 utils.go:199] ID: 16 GRPC request: {}
I0416 06:01:48.571155       1 controllerserver-default.go:42] ID: 16 Using default ControllerGetCapabilities
I0416 06:01:48.571286       1 utils.go:205] ID: 16 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0416 06:01:49.711803       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:49.711847       1 utils.go:199] ID: 17 GRPC request: {}
I0416 06:01:49.711866       1 utils.go:205] ID: 17 GRPC response: {}
I0416 06:01:49.718282       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/GetPluginInfo
I0416 06:01:49.718317       1 utils.go:199] ID: 18 GRPC request: {}
I0416 06:01:49.718326       1 identityserver-default.go:40] ID: 18 Using default GetPluginInfo
I0416 06:01:49.718350       1 utils.go:205] ID: 18 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0416 06:01:49.722953       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/Probe
I0416 06:01:49.723015       1 utils.go:199] ID: 19 GRPC request: {}
I0416 06:01:49.723051       1 utils.go:205] ID: 19 GRPC response: {}
I0416 06:01:49.724601       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0416 06:01:49.724668       1 utils.go:199] ID: 20 GRPC request: {}
I0416 06:01:49.724939       1 utils.go:205] ID: 20 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0416 06:01:49.725890       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0416 06:01:49.725940       1 utils.go:199] ID: 21 GRPC request: {}
I0416 06:01:49.725973       1 controllerserver-default.go:42] ID: 21 Using default ControllerGetCapabilities
I0416 06:01:49.726153       1 utils.go:205] ID: 21 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}