I0505 17:03:45.950991       1 cephcsi.go:171] Driver version: v3.5.1 and Git version: 00f88e58a0b08be3d7ea009a0cfde8dca23021bf
E0505 17:03:45.951164       1 cephcsi.go:187] Failed to get the PID limit, can not reconfigure: could not find a cgroup for 'pids'
I0505 17:03:45.951198       1 cephcsi.go:220] Starting driver type: rbd with name: rbd.csi.ceph.com
I0505 17:03:45.951447       1 driver.go:94] Enabling controller service capability: CREATE_DELETE_VOLUME
I0505 17:03:45.951486       1 driver.go:94] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0505 17:03:45.951494       1 driver.go:94] Enabling controller service capability: CLONE_VOLUME
I0505 17:03:45.951499       1 driver.go:94] Enabling controller service capability: EXPAND_VOLUME
I0505 17:03:45.951506       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_WRITER
I0505 17:03:45.951512       1 driver.go:106] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0505 17:03:45.951517       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0505 17:03:45.951522       1 driver.go:106] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0505 17:03:45.951623       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0505 17:03:45.951756       1 server.go:126] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0505 17:03:46.031472       1 utils.go:191] ID: 1 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 17:03:46.032940       1 utils.go:195] ID: 1 GRPC request: {}
I0505 17:03:46.032959       1 identityserver-default.go:38] ID: 1 Using default GetPluginInfo
I0505 17:03:46.033067       1 utils.go:202] ID: 1 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0505 17:03:46.034074       1 utils.go:191] ID: 2 GRPC call: /csi.v1.Identity/Probe
I0505 17:03:46.034132       1 utils.go:195] ID: 2 GRPC request: {}
I0505 17:03:46.034237       1 utils.go:202] ID: 2 GRPC response: {}
I0505 17:03:46.034703       1 utils.go:191] ID: 3 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 17:03:46.034860       1 utils.go:195] ID: 3 GRPC request: {}
I0505 17:03:46.034874       1 controllerserver-default.go:74] ID: 3 Using default ControllerGetCapabilities
I0505 17:03:46.035068       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}}}]}
I0505 17:03:46.662438       1 utils.go:191] ID: 4 GRPC call: /csi.v1.Identity/Probe
I0505 17:03:46.662526       1 utils.go:195] ID: 4 GRPC request: {}
I0505 17:03:46.662546       1 utils.go:202] ID: 4 GRPC response: {}
I0505 17:03:46.664926       1 utils.go:191] ID: 5 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 17:03:46.664950       1 utils.go:195] ID: 5 GRPC request: {}
I0505 17:03:46.664955       1 identityserver-default.go:38] ID: 5 Using default GetPluginInfo
I0505 17:03:46.664973       1 utils.go:202] ID: 5 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0505 17:03:46.665866       1 utils.go:191] ID: 6 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 17:03:46.665997       1 utils.go:195] ID: 6 GRPC request: {}
I0505 17:03:46.667120       1 utils.go:202] ID: 6 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 17:03:46.669465       1 utils.go:191] ID: 7 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 17:03:46.669545       1 utils.go:195] ID: 7 GRPC request: {}
I0505 17:03:46.669557       1 controllerserver-default.go:74] ID: 7 Using default ControllerGetCapabilities
I0505 17:03:46.669738       1 utils.go:202] ID: 7 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0505 17:03:46.670492       1 utils.go:191] ID: 8 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 17:03:46.670554       1 utils.go:195] ID: 8 GRPC request: {}
I0505 17:03:46.670564       1 controllerserver-default.go:74] ID: 8 Using default ControllerGetCapabilities
I0505 17:03:46.670663       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}}}]}
I0505 17:03:46.756394       1 utils.go:191] ID: 9 GRPC call: /csi.v1.Identity/Probe
I0505 17:03:46.756473       1 utils.go:195] ID: 9 GRPC request: {}
I0505 17:03:46.756499       1 utils.go:202] ID: 9 GRPC response: {}
I0505 17:03:46.757451       1 utils.go:191] ID: 10 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 17:03:46.757517       1 utils.go:195] ID: 10 GRPC request: {}
I0505 17:03:46.757533       1 identityserver-default.go:38] ID: 10 Using default GetPluginInfo
I0505 17:03:46.757567       1 utils.go:202] ID: 10 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0505 17:03:46.759163       1 utils.go:191] ID: 11 GRPC call: /csi.v1.Identity/Probe
I0505 17:03:46.759209       1 utils.go:195] ID: 11 GRPC request: {}
I0505 17:03:46.759234       1 utils.go:202] ID: 11 GRPC response: {}
I0505 17:03:46.759560       1 utils.go:191] ID: 12 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 17:03:46.759678       1 utils.go:195] ID: 12 GRPC request: {}
I0505 17:03:46.759872       1 utils.go:202] ID: 12 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 17:03:46.760565       1 utils.go:191] ID: 13 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 17:03:46.760607       1 utils.go:195] ID: 13 GRPC request: {}
I0505 17:03:46.760622       1 controllerserver-default.go:74] ID: 13 Using default ControllerGetCapabilities
I0505 17:03:46.760719       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}}}]}
I0505 17:03:46.785033       1 utils.go:191] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0505 17:03:46.785119       1 utils.go:195] ID: 14 GRPC request: {}
I0505 17:03:46.785181       1 utils.go:202] ID: 14 GRPC response: {}
I0505 17:03:46.787459       1 utils.go:191] ID: 15 GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 17:03:46.787489       1 utils.go:195] ID: 15 GRPC request: {}
I0505 17:03:46.787497       1 identityserver-default.go:38] ID: 15 Using default GetPluginInfo
I0505 17:03:46.787522       1 utils.go:202] ID: 15 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0505 17:03:46.790045       1 utils.go:191] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0505 17:03:46.790246       1 utils.go:195] ID: 16 GRPC request: {}
I0505 17:03:46.790358       1 utils.go:202] ID: 16 GRPC response: {}
I0505 17:03:46.790854       1 utils.go:191] ID: 17 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 17:03:46.790884       1 utils.go:195] ID: 17 GRPC request: {}
I0505 17:03:46.790992       1 utils.go:202] ID: 17 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 17:03:46.791681       1 utils.go:191] ID: 18 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 17:03:46.791761       1 utils.go:195] ID: 18 GRPC request: {}
I0505 17:03:46.791774       1 controllerserver-default.go:74] ID: 18 Using default ControllerGetCapabilities
I0505 17:03:46.791884       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}}}]}
I0505 17:04:46.118542       1 utils.go:191] ID: 19 GRPC call: /csi.v1.Identity/Probe
I0505 17:04:46.118653       1 utils.go:195] ID: 19 GRPC request: {}
I0505 17:04:46.118676       1 utils.go:202] ID: 19 GRPC response: {}
I0505 17:05:46.118668       1 utils.go:191] ID: 20 GRPC call: /csi.v1.Identity/Probe
I0505 17:05:46.118748       1 utils.go:195] ID: 20 GRPC request: {}
I0505 17:05:46.118772       1 utils.go:202] ID: 20 GRPC response: {}
I0505 17:06:43.351869       1 utils.go:191] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 GRPC call: /csi.v1.Controller/CreateVolume
I0505 17:06:43.353165       1 utils.go:195] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7","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}}]}
I0505 17:06:43.353401       1 rbd_util.go:1296] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 setting disableInUseChecks: false image features: [layering] mounter: rbd
W0505 17:06:43.353430       1 controllerserver.go:1555] thick-provisioning is deprecated and will be removed in a future release
E0505 17:06:43.368247       1 omap.go:78] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0505 17:06:43.386276       1 omap.go:155] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7:ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac])
I0505 17:06:43.393924       1 omap.go:155] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 set omap keys (pool="kube", namespace="", name="csi.volume.ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac"): map[csi.imagename:csi-vol-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac csi.volname:pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 csi.volume.owner:default])
I0505 17:06:43.394005       1 rbd_journal.go:482] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac) and image name (csi-vol-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac) for request name (pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7)
I0505 17:06:43.394096       1 rbd_util.go:352] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 rbd: create kube/csi-vol-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac size 5120M (features: [layering]) using mon 199.19.213.80
I0505 17:06:44.716915       1 controllerserver.go:666] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 created image kube/csi-vol-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac backed for request name pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7
I0505 17:06:44.756440       1 omap.go:155] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 set omap keys (pool="kube", namespace="", name="csi.volume.ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac"): map[csi.imageid:37d988b7bc43])
I0505 17:06:44.756808       1 utils.go:202] ID: 21 Req-ID: pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-6c7fffec-267d-40e6-a1ff-c6693b90cca7","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-ca74c66d-48a4-11f1-a2ac-c2a9423aa3ac"}}
I0505 17:06:46.117732       1 utils.go:191] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0505 17:06:46.117828       1 utils.go:195] ID: 22 GRPC request: {}
I0505 17:06:46.117860       1 utils.go:202] ID: 22 GRPC response: {}