I0511 00:59:01.368113       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0511 00:59:01.368242       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0511 00:59:01.368261       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0511 00:59:01.368266       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0511 00:59:01.368270       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0511 00:59:01.368274       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0511 00:59:01.368279       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0511 00:59:01.368294       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0511 00:59:01.368303       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0511 00:59:01.368307       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0511 00:59:01.368833       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0511 00:59:01.368936       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0511 00:59:08.703910       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:08.708587       1 utils.go:199] ID: 1 GRPC request: {}
I0511 00:59:08.708667       1 utils.go:205] ID: 1 GRPC response: {}
I0511 00:59:08.713560       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0511 00:59:08.713649       1 utils.go:199] ID: 2 GRPC request: {}
I0511 00:59:08.713667       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0511 00:59:08.713731       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0511 00:59:08.715795       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:08.715850       1 utils.go:199] ID: 3 GRPC request: {}
I0511 00:59:08.715925       1 utils.go:205] ID: 3 GRPC response: {}
I0511 00:59:08.716481       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0511 00:59:08.716832       1 utils.go:199] ID: 4 GRPC request: {}
I0511 00:59:08.717444       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0511 00:59:08.718556       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0511 00:59:08.718615       1 utils.go:199] ID: 5 GRPC request: {}
I0511 00:59:08.718626       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0511 00:59:08.718788       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}}}]}
I0511 00:59:11.182549       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:11.182579       1 utils.go:199] ID: 6 GRPC request: {}
I0511 00:59:11.182593       1 utils.go:205] ID: 6 GRPC response: {}
I0511 00:59:11.184021       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0511 00:59:11.184037       1 utils.go:199] ID: 7 GRPC request: {}
I0511 00:59:11.184044       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0511 00:59:11.184062       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0511 00:59:11.186939       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:11.186981       1 utils.go:199] ID: 8 GRPC request: {}
I0511 00:59:11.187008       1 utils.go:205] ID: 8 GRPC response: {}
I0511 00:59:11.187876       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0511 00:59:11.187951       1 utils.go:199] ID: 9 GRPC request: {}
I0511 00:59:11.188084       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0511 00:59:11.188982       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0511 00:59:11.189014       1 utils.go:199] ID: 10 GRPC request: {}
I0511 00:59:11.189033       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0511 00:59:11.189192       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}}}]}
I0511 00:59:11.189931       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0511 00:59:11.189998       1 utils.go:199] ID: 11 GRPC request: {}
I0511 00:59:11.190020       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0511 00:59:11.190171       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}}}]}
I0511 00:59:11.190664       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0511 00:59:11.190698       1 utils.go:199] ID: 12 GRPC request: {}
I0511 00:59:11.190714       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0511 00:59:11.190833       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}}}]}
I0511 00:59:12.438503       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0511 00:59:12.438586       1 utils.go:199] ID: 13 GRPC request: {}
I0511 00:59:12.438605       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0511 00:59:12.438646       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0511 00:59:12.441054       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:12.441091       1 utils.go:199] ID: 14 GRPC request: {}
I0511 00:59:12.441107       1 utils.go:205] ID: 14 GRPC response: {}
I0511 00:59:12.441523       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0511 00:59:12.441561       1 utils.go:199] ID: 15 GRPC request: {}
I0511 00:59:12.441572       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0511 00:59:12.441711       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}}}]}
I0511 00:59:19.902186       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:19.902233       1 utils.go:199] ID: 16 GRPC request: {}
I0511 00:59:19.902250       1 utils.go:205] ID: 16 GRPC response: {}
I0511 00:59:19.904765       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0511 00:59:19.904878       1 utils.go:199] ID: 17 GRPC request: {}
I0511 00:59:19.904921       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0511 00:59:19.904979       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0511 00:59:19.906887       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0511 00:59:19.906940       1 utils.go:199] ID: 18 GRPC request: {}
I0511 00:59:19.906971       1 utils.go:205] ID: 18 GRPC response: {}
I0511 00:59:19.907479       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0511 00:59:19.907527       1 utils.go:199] ID: 19 GRPC request: {}
I0511 00:59:19.907670       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0511 00:59:19.908259       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0511 00:59:19.908313       1 utils.go:199] ID: 20 GRPC request: {}
I0511 00:59:19.908346       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0511 00:59:19.908453       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}}}]}
I0511 01:00:20.411871       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0511 01:00:20.412415       1 utils.go:199] ID: 21 GRPC request: {}
I0511 01:00:20.412683       1 utils.go:205] ID: 21 GRPC response: {}
I0511 01:01:20.416309       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0511 01:01:20.416444       1 utils.go:199] ID: 22 GRPC request: {}
I0511 01:01:20.416514       1 utils.go:205] ID: 22 GRPC response: {}
I0511 01:02:18.404448       1 utils.go:198] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd GRPC call: /csi.v1.Controller/CreateVolume
I0511 01:02:18.405175       1 utils.go:199] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd","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}}]}
I0511 01:02:18.405533       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd setting disableInUseChecks: false image features: [layering] mounter: rbd
E0511 01:02:18.427963       1 omap.go:80] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0511 01:02:18.454211       1 omap.go:159] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd:86c05282-133f-481c-a8aa-85fbc8d055f0])
I0511 01:02:18.461602       1 omap.go:159] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd set omap keys (pool="kube", namespace="", name="csi.volume.86c05282-133f-481c-a8aa-85fbc8d055f0"): map[csi.imagename:csi-vol-86c05282-133f-481c-a8aa-85fbc8d055f0 csi.volname:pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd csi.volume.owner:default])
I0511 01:02:18.461640       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-86c05282-133f-481c-a8aa-85fbc8d055f0) and image name (csi-vol-86c05282-133f-481c-a8aa-85fbc8d055f0) for request name (pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd)
I0511 01:02:18.461733       1 rbd_util.go:423] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd rbd: create kube/csi-vol-86c05282-133f-481c-a8aa-85fbc8d055f0 size 5120M (features: [layering]) using mon 199.204.45.3
I0511 01:02:18.461797       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd setting image options on kube/csi-vol-86c05282-133f-481c-a8aa-85fbc8d055f0
I0511 01:02:20.297975       1 controllerserver.go:763] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd created image kube/csi-vol-86c05282-133f-481c-a8aa-85fbc8d055f0 backed for request name pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd
I0511 01:02:20.333976       1 omap.go:159] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd set omap keys (pool="kube", namespace="", name="csi.volume.86c05282-133f-481c-a8aa-85fbc8d055f0"): map[csi.imageid:37d962bb4403])
I0511 01:02:20.411299       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0511 01:02:20.411353       1 utils.go:199] ID: 24 GRPC request: {}
I0511 01:02:20.411376       1 utils.go:205] ID: 24 GRPC response: {}
I0511 01:02:20.557003       1 utils.go:205] ID: 23 Req-ID: pvc-3b1dfebe-9178-4e42-91a0-a1857123b0bd GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-86c05282-133f-481c-a8aa-85fbc8d055f0","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-86c05282-133f-481c-a8aa-85fbc8d055f0"}}