I0228 06:01:32.115528       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0228 06:01:32.115734       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0228 06:01:32.115762       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0228 06:01:32.115777       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0228 06:01:32.115789       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0228 06:01:32.115801       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0228 06:01:32.115844       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0228 06:01:32.115859       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0228 06:01:32.115871       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0228 06:01:32.115882       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0228 06:01:32.116183       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0228 06:01:32.116194       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0228 06:01:34.707597       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:34.708734       1 utils.go:199] ID: 1 GRPC request: {}
I0228 06:01:34.708896       1 utils.go:205] ID: 1 GRPC response: {}
I0228 06:01:34.712650       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0228 06:01:34.712751       1 utils.go:199] ID: 2 GRPC request: {}
I0228 06:01:34.712779       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0228 06:01:34.712858       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0228 06:01:34.714507       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:34.714547       1 utils.go:199] ID: 3 GRPC request: {}
I0228 06:01:34.714567       1 utils.go:205] ID: 3 GRPC response: {}
I0228 06:01:34.715061       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0228 06:01:34.715221       1 utils.go:199] ID: 4 GRPC request: {}
I0228 06:01:34.715425       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0228 06:01:34.716385       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0228 06:01:34.716470       1 utils.go:199] ID: 5 GRPC request: {}
I0228 06:01:34.716490       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0228 06:01:34.716650       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}}}]}
I0228 06:01:37.574562       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:37.574627       1 utils.go:199] ID: 6 GRPC request: {}
I0228 06:01:37.574650       1 utils.go:205] ID: 6 GRPC response: {}
I0228 06:01:37.576571       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0228 06:01:37.576601       1 utils.go:199] ID: 7 GRPC request: {}
I0228 06:01:37.576607       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0228 06:01:37.576631       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0228 06:01:37.578828       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:37.578855       1 utils.go:199] ID: 8 GRPC request: {}
I0228 06:01:37.578876       1 utils.go:205] ID: 8 GRPC response: {}
I0228 06:01:37.579534       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0228 06:01:37.579550       1 utils.go:199] ID: 9 GRPC request: {}
I0228 06:01:37.579691       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0228 06:01:37.580205       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0228 06:01:37.580222       1 utils.go:199] ID: 10 GRPC request: {}
I0228 06:01:37.580228       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0228 06:01:37.580337       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}}}]}
I0228 06:01:37.581098       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0228 06:01:37.581113       1 utils.go:199] ID: 11 GRPC request: {}
I0228 06:01:37.581116       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0228 06:01:37.581215       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}}}]}
I0228 06:01:37.581552       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0228 06:01:37.581566       1 utils.go:199] ID: 12 GRPC request: {}
I0228 06:01:37.581571       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0228 06:01:37.581662       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}}}]}
I0228 06:01:38.784926       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0228 06:01:38.784973       1 utils.go:199] ID: 13 GRPC request: {}
I0228 06:01:38.784985       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0228 06:01:38.785020       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0228 06:01:38.788681       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:38.788706       1 utils.go:199] ID: 14 GRPC request: {}
I0228 06:01:38.788718       1 utils.go:205] ID: 14 GRPC response: {}
I0228 06:01:38.789195       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0228 06:01:38.789232       1 utils.go:199] ID: 15 GRPC request: {}
I0228 06:01:38.789241       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0228 06:01:38.789342       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}}}]}
I0228 06:01:40.004554       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:40.004659       1 utils.go:199] ID: 16 GRPC request: {}
I0228 06:01:40.004716       1 utils.go:205] ID: 16 GRPC response: {}
I0228 06:01:40.006681       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0228 06:01:40.006730       1 utils.go:199] ID: 17 GRPC request: {}
I0228 06:01:40.006742       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0228 06:01:40.006782       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0228 06:01:40.009719       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0228 06:01:40.009763       1 utils.go:199] ID: 18 GRPC request: {}
I0228 06:01:40.009787       1 utils.go:205] ID: 18 GRPC response: {}
I0228 06:01:40.010172       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0228 06:01:40.010209       1 utils.go:199] ID: 19 GRPC request: {}
I0228 06:01:40.010386       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0228 06:01:40.010956       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0228 06:01:40.010998       1 utils.go:199] ID: 20 GRPC request: {}
I0228 06:01:40.011012       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0228 06:01:40.011114       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}}}]}
I0228 06:02:40.249555       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0228 06:02:40.250000       1 utils.go:199] ID: 21 GRPC request: {}
I0228 06:02:40.250059       1 utils.go:205] ID: 21 GRPC response: {}
I0228 06:03:40.194107       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0228 06:03:40.194240       1 utils.go:199] ID: 22 GRPC request: {}
I0228 06:03:40.194268       1 utils.go:205] ID: 22 GRPC response: {}
I0228 06:04:38.841337       1 utils.go:198] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 GRPC call: /csi.v1.Controller/CreateVolume
I0228 06:04:38.842010       1 utils.go:199] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-746cc419-1aed-4305-80e7-ea13199445a3","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-746cc419-1aed-4305-80e7-ea13199445a3","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}}]}
I0228 06:04:38.842457       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0228 06:04:38.864870       1 omap.go:80] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0228 06:04:38.881747       1 omap.go:159] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-746cc419-1aed-4305-80e7-ea13199445a3:d8d9a8e0-62f7-4f26-bba0-0dd19d50023e])
I0228 06:04:38.889214       1 omap.go:159] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 set omap keys (pool="kube", namespace="", name="csi.volume.d8d9a8e0-62f7-4f26-bba0-0dd19d50023e"): map[csi.imagename:csi-vol-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e csi.volname:pvc-746cc419-1aed-4305-80e7-ea13199445a3 csi.volume.owner:default])
I0228 06:04:38.889314       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e) and image name (csi-vol-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e) for request name (pvc-746cc419-1aed-4305-80e7-ea13199445a3)
I0228 06:04:38.889424       1 rbd_util.go:423] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 rbd: create kube/csi-vol-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e size 5120M (features: [layering]) using mon 162.253.55.74
I0228 06:04:38.889548       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 setting image options on kube/csi-vol-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e
I0228 06:04:40.194285       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0228 06:04:40.194341       1 utils.go:199] ID: 24 GRPC request: {}
I0228 06:04:40.194363       1 utils.go:205] ID: 24 GRPC response: {}
I0228 06:04:40.796934       1 controllerserver.go:763] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 created image kube/csi-vol-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e backed for request name pvc-746cc419-1aed-4305-80e7-ea13199445a3
I0228 06:04:40.831660       1 omap.go:159] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 set omap keys (pool="kube", namespace="", name="csi.volume.d8d9a8e0-62f7-4f26-bba0-0dd19d50023e"): map[csi.imageid:37d986fe41ea])
I0228 06:04:40.938029       1 utils.go:205] ID: 23 Req-ID: pvc-746cc419-1aed-4305-80e7-ea13199445a3 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d8d9a8e0-62f7-4f26-bba0-0dd19d50023e"}}