I0423 08:30:37.829071       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0423 08:30:37.829203       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0423 08:30:37.829346       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0423 08:30:37.829354       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0423 08:30:37.829358       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0423 08:30:37.829360       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0423 08:30:37.829364       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0423 08:30:37.829367       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0423 08:30:37.829369       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0423 08:30:37.829372       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0423 08:30:37.829651       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0423 08:30:37.829683       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0423 08:30:40.615332       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:40.617782       1 utils.go:199] ID: 1 GRPC request: {}
I0423 08:30:40.617991       1 utils.go:205] ID: 1 GRPC response: {}
I0423 08:30:40.621412       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0423 08:30:40.621558       1 utils.go:199] ID: 2 GRPC request: {}
I0423 08:30:40.621572       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0423 08:30:40.621647       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0423 08:30:40.626457       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:40.626480       1 utils.go:199] ID: 3 GRPC request: {}
I0423 08:30:40.626494       1 utils.go:205] ID: 3 GRPC response: {}
I0423 08:30:40.627182       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0423 08:30:40.627282       1 utils.go:199] ID: 4 GRPC request: {}
I0423 08:30:40.627493       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0423 08:30:40.628647       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0423 08:30:40.628742       1 utils.go:199] ID: 5 GRPC request: {}
I0423 08:30:40.628758       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0423 08:30:40.628921       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}}}]}
I0423 08:30:42.721930       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:42.721978       1 utils.go:199] ID: 6 GRPC request: {}
I0423 08:30:42.721993       1 utils.go:205] ID: 6 GRPC response: {}
I0423 08:30:42.723517       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0423 08:30:42.723562       1 utils.go:199] ID: 7 GRPC request: {}
I0423 08:30:42.723573       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0423 08:30:42.723710       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0423 08:30:42.725817       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:42.725939       1 utils.go:199] ID: 8 GRPC request: {}
I0423 08:30:42.726032       1 utils.go:205] ID: 8 GRPC response: {}
I0423 08:30:42.726961       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0423 08:30:42.726992       1 utils.go:199] ID: 9 GRPC request: {}
I0423 08:30:42.727094       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0423 08:30:42.727759       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0423 08:30:42.727786       1 utils.go:199] ID: 10 GRPC request: {}
I0423 08:30:42.727797       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0423 08:30:42.727895       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}}}]}
I0423 08:30:42.728629       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0423 08:30:42.728654       1 utils.go:199] ID: 11 GRPC request: {}
I0423 08:30:42.728662       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0423 08:30:42.728729       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}}}]}
I0423 08:30:42.729239       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0423 08:30:42.729262       1 utils.go:199] ID: 12 GRPC request: {}
I0423 08:30:42.729269       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0423 08:30:42.729335       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}}}]}
I0423 08:30:43.981698       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0423 08:30:43.981749       1 utils.go:199] ID: 13 GRPC request: {}
I0423 08:30:43.981762       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0423 08:30:43.981794       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0423 08:30:43.986002       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:43.986035       1 utils.go:199] ID: 14 GRPC request: {}
I0423 08:30:43.986054       1 utils.go:205] ID: 14 GRPC response: {}
I0423 08:30:43.986505       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0423 08:30:43.986533       1 utils.go:199] ID: 15 GRPC request: {}
I0423 08:30:43.986544       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0423 08:30:43.986644       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}}}]}
I0423 08:30:45.176537       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:45.176698       1 utils.go:199] ID: 16 GRPC request: {}
I0423 08:30:45.176725       1 utils.go:205] ID: 16 GRPC response: {}
I0423 08:30:45.179039       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0423 08:30:45.179059       1 utils.go:199] ID: 17 GRPC request: {}
I0423 08:30:45.179102       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0423 08:30:45.179150       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0423 08:30:45.181710       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0423 08:30:45.181783       1 utils.go:199] ID: 18 GRPC request: {}
I0423 08:30:45.181810       1 utils.go:205] ID: 18 GRPC response: {}
I0423 08:30:45.182260       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0423 08:30:45.182315       1 utils.go:199] ID: 19 GRPC request: {}
I0423 08:30:45.182428       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0423 08:30:45.182967       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0423 08:30:45.182986       1 utils.go:199] ID: 20 GRPC request: {}
I0423 08:30:45.183014       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0423 08:30:45.183094       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}}}]}
I0423 08:31:45.372451       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0423 08:31:45.372499       1 utils.go:199] ID: 21 GRPC request: {}
I0423 08:31:45.372551       1 utils.go:205] ID: 21 GRPC response: {}
I0423 08:32:45.371886       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0423 08:32:45.372034       1 utils.go:199] ID: 22 GRPC request: {}
I0423 08:32:45.372087       1 utils.go:205] ID: 22 GRPC response: {}
I0423 08:33:45.371862       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0423 08:33:45.371936       1 utils.go:199] ID: 23 GRPC request: {}
I0423 08:33:45.371962       1 utils.go:205] ID: 23 GRPC response: {}
I0423 08:33:47.983736       1 utils.go:198] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 GRPC call: /csi.v1.Controller/CreateVolume
I0423 08:33:47.984138       1 utils.go:199] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-1be91f13-65f4-435f-9369-c30da98891d4","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-1be91f13-65f4-435f-9369-c30da98891d4","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}}]}
I0423 08:33:47.984479       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0423 08:33:48.001300       1 omap.go:80] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0423 08:33:48.014873       1 omap.go:159] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-1be91f13-65f4-435f-9369-c30da98891d4:463a1a8e-30d4-4761-960f-57ae4f806e54])
I0423 08:33:48.020779       1 omap.go:159] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 set omap keys (pool="kube", namespace="", name="csi.volume.463a1a8e-30d4-4761-960f-57ae4f806e54"): map[csi.imagename:csi-vol-463a1a8e-30d4-4761-960f-57ae4f806e54 csi.volname:pvc-1be91f13-65f4-435f-9369-c30da98891d4 csi.volume.owner:default])
I0423 08:33:48.020814       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-463a1a8e-30d4-4761-960f-57ae4f806e54) and image name (csi-vol-463a1a8e-30d4-4761-960f-57ae4f806e54) for request name (pvc-1be91f13-65f4-435f-9369-c30da98891d4)
I0423 08:33:48.021006       1 rbd_util.go:423] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 rbd: create kube/csi-vol-463a1a8e-30d4-4761-960f-57ae4f806e54 size 5120M (features: [layering]) using mon 199.19.213.47
I0423 08:33:48.021135       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 setting image options on kube/csi-vol-463a1a8e-30d4-4761-960f-57ae4f806e54
I0423 08:33:49.981573       1 controllerserver.go:763] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 created image kube/csi-vol-463a1a8e-30d4-4761-960f-57ae4f806e54 backed for request name pvc-1be91f13-65f4-435f-9369-c30da98891d4
I0423 08:33:50.015307       1 omap.go:159] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 set omap keys (pool="kube", namespace="", name="csi.volume.463a1a8e-30d4-4761-960f-57ae4f806e54"): map[csi.imageid:37d98059ce41])
I0423 08:33:50.114974       1 utils.go:205] ID: 24 Req-ID: pvc-1be91f13-65f4-435f-9369-c30da98891d4 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-463a1a8e-30d4-4761-960f-57ae4f806e54","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-463a1a8e-30d4-4761-960f-57ae4f806e54"}}