I0305 06:39:26.301383       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0305 06:39:26.301485       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0305 06:39:26.301496       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0305 06:39:26.301500       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0305 06:39:26.301503       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0305 06:39:26.301506       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0305 06:39:26.301510       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0305 06:39:26.301513       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0305 06:39:26.301515       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0305 06:39:26.301642       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0305 06:39:26.301976       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0305 06:39:26.301995       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0305 06:39:28.042599       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:28.044013       1 utils.go:199] ID: 1 GRPC request: {}
I0305 06:39:28.044071       1 utils.go:205] ID: 1 GRPC response: {}
I0305 06:39:28.047088       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 06:39:28.047153       1 utils.go:199] ID: 2 GRPC request: {}
I0305 06:39:28.047170       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0305 06:39:28.047211       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 06:39:28.049479       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:28.049572       1 utils.go:199] ID: 3 GRPC request: {}
I0305 06:39:28.049769       1 utils.go:205] ID: 3 GRPC response: {}
I0305 06:39:28.050223       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0305 06:39:28.050328       1 utils.go:199] ID: 4 GRPC request: {}
I0305 06:39:28.050530       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0305 06:39:28.051290       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 06:39:28.051497       1 utils.go:199] ID: 5 GRPC request: {}
I0305 06:39:28.051518       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0305 06:39:28.051808       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}}}]}
I0305 06:39:29.206668       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:29.206704       1 utils.go:199] ID: 6 GRPC request: {}
I0305 06:39:29.206779       1 utils.go:205] ID: 6 GRPC response: {}
I0305 06:39:29.210132       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 06:39:29.210158       1 utils.go:199] ID: 7 GRPC request: {}
I0305 06:39:29.210167       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0305 06:39:29.210188       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 06:39:29.211474       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:29.211554       1 utils.go:199] ID: 8 GRPC request: {}
I0305 06:39:29.211603       1 utils.go:205] ID: 8 GRPC response: {}
I0305 06:39:29.211944       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0305 06:39:29.211966       1 utils.go:199] ID: 9 GRPC request: {}
I0305 06:39:29.212070       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0305 06:39:29.212701       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 06:39:29.212750       1 utils.go:199] ID: 10 GRPC request: {}
I0305 06:39:29.212771       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0305 06:39:29.212945       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}}}]}
I0305 06:39:29.213346       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 06:39:29.213362       1 utils.go:199] ID: 11 GRPC request: {}
I0305 06:39:29.213368       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0305 06:39:29.213412       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}}}]}
I0305 06:39:29.213678       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 06:39:29.213742       1 utils.go:199] ID: 12 GRPC request: {}
I0305 06:39:29.213773       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0305 06:39:29.213957       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}}}]}
I0305 06:39:30.340572       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 06:39:30.340609       1 utils.go:199] ID: 13 GRPC request: {}
I0305 06:39:30.340617       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0305 06:39:30.340640       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 06:39:30.343954       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:30.343990       1 utils.go:199] ID: 14 GRPC request: {}
I0305 06:39:30.344027       1 utils.go:205] ID: 14 GRPC response: {}
I0305 06:39:30.344723       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 06:39:30.344743       1 utils.go:199] ID: 15 GRPC request: {}
I0305 06:39:30.344822       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0305 06:39:30.344953       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}}}]}
I0305 06:39:32.654698       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:32.654736       1 utils.go:199] ID: 16 GRPC request: {}
I0305 06:39:32.654752       1 utils.go:205] ID: 16 GRPC response: {}
I0305 06:39:32.657324       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0305 06:39:32.657341       1 utils.go:199] ID: 17 GRPC request: {}
I0305 06:39:32.657347       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0305 06:39:32.657371       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0305 06:39:32.660081       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0305 06:39:32.660115       1 utils.go:199] ID: 18 GRPC request: {}
I0305 06:39:32.660136       1 utils.go:205] ID: 18 GRPC response: {}
I0305 06:39:32.660727       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0305 06:39:32.660839       1 utils.go:199] ID: 19 GRPC request: {}
I0305 06:39:32.661020       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0305 06:39:32.661579       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0305 06:39:32.661666       1 utils.go:199] ID: 20 GRPC request: {}
I0305 06:39:32.661718       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0305 06:39:32.661884       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}}}]}
I0305 06:40:33.683686       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0305 06:40:33.683749       1 utils.go:199] ID: 21 GRPC request: {}
I0305 06:40:33.683787       1 utils.go:205] ID: 21 GRPC response: {}
I0305 06:41:33.643595       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0305 06:41:33.644045       1 utils.go:199] ID: 22 GRPC request: {}
I0305 06:41:33.644123       1 utils.go:205] ID: 22 GRPC response: {}
I0305 06:42:29.728153       1 utils.go:198] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 GRPC call: /csi.v1.Controller/CreateVolume
I0305 06:42:29.728563       1 utils.go:199] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85","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}}]}
I0305 06:42:29.729194       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0305 06:42:29.743025       1 omap.go:80] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0305 06:42:29.757547       1 omap.go:159] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85:eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91])
I0305 06:42:29.763591       1 omap.go:159] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 set omap keys (pool="kube", namespace="", name="csi.volume.eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91"): map[csi.imagename:csi-vol-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91 csi.volname:pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 csi.volume.owner:default])
I0305 06:42:29.763637       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91) and image name (csi-vol-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91) for request name (pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85)
I0305 06:42:29.764103       1 rbd_util.go:423] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 rbd: create kube/csi-vol-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91 size 5120M (features: [layering]) using mon 162.253.55.140
I0305 06:42:29.764349       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 setting image options on kube/csi-vol-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91
I0305 06:42:30.912678       1 controllerserver.go:763] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 created image kube/csi-vol-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91 backed for request name pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85
I0305 06:42:30.947838       1 omap.go:159] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 set omap keys (pool="kube", namespace="", name="csi.volume.eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91"): map[csi.imageid:37d9677549d8])
I0305 06:42:31.042233       1 utils.go:205] ID: 23 Req-ID: pvc-7dbee21c-5e2c-4d55-86b3-8bc3c88afa85 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-eb16b7e9-d3c1-4b3a-b207-487ae6fe0b91"}}
I0305 06:42:33.642685       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0305 06:42:33.642738       1 utils.go:199] ID: 24 GRPC request: {}
I0305 06:42:33.642761       1 utils.go:205] ID: 24 GRPC response: {}