I0430 06:02:15.036026       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0430 06:02:15.036570       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0430 06:02:15.036594       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0430 06:02:15.036598       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0430 06:02:15.036603       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0430 06:02:15.036607       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0430 06:02:15.036612       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0430 06:02:15.036620       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0430 06:02:15.036626       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0430 06:02:15.036630       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0430 06:02:15.037584       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0430 06:02:15.037695       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0430 06:02:20.765065       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:20.766597       1 utils.go:199] ID: 1 GRPC request: {}
I0430 06:02:20.766707       1 utils.go:205] ID: 1 GRPC response: {}
I0430 06:02:20.769272       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0430 06:02:20.769370       1 utils.go:199] ID: 2 GRPC request: {}
I0430 06:02:20.769384       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0430 06:02:20.769432       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0430 06:02:20.770801       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:20.770897       1 utils.go:199] ID: 3 GRPC request: {}
I0430 06:02:20.770918       1 utils.go:205] ID: 3 GRPC response: {}
I0430 06:02:20.771291       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0430 06:02:20.771355       1 utils.go:199] ID: 4 GRPC request: {}
I0430 06:02:20.771508       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0430 06:02:20.772236       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0430 06:02:20.772287       1 utils.go:199] ID: 5 GRPC request: {}
I0430 06:02:20.772301       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0430 06:02:20.772429       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}}}]}
I0430 06:02:25.072847       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:25.072900       1 utils.go:199] ID: 6 GRPC request: {}
I0430 06:02:25.072999       1 utils.go:205] ID: 6 GRPC response: {}
I0430 06:02:25.076124       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0430 06:02:25.076167       1 utils.go:199] ID: 7 GRPC request: {}
I0430 06:02:25.076176       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0430 06:02:25.076201       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0430 06:02:25.078373       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:25.078408       1 utils.go:199] ID: 8 GRPC request: {}
I0430 06:02:25.078430       1 utils.go:205] ID: 8 GRPC response: {}
I0430 06:02:25.079231       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0430 06:02:25.079261       1 utils.go:199] ID: 9 GRPC request: {}
I0430 06:02:25.079360       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0430 06:02:25.079955       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0430 06:02:25.079973       1 utils.go:199] ID: 10 GRPC request: {}
I0430 06:02:25.079980       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0430 06:02:25.080041       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}}}]}
I0430 06:02:25.080492       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0430 06:02:25.080516       1 utils.go:199] ID: 11 GRPC request: {}
I0430 06:02:25.080524       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0430 06:02:25.080606       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}}}]}
I0430 06:02:25.081044       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0430 06:02:25.081077       1 utils.go:199] ID: 12 GRPC request: {}
I0430 06:02:25.081093       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0430 06:02:25.081195       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}}}]}
I0430 06:02:26.814588       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0430 06:02:26.814630       1 utils.go:199] ID: 13 GRPC request: {}
I0430 06:02:26.814637       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0430 06:02:26.814659       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0430 06:02:26.817858       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:26.817930       1 utils.go:199] ID: 14 GRPC request: {}
I0430 06:02:26.817953       1 utils.go:205] ID: 14 GRPC response: {}
I0430 06:02:26.818498       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0430 06:02:26.818519       1 utils.go:199] ID: 15 GRPC request: {}
I0430 06:02:26.818527       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0430 06:02:26.818681       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}}}]}
I0430 06:02:28.056572       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:28.056601       1 utils.go:199] ID: 16 GRPC request: {}
I0430 06:02:28.056622       1 utils.go:205] ID: 16 GRPC response: {}
I0430 06:02:28.061569       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0430 06:02:28.061589       1 utils.go:199] ID: 17 GRPC request: {}
I0430 06:02:28.061595       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0430 06:02:28.061618       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0430 06:02:28.063169       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0430 06:02:28.063208       1 utils.go:199] ID: 18 GRPC request: {}
I0430 06:02:28.063251       1 utils.go:205] ID: 18 GRPC response: {}
I0430 06:02:28.063455       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0430 06:02:28.063488       1 utils.go:199] ID: 19 GRPC request: {}
I0430 06:02:28.063610       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0430 06:02:28.064028       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0430 06:02:28.064064       1 utils.go:199] ID: 20 GRPC request: {}
I0430 06:02:28.064081       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0430 06:02:28.064196       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}}}]}
I0430 06:03:28.235726       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0430 06:03:28.235875       1 utils.go:199] ID: 21 GRPC request: {}
I0430 06:03:28.236094       1 utils.go:205] ID: 21 GRPC response: {}
I0430 06:04:28.242479       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0430 06:04:28.242751       1 utils.go:199] ID: 22 GRPC request: {}
I0430 06:04:28.242855       1 utils.go:205] ID: 22 GRPC response: {}
I0430 06:05:28.265086       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0430 06:05:28.265179       1 utils.go:199] ID: 23 GRPC request: {}
I0430 06:05:28.265232       1 utils.go:205] ID: 23 GRPC response: {}
I0430 06:05:28.492507       1 utils.go:198] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f GRPC call: /csi.v1.Controller/CreateVolume
I0430 06:05:28.493240       1 utils.go:199] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f","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}}]}
I0430 06:05:28.493995       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f setting disableInUseChecks: false image features: [layering] mounter: rbd
E0430 06:05:28.515253       1 omap.go:80] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0430 06:05:28.530622       1 omap.go:159] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f:a6fac730-f487-480c-aac2-ef9f24466098])
I0430 06:05:28.539156       1 omap.go:159] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f set omap keys (pool="kube", namespace="", name="csi.volume.a6fac730-f487-480c-aac2-ef9f24466098"): map[csi.imagename:csi-vol-a6fac730-f487-480c-aac2-ef9f24466098 csi.volname:pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f csi.volume.owner:default])
I0430 06:05:28.539214       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-a6fac730-f487-480c-aac2-ef9f24466098) and image name (csi-vol-a6fac730-f487-480c-aac2-ef9f24466098) for request name (pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f)
I0430 06:05:28.539393       1 rbd_util.go:423] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f rbd: create kube/csi-vol-a6fac730-f487-480c-aac2-ef9f24466098 size 5120M (features: [layering]) using mon 199.204.45.153
I0430 06:05:28.539480       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f setting image options on kube/csi-vol-a6fac730-f487-480c-aac2-ef9f24466098
I0430 06:05:30.309447       1 controllerserver.go:763] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f created image kube/csi-vol-a6fac730-f487-480c-aac2-ef9f24466098 backed for request name pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f
I0430 06:05:30.357261       1 omap.go:159] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f set omap keys (pool="kube", namespace="", name="csi.volume.a6fac730-f487-480c-aac2-ef9f24466098"): map[csi.imageid:37d98683b039])
I0430 06:05:30.451448       1 utils.go:205] ID: 24 Req-ID: pvc-d1a1ea7c-8bf6-44df-a2fe-bb86e054976f GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-a6fac730-f487-480c-aac2-ef9f24466098","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-a6fac730-f487-480c-aac2-ef9f24466098"}}