I0505 21:03:56.742747       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0505 21:03:56.742883       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 21:03:56.742896       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 21:03:56.742899       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 21:03:56.742902       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0505 21:03:56.742905       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0505 21:03:56.742909       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 21:03:56.742912       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 21:03:56.742915       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 21:03:56.742918       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 21:03:56.743256       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 21:03:56.743270       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 21:04:05.415635       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:05.416853       1 utils.go:199] ID: 1 GRPC request: {}
I0505 21:04:05.417007       1 utils.go:205] ID: 1 GRPC response: {}
I0505 21:04:05.419840       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 21:04:05.419883       1 utils.go:199] ID: 2 GRPC request: {}
I0505 21:04:05.419901       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0505 21:04:05.420128       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 21:04:05.422075       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:05.422131       1 utils.go:199] ID: 3 GRPC request: {}
I0505 21:04:05.422173       1 utils.go:205] ID: 3 GRPC response: {}
I0505 21:04:05.422798       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 21:04:05.422937       1 utils.go:199] ID: 4 GRPC request: {}
I0505 21:04:05.423204       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 21:04:05.424083       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 21:04:05.424261       1 utils.go:199] ID: 5 GRPC request: {}
I0505 21:04:05.424276       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0505 21:04:05.424446       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 21:04:08.783877       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:08.783938       1 utils.go:199] ID: 6 GRPC request: {}
I0505 21:04:08.783959       1 utils.go:205] ID: 6 GRPC response: {}
I0505 21:04:08.787185       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 21:04:08.787229       1 utils.go:199] ID: 7 GRPC request: {}
I0505 21:04:08.787236       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0505 21:04:08.787255       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 21:04:08.789060       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:08.789282       1 utils.go:199] ID: 8 GRPC request: {}
I0505 21:04:08.789292       1 utils.go:205] ID: 8 GRPC response: {}
I0505 21:04:08.789839       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 21:04:08.789865       1 utils.go:199] ID: 9 GRPC request: {}
I0505 21:04:08.789942       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 21:04:08.790742       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 21:04:08.790779       1 utils.go:199] ID: 10 GRPC request: {}
I0505 21:04:08.790795       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0505 21:04:08.790882       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 21:04:08.791437       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 21:04:08.791453       1 utils.go:199] ID: 11 GRPC request: {}
I0505 21:04:08.791459       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0505 21:04:08.791541       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 21:04:08.792709       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 21:04:08.792733       1 utils.go:199] ID: 12 GRPC request: {}
I0505 21:04:08.792742       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0505 21:04:08.792844       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 21:04:10.056608       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 21:04:10.056650       1 utils.go:199] ID: 13 GRPC request: {}
I0505 21:04:10.056662       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0505 21:04:10.056690       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 21:04:10.061408       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:10.061443       1 utils.go:199] ID: 14 GRPC request: {}
I0505 21:04:10.061456       1 utils.go:205] ID: 14 GRPC response: {}
I0505 21:04:10.061920       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 21:04:10.061955       1 utils.go:199] ID: 15 GRPC request: {}
I0505 21:04:10.061966       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0505 21:04:10.062065       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 21:04:11.442183       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:11.442245       1 utils.go:199] ID: 16 GRPC request: {}
I0505 21:04:11.442268       1 utils.go:205] ID: 16 GRPC response: {}
I0505 21:04:11.444692       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 21:04:11.444738       1 utils.go:199] ID: 17 GRPC request: {}
I0505 21:04:11.444749       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0505 21:04:11.444783       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0505 21:04:11.447952       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0505 21:04:11.448003       1 utils.go:199] ID: 18 GRPC request: {}
I0505 21:04:11.448029       1 utils.go:205] ID: 18 GRPC response: {}
I0505 21:04:11.448575       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 21:04:11.448624       1 utils.go:199] ID: 19 GRPC request: {}
I0505 21:04:11.448748       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 21:04:11.449468       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 21:04:11.449497       1 utils.go:199] ID: 20 GRPC request: {}
I0505 21:04:11.449510       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0505 21:04:11.449608       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 21:05:11.647411       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0505 21:05:11.647477       1 utils.go:199] ID: 21 GRPC request: {}
I0505 21:05:11.647507       1 utils.go:205] ID: 21 GRPC response: {}
I0505 21:06:11.591460       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 21:06:11.591533       1 utils.go:199] ID: 22 GRPC request: {}
I0505 21:06:11.591557       1 utils.go:205] ID: 22 GRPC response: {}
I0505 21:07:11.590523       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0505 21:07:11.590562       1 utils.go:199] ID: 23 GRPC request: {}
I0505 21:07:11.590575       1 utils.go:205] ID: 23 GRPC response: {}
I0505 21:07:13.575207       1 utils.go:198] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 GRPC call: /csi.v1.Controller/CreateVolume
I0505 21:07:13.575428       1 utils.go:199] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67","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 21:07:13.575610       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0505 21:07:13.596167       1 omap.go:80] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 21:07:13.614689       1 omap.go:159] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67:ca0f1168-a036-4e83-b0ad-22f0a5bd4b95])
I0505 21:07:13.622107       1 omap.go:159] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 set omap keys (pool="kube", namespace="", name="csi.volume.ca0f1168-a036-4e83-b0ad-22f0a5bd4b95"): map[csi.imagename:csi-vol-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95 csi.volname:pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 csi.volume.owner:default])
I0505 21:07:13.622242       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95) and image name (csi-vol-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95) for request name (pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67)
I0505 21:07:13.622412       1 rbd_util.go:423] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 rbd: create kube/csi-vol-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95 size 5120M (features: [layering]) using mon 199.204.45.144
I0505 21:07:13.622515       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 setting image options on kube/csi-vol-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95
I0505 21:07:15.618896       1 controllerserver.go:763] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 created image kube/csi-vol-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95 backed for request name pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67
I0505 21:07:15.659285       1 omap.go:159] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 set omap keys (pool="kube", namespace="", name="csi.volume.ca0f1168-a036-4e83-b0ad-22f0a5bd4b95"): map[csi.imageid:37d75197eed9])
I0505 21:07:15.752586       1 utils.go:205] ID: 24 Req-ID: pvc-6c9bf7c2-e2bd-41a3-b6ab-724a89f5dc67 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ca0f1168-a036-4e83-b0ad-22f0a5bd4b95"}}