I0305 16:34:19.412943       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0305 16:34:19.413100       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0305 16:34:19.413118       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0305 16:34:19.413122       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0305 16:34:19.413126       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0305 16:34:19.413129       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0305 16:34:19.413133       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0305 16:34:19.413135       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0305 16:34:19.413139       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0305 16:34:19.413142       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0305 16:34:19.413513       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0305 16:34:19.413597       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0305 16:34:21.275573       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:21.276839       1 utils.go:199] ID: 1 GRPC request: {}
I0305 16:34:21.276895       1 utils.go:205] ID: 1 GRPC response: {}
I0305 16:34:21.280899       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 16:34:21.281028       1 utils.go:199] ID: 2 GRPC request: {}
I0305 16:34:21.281056       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0305 16:34:21.281129       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 16:34:21.282936       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:21.282959       1 utils.go:199] ID: 3 GRPC request: {}
I0305 16:34:21.282971       1 utils.go:205] ID: 3 GRPC response: {}
I0305 16:34:21.283426       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0305 16:34:21.283478       1 utils.go:199] ID: 4 GRPC request: {}
I0305 16:34:21.283600       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0305 16:34:21.287750       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 16:34:21.287904       1 utils.go:199] ID: 5 GRPC request: {}
I0305 16:34:21.287928       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0305 16:34:21.288161       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}}}]}
I0305 16:34:23.073438       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:23.073476       1 utils.go:199] ID: 6 GRPC request: {}
I0305 16:34:23.073502       1 utils.go:205] ID: 6 GRPC response: {}
I0305 16:34:23.076551       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 16:34:23.076619       1 utils.go:199] ID: 7 GRPC request: {}
I0305 16:34:23.076637       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0305 16:34:23.076689       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 16:34:23.079423       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:23.079531       1 utils.go:199] ID: 8 GRPC request: {}
I0305 16:34:23.079641       1 utils.go:205] ID: 8 GRPC response: {}
I0305 16:34:23.080644       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0305 16:34:23.080722       1 utils.go:199] ID: 9 GRPC request: {}
I0305 16:34:23.080915       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0305 16:34:23.081909       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 16:34:23.081959       1 utils.go:199] ID: 10 GRPC request: {}
I0305 16:34:23.081970       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0305 16:34:23.082164       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}}}]}
I0305 16:34:23.083781       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 16:34:23.083801       1 utils.go:199] ID: 11 GRPC request: {}
I0305 16:34:23.083807       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0305 16:34:23.083935       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}}}]}
I0305 16:34:23.085227       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 16:34:23.085250       1 utils.go:199] ID: 12 GRPC request: {}
I0305 16:34:23.085256       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0305 16:34:23.085396       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}}}]}
I0305 16:34:25.547447       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 16:34:25.547573       1 utils.go:199] ID: 13 GRPC request: {}
I0305 16:34:25.547590       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0305 16:34:25.547687       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 16:34:25.554801       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:25.554874       1 utils.go:199] ID: 14 GRPC request: {}
I0305 16:34:25.554905       1 utils.go:205] ID: 14 GRPC response: {}
I0305 16:34:25.556847       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 16:34:25.556904       1 utils.go:199] ID: 15 GRPC request: {}
I0305 16:34:25.556916       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0305 16:34:25.557057       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}}}]}
I0305 16:34:26.716302       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:26.716470       1 utils.go:199] ID: 16 GRPC request: {}
I0305 16:34:26.716556       1 utils.go:205] ID: 16 GRPC response: {}
I0305 16:34:26.721198       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 16:34:26.721309       1 utils.go:199] ID: 17 GRPC request: {}
I0305 16:34:26.721384       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0305 16:34:26.721470       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 16:34:26.723067       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0305 16:34:26.723125       1 utils.go:199] ID: 18 GRPC request: {}
I0305 16:34:26.723176       1 utils.go:205] ID: 18 GRPC response: {}
I0305 16:34:26.723822       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0305 16:34:26.723894       1 utils.go:199] ID: 19 GRPC request: {}
I0305 16:34:26.724073       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0305 16:34:26.725022       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 16:34:26.725096       1 utils.go:199] ID: 20 GRPC request: {}
I0305 16:34:26.725113       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0305 16:34:26.725254       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}}}]}
I0305 16:35:26.996448       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0305 16:35:26.996507       1 utils.go:199] ID: 21 GRPC request: {}
I0305 16:35:26.996569       1 utils.go:205] ID: 21 GRPC response: {}
I0305 16:36:26.948122       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0305 16:36:26.948191       1 utils.go:199] ID: 22 GRPC request: {}
I0305 16:36:26.948457       1 utils.go:205] ID: 22 GRPC response: {}
I0305 16:37:26.947041       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0305 16:37:26.947094       1 utils.go:199] ID: 23 GRPC request: {}
I0305 16:37:26.947310       1 utils.go:205] ID: 23 GRPC response: {}
I0305 16:37:30.360552       1 utils.go:198] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 GRPC call: /csi.v1.Controller/CreateVolume
I0305 16:37:30.360960       1 utils.go:199] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7","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}}]}
I0305 16:37:30.361191       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0305 16:37:30.381378       1 omap.go:80] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0305 16:37:30.397210       1 omap.go:159] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7:283800eb-856e-4c26-a536-c6ae50842338])
I0305 16:37:30.405611       1 omap.go:159] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 set omap keys (pool="kube", namespace="", name="csi.volume.283800eb-856e-4c26-a536-c6ae50842338"): map[csi.imagename:csi-vol-283800eb-856e-4c26-a536-c6ae50842338 csi.volname:pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 csi.volume.owner:default])
I0305 16:37:30.405659       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-283800eb-856e-4c26-a536-c6ae50842338) and image name (csi-vol-283800eb-856e-4c26-a536-c6ae50842338) for request name (pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7)
I0305 16:37:30.405823       1 rbd_util.go:423] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 rbd: create kube/csi-vol-283800eb-856e-4c26-a536-c6ae50842338 size 5120M (features: [layering]) using mon 199.204.45.25
I0305 16:37:30.405911       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 setting image options on kube/csi-vol-283800eb-856e-4c26-a536-c6ae50842338
I0305 16:37:32.214074       1 controllerserver.go:763] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 created image kube/csi-vol-283800eb-856e-4c26-a536-c6ae50842338 backed for request name pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7
I0305 16:37:32.249340       1 omap.go:159] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 set omap keys (pool="kube", namespace="", name="csi.volume.283800eb-856e-4c26-a536-c6ae50842338"): map[csi.imageid:37d9641a9bf])
I0305 16:37:32.349350       1 utils.go:205] ID: 24 Req-ID: pvc-d25f83c4-b90a-40af-a0d7-5c7fb69474a7 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-283800eb-856e-4c26-a536-c6ae50842338","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-283800eb-856e-4c26-a536-c6ae50842338"}}