I0505 13:47:47.548378       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0505 13:47:47.548525       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 13:47:47.548546       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 13:47:47.548550       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 13:47:47.548553       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0505 13:47:47.548556       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0505 13:47:47.548559       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 13:47:47.548562       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 13:47:47.548566       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 13:47:47.548570       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 13:47:47.548862       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 13:47:47.548941       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 13:47:50.466220       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:50.467585       1 utils.go:199] ID: 1 GRPC request: {}
I0505 13:47:50.467641       1 utils.go:205] ID: 1 GRPC response: {}
I0505 13:47:50.470293       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:47:50.470373       1 utils.go:199] ID: 2 GRPC request: {}
I0505 13:47:50.470399       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0505 13:47:50.470470       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:47:50.472144       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:50.472177       1 utils.go:199] ID: 3 GRPC request: {}
I0505 13:47:50.472191       1 utils.go:205] ID: 3 GRPC response: {}
I0505 13:47:50.472601       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 13:47:50.472655       1 utils.go:199] ID: 4 GRPC request: {}
I0505 13:47:50.472860       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 13:47:50.473686       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:47:50.473725       1 utils.go:199] ID: 5 GRPC request: {}
I0505 13:47:50.473735       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0505 13:47:50.473932       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}}}]}
I0505 13:47:52.816699       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:52.816745       1 utils.go:199] ID: 6 GRPC request: {}
I0505 13:47:52.816773       1 utils.go:205] ID: 6 GRPC response: {}
I0505 13:47:52.820535       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:47:52.820638       1 utils.go:199] ID: 7 GRPC request: {}
I0505 13:47:52.820682       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0505 13:47:52.820735       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:47:52.822364       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:52.822384       1 utils.go:199] ID: 8 GRPC request: {}
I0505 13:47:52.822395       1 utils.go:205] ID: 8 GRPC response: {}
I0505 13:47:52.822878       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 13:47:52.822921       1 utils.go:199] ID: 9 GRPC request: {}
I0505 13:47:52.823076       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 13:47:52.823733       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:47:52.823753       1 utils.go:199] ID: 10 GRPC request: {}
I0505 13:47:52.823761       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0505 13:47:52.823839       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}}}]}
I0505 13:47:52.824427       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:47:52.824456       1 utils.go:199] ID: 11 GRPC request: {}
I0505 13:47:52.824465       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0505 13:47:52.824555       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}}}]}
I0505 13:47:52.825138       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:47:52.825159       1 utils.go:199] ID: 12 GRPC request: {}
I0505 13:47:52.825165       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0505 13:47:52.825288       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}}}]}
I0505 13:47:54.008983       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:47:54.009027       1 utils.go:199] ID: 13 GRPC request: {}
I0505 13:47:54.009038       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0505 13:47:54.009072       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:47:54.011409       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:54.011514       1 utils.go:199] ID: 14 GRPC request: {}
I0505 13:47:54.011576       1 utils.go:205] ID: 14 GRPC response: {}
I0505 13:47:54.012080       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:47:54.012110       1 utils.go:199] ID: 15 GRPC request: {}
I0505 13:47:54.012120       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0505 13:47:54.012223       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}}}]}
I0505 13:47:55.228837       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:55.228880       1 utils.go:199] ID: 16 GRPC request: {}
I0505 13:47:55.228896       1 utils.go:205] ID: 16 GRPC response: {}
I0505 13:47:55.230929       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 13:47:55.230950       1 utils.go:199] ID: 17 GRPC request: {}
I0505 13:47:55.230957       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0505 13:47:55.230978       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 13:47:55.233049       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0505 13:47:55.233291       1 utils.go:199] ID: 18 GRPC request: {}
I0505 13:47:55.233311       1 utils.go:205] ID: 18 GRPC response: {}
I0505 13:47:55.233823       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 13:47:55.233851       1 utils.go:199] ID: 19 GRPC request: {}
I0505 13:47:55.233974       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 13:47:55.234947       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 13:47:55.234975       1 utils.go:199] ID: 20 GRPC request: {}
I0505 13:47:55.234983       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0505 13:47:55.235053       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}}}]}
I0505 13:48:55.446401       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0505 13:48:55.446468       1 utils.go:199] ID: 21 GRPC request: {}
I0505 13:48:55.446493       1 utils.go:205] ID: 21 GRPC response: {}
I0505 13:49:55.450354       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 13:49:55.450402       1 utils.go:199] ID: 22 GRPC request: {}
I0505 13:49:55.450424       1 utils.go:205] ID: 22 GRPC response: {}
I0505 13:50:55.445861       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0505 13:50:55.445927       1 utils.go:199] ID: 23 GRPC request: {}
I0505 13:50:55.445957       1 utils.go:205] ID: 23 GRPC response: {}
I0505 13:50:56.893537       1 utils.go:198] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f GRPC call: /csi.v1.Controller/CreateVolume
I0505 13:50:56.893887       1 utils.go:199] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f","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}}]}
I0505 13:50:56.894098       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f setting disableInUseChecks: false image features: [layering] mounter: rbd
E0505 13:50:56.915665       1 omap.go:80] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 13:50:56.930501       1 omap.go:159] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f:d94fa20a-8857-4aa0-968b-1a28842953f8])
I0505 13:50:56.936163       1 omap.go:159] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f set omap keys (pool="kube", namespace="", name="csi.volume.d94fa20a-8857-4aa0-968b-1a28842953f8"): map[csi.imagename:csi-vol-d94fa20a-8857-4aa0-968b-1a28842953f8 csi.volname:pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f csi.volume.owner:default])
I0505 13:50:56.936211       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d94fa20a-8857-4aa0-968b-1a28842953f8) and image name (csi-vol-d94fa20a-8857-4aa0-968b-1a28842953f8) for request name (pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f)
I0505 13:50:56.936292       1 rbd_util.go:423] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f rbd: create kube/csi-vol-d94fa20a-8857-4aa0-968b-1a28842953f8 size 5120M (features: [layering]) using mon 199.19.213.21
I0505 13:50:56.936366       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f setting image options on kube/csi-vol-d94fa20a-8857-4aa0-968b-1a28842953f8
I0505 13:50:58.408581       1 controllerserver.go:763] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f created image kube/csi-vol-d94fa20a-8857-4aa0-968b-1a28842953f8 backed for request name pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f
I0505 13:50:58.437184       1 omap.go:159] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f set omap keys (pool="kube", namespace="", name="csi.volume.d94fa20a-8857-4aa0-968b-1a28842953f8"): map[csi.imageid:37d9c6d4b585])
I0505 13:50:58.538368       1 utils.go:205] ID: 24 Req-ID: pvc-38978be2-f0f1-4ab9-8319-4a9e137a775f GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-d94fa20a-8857-4aa0-968b-1a28842953f8","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-d94fa20a-8857-4aa0-968b-1a28842953f8"}}