I0428 13:57:15.544630       1 cephcsi.go:171] Driver version: v3.5.1 and Git version: 00f88e58a0b08be3d7ea009a0cfde8dca23021bf
E0428 13:57:15.544887       1 cephcsi.go:187] Failed to get the PID limit, can not reconfigure: could not find a cgroup for 'pids'
I0428 13:57:15.544943       1 cephcsi.go:220] Starting driver type: rbd with name: rbd.csi.ceph.com
I0428 13:57:15.545280       1 driver.go:94] Enabling controller service capability: CREATE_DELETE_VOLUME
I0428 13:57:15.545297       1 driver.go:94] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0428 13:57:15.545304       1 driver.go:94] Enabling controller service capability: CLONE_VOLUME
I0428 13:57:15.545312       1 driver.go:94] Enabling controller service capability: EXPAND_VOLUME
I0428 13:57:15.545321       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_WRITER
I0428 13:57:15.545332       1 driver.go:106] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0428 13:57:15.545339       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0428 13:57:15.545345       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0428 13:57:15.545518       1 server.go:126] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0428 13:57:15.545522       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0428 13:57:15.635740       1 utils.go:191] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0428 13:57:15.639482       1 utils.go:195] ID: 1 GRPC request: {}
I0428 13:57:15.639641       1 utils.go:202] ID: 1 GRPC response: {}
I0428 13:57:15.641070       1 utils.go:191] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0428 13:57:15.641159       1 utils.go:195] ID: 2 GRPC request: {}
I0428 13:57:15.641176       1 identityserver-default.go:38] ID: 2 Using default GetPluginInfo
I0428 13:57:15.641252       1 utils.go:202] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0428 13:57:15.641940       1 utils.go:191] ID: 3 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0428 13:57:15.641976       1 utils.go:195] ID: 3 GRPC request: {}
I0428 13:57:15.642203       1 utils.go:202] ID: 3 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0428 13:57:15.643059       1 utils.go:191] ID: 4 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:15.643114       1 utils.go:195] ID: 4 GRPC request: {}
I0428 13:57:15.643126       1 controllerserver-default.go:74] ID: 4 Using default ControllerGetCapabilities
I0428 13:57:15.643262       1 utils.go:202] ID: 4 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:57:15.644145       1 utils.go:191] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:15.644200       1 utils.go:195] ID: 5 GRPC request: {}
I0428 13:57:15.644215       1 controllerserver-default.go:74] ID: 5 Using default ControllerGetCapabilities
I0428 13:57:15.644363       1 utils.go:202] ID: 5 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:57:15.684256       1 utils.go:191] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0428 13:57:15.684366       1 utils.go:195] ID: 6 GRPC request: {}
I0428 13:57:15.684395       1 utils.go:202] ID: 6 GRPC response: {}
I0428 13:57:15.685129       1 utils.go:191] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0428 13:57:15.685170       1 utils.go:195] ID: 7 GRPC request: {}
I0428 13:57:15.685206       1 identityserver-default.go:38] ID: 7 Using default GetPluginInfo
I0428 13:57:15.685242       1 utils.go:202] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0428 13:57:15.687425       1 utils.go:191] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0428 13:57:15.687512       1 utils.go:195] ID: 8 GRPC request: {}
I0428 13:57:15.687536       1 utils.go:202] ID: 8 GRPC response: {}
I0428 13:57:15.687924       1 utils.go:191] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0428 13:57:15.687973       1 utils.go:195] ID: 9 GRPC request: {}
I0428 13:57:15.688118       1 utils.go:202] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0428 13:57:15.688841       1 utils.go:191] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:15.688913       1 utils.go:195] ID: 10 GRPC request: {}
I0428 13:57:15.688927       1 controllerserver-default.go:74] ID: 10 Using default ControllerGetCapabilities
I0428 13:57:15.689023       1 utils.go:202] ID: 10 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:57:15.728511       1 utils.go:191] ID: 11 GRPC call: /csi.v1.Identity/GetPluginInfo
I0428 13:57:15.728640       1 utils.go:195] ID: 11 GRPC request: {}
I0428 13:57:15.728655       1 identityserver-default.go:38] ID: 11 Using default GetPluginInfo
I0428 13:57:15.728755       1 utils.go:202] ID: 11 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0428 13:57:15.730398       1 utils.go:191] ID: 12 GRPC call: /csi.v1.Identity/Probe
I0428 13:57:15.730512       1 utils.go:195] ID: 12 GRPC request: {}
I0428 13:57:15.730590       1 utils.go:202] ID: 12 GRPC response: {}
I0428 13:57:15.731310       1 utils.go:191] ID: 13 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:15.731394       1 utils.go:195] ID: 13 GRPC request: {}
I0428 13:57:15.731415       1 controllerserver-default.go:74] ID: 13 Using default ControllerGetCapabilities
I0428 13:57:15.731665       1 utils.go:202] ID: 13 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:57:16.604116       1 utils.go:191] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0428 13:57:16.604170       1 utils.go:195] ID: 14 GRPC request: {}
I0428 13:57:16.604189       1 utils.go:202] ID: 14 GRPC response: {}
I0428 13:57:16.605403       1 utils.go:191] ID: 15 GRPC call: /csi.v1.Identity/GetPluginInfo
I0428 13:57:16.605437       1 utils.go:195] ID: 15 GRPC request: {}
I0428 13:57:16.605447       1 identityserver-default.go:38] ID: 15 Using default GetPluginInfo
I0428 13:57:16.605473       1 utils.go:202] ID: 15 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0428 13:57:16.609981       1 utils.go:191] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0428 13:57:16.610056       1 utils.go:195] ID: 16 GRPC request: {}
I0428 13:57:16.610079       1 utils.go:202] ID: 16 GRPC response: {}
I0428 13:57:16.610698       1 utils.go:191] ID: 17 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0428 13:57:16.610751       1 utils.go:195] ID: 17 GRPC request: {}
I0428 13:57:16.610904       1 utils.go:202] ID: 17 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0428 13:57:16.612259       1 utils.go:191] ID: 18 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:16.612287       1 utils.go:195] ID: 18 GRPC request: {}
I0428 13:57:16.612303       1 controllerserver-default.go:74] ID: 18 Using default ControllerGetCapabilities
I0428 13:57:16.612388       1 utils.go:202] ID: 18 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:58:17.949361       1 utils.go:191] ID: 19 GRPC call: /csi.v1.Identity/Probe
I0428 13:58:17.949459       1 utils.go:195] ID: 19 GRPC request: {}
I0428 13:58:17.949508       1 utils.go:202] ID: 19 GRPC response: {}
I0428 13:59:17.950440       1 utils.go:191] ID: 20 GRPC call: /csi.v1.Identity/Probe
I0428 13:59:17.950611       1 utils.go:195] ID: 20 GRPC request: {}
I0428 13:59:17.950643       1 utils.go:202] ID: 20 GRPC response: {}
I0428 14:00:17.951096       1 utils.go:191] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0428 14:00:17.951228       1 utils.go:195] ID: 21 GRPC request: {}
I0428 14:00:17.951288       1 utils.go:202] ID: 21 GRPC response: {}
I0428 14:00:26.250030       1 utils.go:191] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 GRPC call: /csi.v1.Controller/CreateVolume
I0428 14:00:26.250434       1 utils.go:195] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","pool":"kube","thickProvision":"false"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0428 14:00:26.250713       1 rbd_util.go:1296] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 setting disableInUseChecks: false image features: [layering] mounter: rbd
W0428 14:00:26.250729       1 controllerserver.go:1555] thick-provisioning is deprecated and will be removed in a future release
E0428 14:00:26.280409       1 omap.go:78] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0428 14:00:26.300420       1 omap.go:155] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1:9b80465a-430a-11f1-8c65-96855aa3d6b4])
I0428 14:00:26.307333       1 omap.go:155] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 set omap keys (pool="kube", namespace="", name="csi.volume.9b80465a-430a-11f1-8c65-96855aa3d6b4"): map[csi.imagename:csi-vol-9b80465a-430a-11f1-8c65-96855aa3d6b4 csi.volname:pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 csi.volume.owner:default])
I0428 14:00:26.307382       1 rbd_journal.go:482] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9b80465a-430a-11f1-8c65-96855aa3d6b4) and image name (csi-vol-9b80465a-430a-11f1-8c65-96855aa3d6b4) for request name (pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1)
I0428 14:00:26.307551       1 rbd_util.go:352] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 rbd: create kube/csi-vol-9b80465a-430a-11f1-8c65-96855aa3d6b4 size 5120M (features: [layering]) using mon 199.19.213.134
I0428 14:00:27.877754       1 controllerserver.go:666] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 created image kube/csi-vol-9b80465a-430a-11f1-8c65-96855aa3d6b4 backed for request name pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1
I0428 14:00:27.923908       1 omap.go:155] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 set omap keys (pool="kube", namespace="", name="csi.volume.9b80465a-430a-11f1-8c65-96855aa3d6b4"): map[csi.imageid:37d73910128c])
I0428 14:00:27.924535       1 utils.go:202] ID: 22 Req-ID: pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-40902cb5-6b4f-43d9-bbdd-2f59c6892ca1","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-9b80465a-430a-11f1-8c65-96855aa3d6b4","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-9b80465a-430a-11f1-8c65-96855aa3d6b4"}}
I0428 14:01:17.948169       1 utils.go:191] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0428 14:01:17.948396       1 utils.go:195] ID: 23 GRPC request: {}
I0428 14:01:17.948453       1 utils.go:202] ID: 23 GRPC response: {}