I0220 21:40:00.439326       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0220 21:40:00.439449       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0220 21:40:00.439465       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0220 21:40:00.439469       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0220 21:40:00.439472       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0220 21:40:00.439474       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0220 21:40:00.439478       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0220 21:40:00.439481       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0220 21:40:00.439483       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0220 21:40:00.439486       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0220 21:40:00.439756       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0220 21:40:00.439856       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0220 21:40:04.334522       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:04.336570       1 utils.go:199] ID: 1 GRPC request: {}
I0220 21:40:04.336658       1 utils.go:205] ID: 1 GRPC response: {}
I0220 21:40:04.340453       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0220 21:40:04.340644       1 utils.go:199] ID: 2 GRPC request: {}
I0220 21:40:04.340699       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0220 21:40:04.340792       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0220 21:40:04.342410       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:04.342444       1 utils.go:199] ID: 3 GRPC request: {}
I0220 21:40:04.342467       1 utils.go:205] ID: 3 GRPC response: {}
I0220 21:40:04.342818       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0220 21:40:04.342880       1 utils.go:199] ID: 4 GRPC request: {}
I0220 21:40:04.343039       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0220 21:40:04.344053       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0220 21:40:04.344099       1 utils.go:199] ID: 5 GRPC request: {}
I0220 21:40:04.344113       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0220 21:40:04.344224       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}}}]}
I0220 21:40:07.458156       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:07.458214       1 utils.go:199] ID: 6 GRPC request: {}
I0220 21:40:07.458296       1 utils.go:205] ID: 6 GRPC response: {}
I0220 21:40:07.460383       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0220 21:40:07.460412       1 utils.go:199] ID: 7 GRPC request: {}
I0220 21:40:07.460421       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0220 21:40:07.460448       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0220 21:40:07.463080       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:07.463096       1 utils.go:199] ID: 8 GRPC request: {}
I0220 21:40:07.463104       1 utils.go:205] ID: 8 GRPC response: {}
I0220 21:40:07.463607       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0220 21:40:07.463623       1 utils.go:199] ID: 9 GRPC request: {}
I0220 21:40:07.463746       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0220 21:40:07.465006       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0220 21:40:07.465023       1 utils.go:199] ID: 10 GRPC request: {}
I0220 21:40:07.465033       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0220 21:40:07.465097       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}}}]}
I0220 21:40:07.465599       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0220 21:40:07.465613       1 utils.go:199] ID: 11 GRPC request: {}
I0220 21:40:07.465618       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0220 21:40:07.465700       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}}}]}
I0220 21:40:07.466148       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0220 21:40:07.466163       1 utils.go:199] ID: 12 GRPC request: {}
I0220 21:40:07.466168       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0220 21:40:07.466245       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}}}]}
I0220 21:40:09.373374       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0220 21:40:09.373432       1 utils.go:199] ID: 13 GRPC request: {}
I0220 21:40:09.373447       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0220 21:40:09.373490       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0220 21:40:09.377550       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:09.377585       1 utils.go:199] ID: 14 GRPC request: {}
I0220 21:40:09.377605       1 utils.go:205] ID: 14 GRPC response: {}
I0220 21:40:09.378326       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0220 21:40:09.378350       1 utils.go:199] ID: 15 GRPC request: {}
I0220 21:40:09.378358       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0220 21:40:09.378446       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}}}]}
I0220 21:40:11.154776       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:11.154856       1 utils.go:199] ID: 16 GRPC request: {}
I0220 21:40:11.154903       1 utils.go:205] ID: 16 GRPC response: {}
I0220 21:40:11.159070       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0220 21:40:11.159097       1 utils.go:199] ID: 17 GRPC request: {}
I0220 21:40:11.159109       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0220 21:40:11.159146       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0220 21:40:11.163246       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0220 21:40:11.163301       1 utils.go:199] ID: 18 GRPC request: {}
I0220 21:40:11.163328       1 utils.go:205] ID: 18 GRPC response: {}
I0220 21:40:11.163834       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0220 21:40:11.163878       1 utils.go:199] ID: 19 GRPC request: {}
I0220 21:40:11.163999       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0220 21:40:11.164882       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0220 21:40:11.164958       1 utils.go:199] ID: 20 GRPC request: {}
I0220 21:40:11.164990       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0220 21:40:11.165182       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}}}]}
I0220 21:41:11.413295       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0220 21:41:11.413336       1 utils.go:199] ID: 21 GRPC request: {}
I0220 21:41:11.413352       1 utils.go:205] ID: 21 GRPC response: {}
I0220 21:42:11.353198       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0220 21:42:11.353287       1 utils.go:199] ID: 22 GRPC request: {}
I0220 21:42:11.353316       1 utils.go:205] ID: 22 GRPC response: {}
I0220 21:43:11.411698       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0220 21:43:11.411804       1 utils.go:199] ID: 23 GRPC request: {}
I0220 21:43:11.411852       1 utils.go:205] ID: 23 GRPC response: {}
I0220 21:43:14.766205       1 utils.go:198] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a GRPC call: /csi.v1.Controller/CreateVolume
I0220 21:43:14.766722       1 utils.go:199] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-20277f95-8433-4de5-a740-3a94a0b1924a","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-20277f95-8433-4de5-a740-3a94a0b1924a","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}}]}
I0220 21:43:14.767073       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a setting disableInUseChecks: false image features: [layering] mounter: rbd
E0220 21:43:14.785930       1 omap.go:80] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0220 21:43:14.803249       1 omap.go:159] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-20277f95-8433-4de5-a740-3a94a0b1924a:239382ca-6a25-4cda-bb03-e814b2c6fa3a])
I0220 21:43:14.810337       1 omap.go:159] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a set omap keys (pool="kube", namespace="", name="csi.volume.239382ca-6a25-4cda-bb03-e814b2c6fa3a"): map[csi.imagename:csi-vol-239382ca-6a25-4cda-bb03-e814b2c6fa3a csi.volname:pvc-20277f95-8433-4de5-a740-3a94a0b1924a csi.volume.owner:default])
I0220 21:43:14.810425       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-239382ca-6a25-4cda-bb03-e814b2c6fa3a) and image name (csi-vol-239382ca-6a25-4cda-bb03-e814b2c6fa3a) for request name (pvc-20277f95-8433-4de5-a740-3a94a0b1924a)
I0220 21:43:14.810555       1 rbd_util.go:423] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a rbd: create kube/csi-vol-239382ca-6a25-4cda-bb03-e814b2c6fa3a size 5120M (features: [layering]) using mon 199.204.45.140
I0220 21:43:14.810655       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a setting image options on kube/csi-vol-239382ca-6a25-4cda-bb03-e814b2c6fa3a
I0220 21:43:16.389597       1 controllerserver.go:763] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a created image kube/csi-vol-239382ca-6a25-4cda-bb03-e814b2c6fa3a backed for request name pvc-20277f95-8433-4de5-a740-3a94a0b1924a
I0220 21:43:16.426098       1 omap.go:159] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a set omap keys (pool="kube", namespace="", name="csi.volume.239382ca-6a25-4cda-bb03-e814b2c6fa3a"): map[csi.imageid:37d999b4006f])
I0220 21:43:16.510002       1 utils.go:205] ID: 24 Req-ID: pvc-20277f95-8433-4de5-a740-3a94a0b1924a GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-239382ca-6a25-4cda-bb03-e814b2c6fa3a","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-239382ca-6a25-4cda-bb03-e814b2c6fa3a"}}