I0121 00:43:29.989735       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0121 00:43:29.989900       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0121 00:43:29.989924       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0121 00:43:29.989932       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0121 00:43:29.989937       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0121 00:43:29.989940       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0121 00:43:29.989987       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0121 00:43:29.989996       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0121 00:43:29.990000       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0121 00:43:29.990002       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0121 00:43:29.990417       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0121 00:43:29.990682       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0121 00:43:35.285043       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:35.286082       1 utils.go:199] ID: 1 GRPC request: {}
I0121 00:43:35.288934       1 utils.go:205] ID: 1 GRPC response: {}
I0121 00:43:35.295277       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0121 00:43:35.295420       1 utils.go:199] ID: 2 GRPC request: {}
I0121 00:43:35.295436       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0121 00:43:35.295584       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0121 00:43:35.297194       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:35.297219       1 utils.go:199] ID: 3 GRPC request: {}
I0121 00:43:35.297232       1 utils.go:205] ID: 3 GRPC response: {}
I0121 00:43:35.297729       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0121 00:43:35.297763       1 utils.go:199] ID: 4 GRPC request: {}
I0121 00:43:35.297934       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0121 00:43:35.298592       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0121 00:43:35.298692       1 utils.go:199] ID: 5 GRPC request: {}
I0121 00:43:35.298704       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0121 00:43:35.298868       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}}}]}
I0121 00:43:38.858282       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:38.858362       1 utils.go:199] ID: 6 GRPC request: {}
I0121 00:43:38.858414       1 utils.go:205] ID: 6 GRPC response: {}
I0121 00:43:38.863048       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0121 00:43:38.863075       1 utils.go:199] ID: 7 GRPC request: {}
I0121 00:43:38.863081       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0121 00:43:38.863113       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0121 00:43:38.870362       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:38.870422       1 utils.go:199] ID: 8 GRPC request: {}
I0121 00:43:38.870450       1 utils.go:205] ID: 8 GRPC response: {}
I0121 00:43:38.870865       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0121 00:43:38.870911       1 utils.go:199] ID: 9 GRPC request: {}
I0121 00:43:38.871019       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0121 00:43:38.871594       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0121 00:43:38.871640       1 utils.go:199] ID: 10 GRPC request: {}
I0121 00:43:38.871657       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0121 00:43:38.871749       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}}}]}
I0121 00:43:38.872267       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0121 00:43:38.872314       1 utils.go:199] ID: 11 GRPC request: {}
I0121 00:43:38.872332       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0121 00:43:38.872405       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}}}]}
I0121 00:43:38.872725       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0121 00:43:38.872769       1 utils.go:199] ID: 12 GRPC request: {}
I0121 00:43:38.872784       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0121 00:43:38.872912       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}}}]}
I0121 00:43:41.639938       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0121 00:43:41.640024       1 utils.go:199] ID: 13 GRPC request: {}
I0121 00:43:41.640046       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0121 00:43:41.640094       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0121 00:43:41.643502       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:41.643560       1 utils.go:199] ID: 14 GRPC request: {}
I0121 00:43:41.643587       1 utils.go:205] ID: 14 GRPC response: {}
I0121 00:43:41.649104       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0121 00:43:41.649142       1 utils.go:199] ID: 15 GRPC request: {}
I0121 00:43:41.649173       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0121 00:43:41.649321       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}}}]}
I0121 00:43:44.332824       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:44.333756       1 utils.go:199] ID: 16 GRPC request: {}
I0121 00:43:44.334135       1 utils.go:205] ID: 16 GRPC response: {}
I0121 00:43:44.335980       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0121 00:43:44.335996       1 utils.go:199] ID: 17 GRPC request: {}
I0121 00:43:44.336003       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0121 00:43:44.336030       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0121 00:43:44.337507       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0121 00:43:44.337567       1 utils.go:199] ID: 18 GRPC request: {}
I0121 00:43:44.337579       1 utils.go:205] ID: 18 GRPC response: {}
I0121 00:43:44.337867       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0121 00:43:44.337885       1 utils.go:199] ID: 19 GRPC request: {}
I0121 00:43:44.337981       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0121 00:43:44.339916       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0121 00:43:44.339961       1 utils.go:199] ID: 20 GRPC request: {}
I0121 00:43:44.339967       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0121 00:43:44.340041       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}}}]}
I0121 00:44:44.593789       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0121 00:44:44.594309       1 utils.go:199] ID: 21 GRPC request: {}
I0121 00:44:44.594419       1 utils.go:205] ID: 21 GRPC response: {}
I0121 00:45:44.534922       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0121 00:45:44.534983       1 utils.go:199] ID: 22 GRPC request: {}
I0121 00:45:44.535007       1 utils.go:205] ID: 22 GRPC response: {}
I0121 00:46:43.445904       1 utils.go:198] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a GRPC call: /csi.v1.Controller/CreateVolume
I0121 00:46:43.446907       1 utils.go:199] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-19415a70-110c-4253-97a1-21208b37fb1a","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-19415a70-110c-4253-97a1-21208b37fb1a","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}}]}
I0121 00:46:43.447206       1 rbd_util.go:1315] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a setting disableInUseChecks: false image features: [layering] mounter: rbd
E0121 00:46:43.498924       1 omap.go:80] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0121 00:46:43.513351       1 omap.go:159] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-19415a70-110c-4253-97a1-21208b37fb1a:73c84180-1a47-4a9e-813e-3706cce0e6ae])
I0121 00:46:43.518548       1 omap.go:159] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a set omap keys (pool="kube", namespace="", name="csi.volume.73c84180-1a47-4a9e-813e-3706cce0e6ae"): map[csi.imagename:csi-vol-73c84180-1a47-4a9e-813e-3706cce0e6ae csi.volname:pvc-19415a70-110c-4253-97a1-21208b37fb1a csi.volume.owner:default])
I0121 00:46:43.518673       1 rbd_journal.go:491] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-73c84180-1a47-4a9e-813e-3706cce0e6ae) and image name (csi-vol-73c84180-1a47-4a9e-813e-3706cce0e6ae) for request name (pvc-19415a70-110c-4253-97a1-21208b37fb1a)
I0121 00:46:43.518801       1 rbd_util.go:423] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a rbd: create kube/csi-vol-73c84180-1a47-4a9e-813e-3706cce0e6ae size 5120M (features: [layering]) using mon 162.253.55.195
I0121 00:46:43.518965       1 rbd_util.go:1563] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a setting image options on kube/csi-vol-73c84180-1a47-4a9e-813e-3706cce0e6ae
I0121 00:46:44.533622       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0121 00:46:44.533863       1 utils.go:199] ID: 24 GRPC request: {}
I0121 00:46:44.534039       1 utils.go:205] ID: 24 GRPC response: {}
I0121 00:46:45.249819       1 controllerserver.go:763] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a created image kube/csi-vol-73c84180-1a47-4a9e-813e-3706cce0e6ae backed for request name pvc-19415a70-110c-4253-97a1-21208b37fb1a
I0121 00:46:45.279373       1 omap.go:159] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a set omap keys (pool="kube", namespace="", name="csi.volume.73c84180-1a47-4a9e-813e-3706cce0e6ae"): map[csi.imageid:37d9ad4333fc])
I0121 00:46:45.370567       1 utils.go:205] ID: 23 Req-ID: pvc-19415a70-110c-4253-97a1-21208b37fb1a GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-73c84180-1a47-4a9e-813e-3706cce0e6ae","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-73c84180-1a47-4a9e-813e-3706cce0e6ae"}}