I0226 17:57:21.081071       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0226 17:57:21.081196       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0226 17:57:21.081211       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0226 17:57:21.081216       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0226 17:57:21.081220       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0226 17:57:21.081224       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0226 17:57:21.081230       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0226 17:57:21.081234       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0226 17:57:21.081238       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0226 17:57:21.081242       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0226 17:57:21.081547       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0226 17:57:21.081860       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0226 17:57:23.616637       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:23.622429       1 utils.go:199] ID: 1 GRPC request: {}
I0226 17:57:23.622542       1 utils.go:205] ID: 1 GRPC response: {}
I0226 17:57:23.627853       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 17:57:23.627978       1 utils.go:199] ID: 2 GRPC request: {}
I0226 17:57:23.628002       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0226 17:57:23.628072       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 17:57:23.630088       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:23.630150       1 utils.go:199] ID: 3 GRPC request: {}
I0226 17:57:23.630184       1 utils.go:205] ID: 3 GRPC response: {}
I0226 17:57:23.630791       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0226 17:57:23.630903       1 utils.go:199] ID: 4 GRPC request: {}
I0226 17:57:23.631228       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0226 17:57:23.632450       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 17:57:23.632539       1 utils.go:199] ID: 5 GRPC request: {}
I0226 17:57:23.632569       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0226 17:57:23.632785       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}}}]}
I0226 17:57:26.021054       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:26.021104       1 utils.go:199] ID: 6 GRPC request: {}
I0226 17:57:26.021122       1 utils.go:205] ID: 6 GRPC response: {}
I0226 17:57:26.024648       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 17:57:26.024679       1 utils.go:199] ID: 7 GRPC request: {}
I0226 17:57:26.024691       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0226 17:57:26.024731       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 17:57:26.026537       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:26.026597       1 utils.go:199] ID: 8 GRPC request: {}
I0226 17:57:26.026624       1 utils.go:205] ID: 8 GRPC response: {}
I0226 17:57:26.027220       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0226 17:57:26.027243       1 utils.go:199] ID: 9 GRPC request: {}
I0226 17:57:26.027351       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0226 17:57:26.028058       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 17:57:26.028080       1 utils.go:199] ID: 10 GRPC request: {}
I0226 17:57:26.028091       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0226 17:57:26.028164       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}}}]}
I0226 17:57:26.028837       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 17:57:26.028901       1 utils.go:199] ID: 11 GRPC request: {}
I0226 17:57:26.028917       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0226 17:57:26.029030       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}}}]}
I0226 17:57:26.029517       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 17:57:26.029555       1 utils.go:199] ID: 12 GRPC request: {}
I0226 17:57:26.029569       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0226 17:57:26.029721       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}}}]}
I0226 17:57:27.199968       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 17:57:27.200013       1 utils.go:199] ID: 13 GRPC request: {}
I0226 17:57:27.200031       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0226 17:57:27.200058       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 17:57:27.206246       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:27.206373       1 utils.go:199] ID: 14 GRPC request: {}
I0226 17:57:27.206405       1 utils.go:205] ID: 14 GRPC response: {}
I0226 17:57:27.207225       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 17:57:27.207265       1 utils.go:199] ID: 15 GRPC request: {}
I0226 17:57:27.207275       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0226 17:57:27.207370       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}}}]}
I0226 17:57:28.419294       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:28.419348       1 utils.go:199] ID: 16 GRPC request: {}
I0226 17:57:28.419373       1 utils.go:205] ID: 16 GRPC response: {}
I0226 17:57:28.421734       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0226 17:57:28.421782       1 utils.go:199] ID: 17 GRPC request: {}
I0226 17:57:28.421791       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0226 17:57:28.421818       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0226 17:57:28.423194       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0226 17:57:28.423216       1 utils.go:199] ID: 18 GRPC request: {}
I0226 17:57:28.423226       1 utils.go:205] ID: 18 GRPC response: {}
I0226 17:57:28.423682       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0226 17:57:28.423728       1 utils.go:199] ID: 19 GRPC request: {}
I0226 17:57:28.423834       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0226 17:57:28.424630       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0226 17:57:28.424667       1 utils.go:199] ID: 20 GRPC request: {}
I0226 17:57:28.424806       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0226 17:57:28.424988       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}}}]}
I0226 17:58:28.686386       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0226 17:58:28.686436       1 utils.go:199] ID: 21 GRPC request: {}
I0226 17:58:28.686459       1 utils.go:205] ID: 21 GRPC response: {}
I0226 17:59:28.641168       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0226 17:59:28.641245       1 utils.go:199] ID: 22 GRPC request: {}
I0226 17:59:28.641293       1 utils.go:205] ID: 22 GRPC response: {}
I0226 18:00:28.693229       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0226 18:00:28.693312       1 utils.go:199] ID: 23 GRPC request: {}
I0226 18:00:28.693337       1 utils.go:205] ID: 23 GRPC response: {}
I0226 18:00:33.469568       1 utils.go:198] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 GRPC call: /csi.v1.Controller/CreateVolume
I0226 18:00:33.469963       1 utils.go:199] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3","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}}]}
I0226 18:00:33.470232       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0226 18:00:33.489243       1 omap.go:80] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0226 18:00:33.504562       1 omap.go:159] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3:9a9236ac-3978-4800-85b5-071cd108e311])
I0226 18:00:33.512001       1 omap.go:159] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 set omap keys (pool="kube", namespace="", name="csi.volume.9a9236ac-3978-4800-85b5-071cd108e311"): map[csi.imagename:csi-vol-9a9236ac-3978-4800-85b5-071cd108e311 csi.volname:pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 csi.volume.owner:default])
I0226 18:00:33.512042       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9a9236ac-3978-4800-85b5-071cd108e311) and image name (csi-vol-9a9236ac-3978-4800-85b5-071cd108e311) for request name (pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3)
I0226 18:00:33.512116       1 rbd_util.go:423] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 rbd: create kube/csi-vol-9a9236ac-3978-4800-85b5-071cd108e311 size 5120M (features: [layering]) using mon 162.253.55.36
I0226 18:00:33.512165       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 setting image options on kube/csi-vol-9a9236ac-3978-4800-85b5-071cd108e311
I0226 18:00:34.771779       1 controllerserver.go:763] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 created image kube/csi-vol-9a9236ac-3978-4800-85b5-071cd108e311 backed for request name pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3
I0226 18:00:34.809040       1 omap.go:159] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 set omap keys (pool="kube", namespace="", name="csi.volume.9a9236ac-3978-4800-85b5-071cd108e311"): map[csi.imageid:37d9263dff5e])
I0226 18:00:34.909379       1 utils.go:205] ID: 24 Req-ID: pvc-55224bd7-fc33-41ee-a433-1bcd0fe9e2f3 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-9a9236ac-3978-4800-85b5-071cd108e311","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9a9236ac-3978-4800-85b5-071cd108e311"}}