I0813 00:56:19.842335       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0813 00:56:19.842480       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0813 00:56:19.842494       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0813 00:56:19.842499       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0813 00:56:19.842503       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0813 00:56:19.842507       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0813 00:56:19.842512       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0813 00:56:19.842516       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0813 00:56:19.842519       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0813 00:56:19.842522       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0813 00:56:19.842825       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0813 00:56:19.842972       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0813 00:56:23.270321       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:23.271852       1 utils.go:199] ID: 1 GRPC request: {}
I0813 00:56:23.271968       1 utils.go:205] ID: 1 GRPC response: {}
I0813 00:56:23.274463       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0813 00:56:23.274638       1 utils.go:199] ID: 2 GRPC request: {}
I0813 00:56:23.274655       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0813 00:56:23.274742       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0813 00:56:23.276857       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:23.276910       1 utils.go:199] ID: 3 GRPC request: {}
I0813 00:56:23.276930       1 utils.go:205] ID: 3 GRPC response: {}
I0813 00:56:23.277633       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0813 00:56:23.277898       1 utils.go:199] ID: 4 GRPC request: {}
I0813 00:56:23.278272       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0813 00:56:23.279488       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0813 00:56:23.279791       1 utils.go:199] ID: 5 GRPC request: {}
I0813 00:56:23.279946       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0813 00:56:23.280305       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}}}]}
I0813 00:56:25.221494       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:25.221626       1 utils.go:199] ID: 6 GRPC request: {}
I0813 00:56:25.221672       1 utils.go:205] ID: 6 GRPC response: {}
I0813 00:56:25.223611       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0813 00:56:25.223648       1 utils.go:199] ID: 7 GRPC request: {}
I0813 00:56:25.223658       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0813 00:56:25.223677       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0813 00:56:25.225265       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:25.225289       1 utils.go:199] ID: 8 GRPC request: {}
I0813 00:56:25.225301       1 utils.go:205] ID: 8 GRPC response: {}
I0813 00:56:25.225748       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0813 00:56:25.225763       1 utils.go:199] ID: 9 GRPC request: {}
I0813 00:56:25.225841       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0813 00:56:25.228879       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0813 00:56:25.228896       1 utils.go:199] ID: 10 GRPC request: {}
I0813 00:56:25.228901       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0813 00:56:25.228955       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}}}]}
I0813 00:56:25.229412       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0813 00:56:25.229425       1 utils.go:199] ID: 11 GRPC request: {}
I0813 00:56:25.229429       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0813 00:56:25.229477       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}}}]}
I0813 00:56:25.229813       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0813 00:56:25.229826       1 utils.go:199] ID: 12 GRPC request: {}
I0813 00:56:25.229830       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0813 00:56:25.229958       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}}}]}
I0813 00:56:26.483414       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0813 00:56:26.483456       1 utils.go:199] ID: 13 GRPC request: {}
I0813 00:56:26.483484       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0813 00:56:26.485872       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0813 00:56:26.490456       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:26.490481       1 utils.go:199] ID: 14 GRPC request: {}
I0813 00:56:26.490495       1 utils.go:205] ID: 14 GRPC response: {}
I0813 00:56:26.491120       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0813 00:56:26.491142       1 utils.go:199] ID: 15 GRPC request: {}
I0813 00:56:26.491150       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0813 00:56:26.491264       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}}}]}
I0813 00:56:27.755643       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:27.755718       1 utils.go:199] ID: 16 GRPC request: {}
I0813 00:56:27.755735       1 utils.go:205] ID: 16 GRPC response: {}
I0813 00:56:27.758349       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0813 00:56:27.758384       1 utils.go:199] ID: 17 GRPC request: {}
I0813 00:56:27.758394       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0813 00:56:27.758457       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0813 00:56:27.761535       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0813 00:56:27.761571       1 utils.go:199] ID: 18 GRPC request: {}
I0813 00:56:27.761589       1 utils.go:205] ID: 18 GRPC response: {}
I0813 00:56:27.761868       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0813 00:56:27.761888       1 utils.go:199] ID: 19 GRPC request: {}
I0813 00:56:27.761965       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0813 00:56:27.764441       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0813 00:56:27.764471       1 utils.go:199] ID: 20 GRPC request: {}
I0813 00:56:27.764479       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0813 00:56:27.764671       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}}}]}
I0813 00:57:27.991499       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0813 00:57:27.991581       1 utils.go:199] ID: 21 GRPC request: {}
I0813 00:57:27.991605       1 utils.go:205] ID: 21 GRPC response: {}
I0813 00:58:27.936030       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0813 00:58:27.936107       1 utils.go:199] ID: 22 GRPC request: {}
I0813 00:58:27.936125       1 utils.go:205] ID: 22 GRPC response: {}
I0813 00:59:13.460031       1 utils.go:198] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 GRPC call: /csi.v1.Controller/CreateVolume
I0813 00:59:13.460480       1 utils.go:199] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1","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}}]}
I0813 00:59:13.460781       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0813 00:59:13.476475       1 omap.go:80] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0813 00:59:13.484556       1 omap.go:159] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1:39a911d5-7988-4835-bd76-7e82917c8959])
I0813 00:59:13.487824       1 omap.go:159] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 set omap keys (pool="kube", namespace="", name="csi.volume.39a911d5-7988-4835-bd76-7e82917c8959"): map[csi.imagename:csi-vol-39a911d5-7988-4835-bd76-7e82917c8959 csi.volname:pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 csi.volume.owner:default])
I0813 00:59:13.487894       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-39a911d5-7988-4835-bd76-7e82917c8959) and image name (csi-vol-39a911d5-7988-4835-bd76-7e82917c8959) for request name (pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1)
I0813 00:59:13.487969       1 rbd_util.go:423] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 rbd: create kube/csi-vol-39a911d5-7988-4835-bd76-7e82917c8959 size 5120M (features: [layering]) using mon 199.19.213.152
I0813 00:59:13.488022       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 setting image options on kube/csi-vol-39a911d5-7988-4835-bd76-7e82917c8959
I0813 00:59:13.506671       1 controllerserver.go:763] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 created image kube/csi-vol-39a911d5-7988-4835-bd76-7e82917c8959 backed for request name pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1
I0813 00:59:13.531569       1 omap.go:159] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 set omap keys (pool="kube", namespace="", name="csi.volume.39a911d5-7988-4835-bd76-7e82917c8959"): map[csi.imageid:3804f78ea6cb])
I0813 00:59:13.601087       1 utils.go:205] ID: 23 Req-ID: pvc-6a45cbce-8f57-473f-b04b-da818d03c7d1 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-39a911d5-7988-4835-bd76-7e82917c8959","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-39a911d5-7988-4835-bd76-7e82917c8959"}}
I0813 00:59:27.933420       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0813 00:59:27.933481       1 utils.go:199] ID: 24 GRPC request: {}
I0813 00:59:27.933502       1 utils.go:205] ID: 24 GRPC response: {}