I0222 17:53:11.867059       1 cephcsi.go:171] Driver version: v3.5.1 and Git version: 00f88e58a0b08be3d7ea009a0cfde8dca23021bf
E0222 17:53:11.867298       1 cephcsi.go:187] Failed to get the PID limit, can not reconfigure: could not find a cgroup for 'pids'
I0222 17:53:11.867359       1 cephcsi.go:220] Starting driver type: rbd with name: rbd.csi.ceph.com
I0222 17:53:11.867670       1 driver.go:94] Enabling controller service capability: CREATE_DELETE_VOLUME
I0222 17:53:11.867690       1 driver.go:94] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0222 17:53:11.867698       1 driver.go:94] Enabling controller service capability: CLONE_VOLUME
I0222 17:53:11.867706       1 driver.go:94] Enabling controller service capability: EXPAND_VOLUME
I0222 17:53:11.867716       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_WRITER
I0222 17:53:11.867724       1 driver.go:106] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0222 17:53:11.867739       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0222 17:53:11.867747       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0222 17:53:11.867965       1 server.go:126] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0222 17:53:11.867997       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0222 17:53:12.038707       1 utils.go:191] ID: 1 GRPC call: /csi.v1.Identity/GetPluginInfo
I0222 17:53:12.041282       1 utils.go:195] ID: 1 GRPC request: {}
I0222 17:53:12.041351       1 identityserver-default.go:38] ID: 1 Using default GetPluginInfo
I0222 17:53:12.041467       1 utils.go:202] ID: 1 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0222 17:53:12.043759       1 utils.go:191] ID: 2 GRPC call: /csi.v1.Identity/Probe
I0222 17:53:12.043849       1 utils.go:195] ID: 2 GRPC request: {}
I0222 17:53:12.043986       1 utils.go:202] ID: 2 GRPC response: {}
I0222 17:53:12.044724       1 utils.go:191] ID: 3 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0222 17:53:12.044817       1 utils.go:195] ID: 3 GRPC request: {}
I0222 17:53:12.044831       1 controllerserver-default.go:74] ID: 3 Using default ControllerGetCapabilities
I0222 17:53:12.045018       1 utils.go:202] ID: 3 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0222 17:53:12.179643       1 utils.go:191] ID: 4 GRPC call: /csi.v1.Identity/Probe
I0222 17:53:12.179680       1 utils.go:195] ID: 4 GRPC request: {}
I0222 17:53:12.179697       1 utils.go:202] ID: 4 GRPC response: {}
I0222 17:53:12.180960       1 utils.go:191] ID: 5 GRPC call: /csi.v1.Identity/GetPluginInfo
I0222 17:53:12.180988       1 utils.go:195] ID: 5 GRPC request: {}
I0222 17:53:12.180994       1 identityserver-default.go:38] ID: 5 Using default GetPluginInfo
I0222 17:53:12.181015       1 utils.go:202] ID: 5 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0222 17:53:12.183094       1 utils.go:191] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0222 17:53:12.183151       1 utils.go:195] ID: 6 GRPC request: {}
I0222 17:53:12.183172       1 utils.go:202] ID: 6 GRPC response: {}
I0222 17:53:12.184093       1 utils.go:191] ID: 7 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0222 17:53:12.184195       1 utils.go:195] ID: 7 GRPC request: {}
I0222 17:53:12.184347       1 utils.go:202] ID: 7 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0222 17:53:12.185505       1 utils.go:191] ID: 8 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0222 17:53:12.185548       1 utils.go:195] ID: 8 GRPC request: {}
I0222 17:53:12.185563       1 controllerserver-default.go:74] ID: 8 Using default ControllerGetCapabilities
I0222 17:53:12.185660       1 utils.go:202] ID: 8 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0222 17:53:12.439900       1 utils.go:191] ID: 9 GRPC call: /csi.v1.Identity/Probe
I0222 17:53:12.440019       1 utils.go:195] ID: 9 GRPC request: {}
I0222 17:53:12.440094       1 utils.go:202] ID: 9 GRPC response: {}
I0222 17:53:12.442268       1 utils.go:191] ID: 10 GRPC call: /csi.v1.Identity/GetPluginInfo
I0222 17:53:12.442346       1 utils.go:195] ID: 10 GRPC request: {}
I0222 17:53:12.442376       1 identityserver-default.go:38] ID: 10 Using default GetPluginInfo
I0222 17:53:12.442439       1 utils.go:202] ID: 10 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0222 17:53:12.443554       1 utils.go:191] ID: 11 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0222 17:53:12.443665       1 utils.go:195] ID: 11 GRPC request: {}
I0222 17:53:12.443893       1 utils.go:202] ID: 11 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0222 17:53:12.445199       1 utils.go:191] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0222 17:53:12.445254       1 utils.go:195] ID: 12 GRPC request: {}
I0222 17:53:12.445276       1 controllerserver-default.go:74] ID: 12 Using default ControllerGetCapabilities
I0222 17:53:12.445377       1 utils.go:202] ID: 12 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0222 17:53:12.446147       1 utils.go:191] ID: 13 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0222 17:53:12.446309       1 utils.go:195] ID: 13 GRPC request: {}
I0222 17:53:12.446353       1 controllerserver-default.go:74] ID: 13 Using default ControllerGetCapabilities
I0222 17:53:12.446541       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}}}]}
I0222 17:53:12.764498       1 utils.go:191] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0222 17:53:12.764557       1 utils.go:195] ID: 14 GRPC request: {}
I0222 17:53:12.764574       1 utils.go:202] ID: 14 GRPC response: {}
I0222 17:53:12.771893       1 utils.go:191] ID: 15 GRPC call: /csi.v1.Identity/GetPluginInfo
I0222 17:53:12.772024       1 utils.go:195] ID: 15 GRPC request: {}
I0222 17:53:12.772042       1 identityserver-default.go:38] ID: 15 Using default GetPluginInfo
I0222 17:53:12.772222       1 utils.go:202] ID: 15 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0222 17:53:12.774618       1 utils.go:191] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0222 17:53:12.774672       1 utils.go:195] ID: 16 GRPC request: {}
I0222 17:53:12.774693       1 utils.go:202] ID: 16 GRPC response: {}
I0222 17:53:12.774976       1 utils.go:191] ID: 17 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0222 17:53:12.775009       1 utils.go:195] ID: 17 GRPC request: {}
I0222 17:53:12.775089       1 utils.go:202] ID: 17 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0222 17:53:12.775881       1 utils.go:191] ID: 18 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0222 17:53:12.775907       1 utils.go:195] ID: 18 GRPC request: {}
I0222 17:53:12.775915       1 controllerserver-default.go:74] ID: 18 Using default ControllerGetCapabilities
I0222 17:53:12.775969       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}}}]}
I0222 17:54:12.489845       1 utils.go:191] ID: 19 GRPC call: /csi.v1.Identity/Probe
I0222 17:54:12.489919       1 utils.go:195] ID: 19 GRPC request: {}
I0222 17:54:12.489942       1 utils.go:202] ID: 19 GRPC response: {}
I0222 17:55:12.491269       1 utils.go:191] ID: 20 GRPC call: /csi.v1.Identity/Probe
I0222 17:55:12.491419       1 utils.go:195] ID: 20 GRPC request: {}
I0222 17:55:12.491486       1 utils.go:202] ID: 20 GRPC response: {}
I0222 17:56:02.426939       1 utils.go:191] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 GRPC call: /csi.v1.Controller/CreateVolume
I0222 17:56:02.427466       1 utils.go:195] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517","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}}]}
I0222 17:56:02.427746       1 rbd_util.go:1296] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 setting disableInUseChecks: false image features: [layering] mounter: rbd
W0222 17:56:02.427807       1 controllerserver.go:1555] thick-provisioning is deprecated and will be removed in a future release
E0222 17:56:02.447821       1 omap.go:78] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0222 17:56:02.464572       1 omap.go:155] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517:c07681aa-1017-11f1-a403-2e190c541d4f])
I0222 17:56:02.472538       1 omap.go:155] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 set omap keys (pool="kube", namespace="", name="csi.volume.c07681aa-1017-11f1-a403-2e190c541d4f"): map[csi.imagename:csi-vol-c07681aa-1017-11f1-a403-2e190c541d4f csi.volname:pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 csi.volume.owner:default])
I0222 17:56:02.472637       1 rbd_journal.go:482] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c07681aa-1017-11f1-a403-2e190c541d4f) and image name (csi-vol-c07681aa-1017-11f1-a403-2e190c541d4f) for request name (pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517)
I0222 17:56:02.472763       1 rbd_util.go:352] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 rbd: create kube/csi-vol-c07681aa-1017-11f1-a403-2e190c541d4f size 5120M (features: [layering]) using mon 162.253.55.43
I0222 17:56:04.179567       1 controllerserver.go:666] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 created image kube/csi-vol-c07681aa-1017-11f1-a403-2e190c541d4f backed for request name pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517
I0222 17:56:04.212972       1 omap.go:155] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 set omap keys (pool="kube", namespace="", name="csi.volume.c07681aa-1017-11f1-a403-2e190c541d4f"): map[csi.imageid:37d946fac5])
I0222 17:56:04.213225       1 utils.go:202] ID: 21 Req-ID: pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-c07681aa-1017-11f1-a403-2e190c541d4f","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c07681aa-1017-11f1-a403-2e190c541d4f"}}
I0222 17:56:12.488469       1 utils.go:191] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0222 17:56:12.488537       1 utils.go:195] ID: 22 GRPC request: {}
I0222 17:56:12.488563       1 utils.go:202] ID: 22 GRPC response: {}