I0508 05:59:46.139677       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0508 05:59:46.139809       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0508 05:59:46.139819       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0508 05:59:46.139822       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0508 05:59:46.139825       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0508 05:59:46.139828       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0508 05:59:46.139832       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0508 05:59:46.139835       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0508 05:59:46.139837       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0508 05:59:46.139840       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0508 05:59:46.140297       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0508 05:59:46.140398       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0508 05:59:49.141022       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:49.142803       1 utils.go:199] ID: 1 GRPC request: {}
I0508 05:59:49.142872       1 utils.go:205] ID: 1 GRPC response: {}
I0508 05:59:49.146829       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0508 05:59:49.146884       1 utils.go:199] ID: 2 GRPC request: {}
I0508 05:59:49.146896       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0508 05:59:49.146932       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0508 05:59:49.148429       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:49.148461       1 utils.go:199] ID: 3 GRPC request: {}
I0508 05:59:49.148476       1 utils.go:205] ID: 3 GRPC response: {}
I0508 05:59:49.149185       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0508 05:59:49.149281       1 utils.go:199] ID: 4 GRPC request: {}
I0508 05:59:49.149441       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0508 05:59:49.151453       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0508 05:59:49.151624       1 utils.go:199] ID: 5 GRPC request: {}
I0508 05:59:49.151642       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0508 05:59:49.152285       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}}}]}
I0508 05:59:50.675149       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:50.675253       1 utils.go:199] ID: 6 GRPC request: {}
I0508 05:59:50.675332       1 utils.go:205] ID: 6 GRPC response: {}
I0508 05:59:50.680520       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0508 05:59:50.680558       1 utils.go:199] ID: 7 GRPC request: {}
I0508 05:59:50.680567       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0508 05:59:50.680591       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0508 05:59:50.682988       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:50.683153       1 utils.go:199] ID: 8 GRPC request: {}
I0508 05:59:50.683198       1 utils.go:205] ID: 8 GRPC response: {}
I0508 05:59:50.683775       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0508 05:59:50.683817       1 utils.go:199] ID: 9 GRPC request: {}
I0508 05:59:50.683912       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0508 05:59:50.687912       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0508 05:59:50.687948       1 utils.go:199] ID: 10 GRPC request: {}
I0508 05:59:50.687968       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0508 05:59:50.688115       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}}}]}
I0508 05:59:50.688780       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0508 05:59:50.688821       1 utils.go:199] ID: 11 GRPC request: {}
I0508 05:59:50.688839       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0508 05:59:50.688929       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}}}]}
I0508 05:59:50.689564       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0508 05:59:50.689604       1 utils.go:199] ID: 12 GRPC request: {}
I0508 05:59:50.689633       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0508 05:59:50.689787       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}}}]}
I0508 05:59:55.697666       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0508 05:59:55.697737       1 utils.go:199] ID: 13 GRPC request: {}
I0508 05:59:55.697754       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0508 05:59:55.697816       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0508 05:59:55.701976       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:55.701998       1 utils.go:199] ID: 14 GRPC request: {}
I0508 05:59:55.702012       1 utils.go:205] ID: 14 GRPC response: {}
I0508 05:59:55.702496       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0508 05:59:55.702534       1 utils.go:199] ID: 15 GRPC request: {}
I0508 05:59:55.702542       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0508 05:59:55.702643       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}}}]}
I0508 05:59:59.351615       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:59.351689       1 utils.go:199] ID: 16 GRPC request: {}
I0508 05:59:59.351728       1 utils.go:205] ID: 16 GRPC response: {}
I0508 05:59:59.356607       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0508 05:59:59.356652       1 utils.go:199] ID: 17 GRPC request: {}
I0508 05:59:59.356664       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0508 05:59:59.356688       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0508 05:59:59.359430       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0508 05:59:59.359475       1 utils.go:199] ID: 18 GRPC request: {}
I0508 05:59:59.359492       1 utils.go:205] ID: 18 GRPC response: {}
I0508 05:59:59.359875       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0508 05:59:59.359906       1 utils.go:199] ID: 19 GRPC request: {}
I0508 05:59:59.360018       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0508 05:59:59.360590       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0508 05:59:59.360608       1 utils.go:199] ID: 20 GRPC request: {}
I0508 05:59:59.360617       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0508 05:59:59.360737       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}}}]}
I0508 06:00:59.623976       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0508 06:00:59.624150       1 utils.go:199] ID: 21 GRPC request: {}
I0508 06:00:59.624301       1 utils.go:205] ID: 21 GRPC response: {}
I0508 06:01:59.576308       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0508 06:01:59.576422       1 utils.go:199] ID: 22 GRPC request: {}
I0508 06:01:59.576467       1 utils.go:205] ID: 22 GRPC response: {}
I0508 06:02:59.574899       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0508 06:02:59.574954       1 utils.go:199] ID: 23 GRPC request: {}
I0508 06:02:59.574976       1 utils.go:205] ID: 23 GRPC response: {}
I0508 06:03:06.326548       1 utils.go:198] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 GRPC call: /csi.v1.Controller/CreateVolume
I0508 06:03:06.327092       1 utils.go:199] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-aae94ca0-e62d-477b-bdd2-988c038f1628","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-aae94ca0-e62d-477b-bdd2-988c038f1628","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}}]}
I0508 06:03:06.327425       1 rbd_util.go:1315] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0508 06:03:06.346005       1 omap.go:80] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0508 06:03:06.363850       1 omap.go:159] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-aae94ca0-e62d-477b-bdd2-988c038f1628:c69b124b-39c7-492a-83bb-76e6be747e55])
I0508 06:03:06.371348       1 omap.go:159] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 set omap keys (pool="kube", namespace="", name="csi.volume.c69b124b-39c7-492a-83bb-76e6be747e55"): map[csi.imagename:csi-vol-c69b124b-39c7-492a-83bb-76e6be747e55 csi.volname:pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 csi.volume.owner:default])
I0508 06:03:06.371424       1 rbd_journal.go:491] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c69b124b-39c7-492a-83bb-76e6be747e55) and image name (csi-vol-c69b124b-39c7-492a-83bb-76e6be747e55) for request name (pvc-aae94ca0-e62d-477b-bdd2-988c038f1628)
I0508 06:03:06.371603       1 rbd_util.go:423] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 rbd: create kube/csi-vol-c69b124b-39c7-492a-83bb-76e6be747e55 size 5120M (features: [layering]) using mon 199.19.213.122
I0508 06:03:06.371696       1 rbd_util.go:1563] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 setting image options on kube/csi-vol-c69b124b-39c7-492a-83bb-76e6be747e55
I0508 06:03:07.511686       1 controllerserver.go:763] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 created image kube/csi-vol-c69b124b-39c7-492a-83bb-76e6be747e55 backed for request name pvc-aae94ca0-e62d-477b-bdd2-988c038f1628
I0508 06:03:07.555321       1 omap.go:159] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 set omap keys (pool="kube", namespace="", name="csi.volume.c69b124b-39c7-492a-83bb-76e6be747e55"): map[csi.imageid:37d9c35aa71f])
I0508 06:03:07.651653       1 utils.go:205] ID: 24 Req-ID: pvc-aae94ca0-e62d-477b-bdd2-988c038f1628 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-c69b124b-39c7-492a-83bb-76e6be747e55","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c69b124b-39c7-492a-83bb-76e6be747e55"}}