I0717 05:06:41.221101       1 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0717 05:06:41.221292       1 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0717 05:06:41.221313       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_VOLUME
I0717 05:06:41.221317       1 driver.go:95] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0717 05:06:41.221322       1 driver.go:95] Enabling controller service capability: CLONE_VOLUME
I0717 05:06:41.221326       1 driver.go:95] Enabling controller service capability: EXPAND_VOLUME
I0717 05:06:41.221334       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_WRITER
I0717 05:06:41.221342       1 driver.go:108] Enabling volume access mode: MULTI_NODE_MULTI_WRITER
I0717 05:06:41.221345       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_SINGLE_WRITER
I0717 05:06:41.221347       1 driver.go:108] Enabling volume access mode: SINGLE_NODE_MULTI_WRITER
I0717 05:06:41.221694       1 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi-provisioner.sock", Net:"unix"}
I0717 05:06:41.221795       1 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0717 05:06:49.417807       1 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:49.419522       1 utils.go:199] ID: 1 GRPC request: {}
I0717 05:06:49.419588       1 utils.go:205] ID: 1 GRPC response: {}
I0717 05:06:49.422059       1 utils.go:198] ID: 2 GRPC call: /csi.v1.Identity/GetPluginInfo
I0717 05:06:49.422243       1 utils.go:199] ID: 2 GRPC request: {}
I0717 05:06:49.422266       1 identityserver-default.go:40] ID: 2 Using default GetPluginInfo
I0717 05:06:49.422385       1 utils.go:205] ID: 2 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0717 05:06:49.426105       1 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:49.426130       1 utils.go:199] ID: 3 GRPC request: {}
I0717 05:06:49.426145       1 utils.go:205] ID: 3 GRPC response: {}
I0717 05:06:49.426645       1 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0717 05:06:49.426716       1 utils.go:199] ID: 4 GRPC request: {}
I0717 05:06:49.426870       1 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0717 05:06:49.429680       1 utils.go:198] ID: 5 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0717 05:06:49.429784       1 utils.go:199] ID: 5 GRPC request: {}
I0717 05:06:49.429811       1 controllerserver-default.go:42] ID: 5 Using default ControllerGetCapabilities
I0717 05:06:49.429981       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}}}]}
I0717 05:06:51.117824       1 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:51.117894       1 utils.go:199] ID: 6 GRPC request: {}
I0717 05:06:51.117965       1 utils.go:205] ID: 6 GRPC response: {}
I0717 05:06:51.120033       1 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/GetPluginInfo
I0717 05:06:51.120056       1 utils.go:199] ID: 7 GRPC request: {}
I0717 05:06:51.120062       1 identityserver-default.go:40] ID: 7 Using default GetPluginInfo
I0717 05:06:51.120154       1 utils.go:205] ID: 7 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0717 05:06:51.125789       1 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:51.125807       1 utils.go:199] ID: 8 GRPC request: {}
I0717 05:06:51.125820       1 utils.go:205] ID: 8 GRPC response: {}
I0717 05:06:51.126424       1 utils.go:198] ID: 9 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0717 05:06:51.126441       1 utils.go:199] ID: 9 GRPC request: {}
I0717 05:06:51.126575       1 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0717 05:06:51.127522       1 utils.go:198] ID: 10 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0717 05:06:51.127588       1 utils.go:199] ID: 10 GRPC request: {}
I0717 05:06:51.127600       1 controllerserver-default.go:42] ID: 10 Using default ControllerGetCapabilities
I0717 05:06:51.127673       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}}}]}
I0717 05:06:51.128359       1 utils.go:198] ID: 11 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0717 05:06:51.128380       1 utils.go:199] ID: 11 GRPC request: {}
I0717 05:06:51.128386       1 controllerserver-default.go:42] ID: 11 Using default ControllerGetCapabilities
I0717 05:06:51.128558       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}}}]}
I0717 05:06:51.128943       1 utils.go:198] ID: 12 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0717 05:06:51.129005       1 utils.go:199] ID: 12 GRPC request: {}
I0717 05:06:51.129016       1 controllerserver-default.go:42] ID: 12 Using default ControllerGetCapabilities
I0717 05:06:51.129133       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}}}]}
I0717 05:06:52.754023       1 utils.go:198] ID: 13 GRPC call: /csi.v1.Identity/GetPluginInfo
I0717 05:06:52.754078       1 utils.go:199] ID: 13 GRPC request: {}
I0717 05:06:52.754087       1 identityserver-default.go:40] ID: 13 Using default GetPluginInfo
I0717 05:06:52.754132       1 utils.go:205] ID: 13 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0717 05:06:52.755993       1 utils.go:198] ID: 14 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:52.756015       1 utils.go:199] ID: 14 GRPC request: {}
I0717 05:06:52.756027       1 utils.go:205] ID: 14 GRPC response: {}
I0717 05:06:52.757394       1 utils.go:198] ID: 15 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0717 05:06:52.757514       1 utils.go:199] ID: 15 GRPC request: {}
I0717 05:06:52.757590       1 controllerserver-default.go:42] ID: 15 Using default ControllerGetCapabilities
I0717 05:06:52.757880       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}}}]}
I0717 05:06:54.380414       1 utils.go:198] ID: 16 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:54.380566       1 utils.go:199] ID: 16 GRPC request: {}
I0717 05:06:54.380617       1 utils.go:205] ID: 16 GRPC response: {}
I0717 05:06:54.383090       1 utils.go:198] ID: 17 GRPC call: /csi.v1.Identity/GetPluginInfo
I0717 05:06:54.383191       1 utils.go:199] ID: 17 GRPC request: {}
I0717 05:06:54.383210       1 identityserver-default.go:40] ID: 17 Using default GetPluginInfo
I0717 05:06:54.383338       1 utils.go:205] ID: 17 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0717 05:06:54.385177       1 utils.go:198] ID: 18 GRPC call: /csi.v1.Identity/Probe
I0717 05:06:54.385221       1 utils.go:199] ID: 18 GRPC request: {}
I0717 05:06:54.385259       1 utils.go:205] ID: 18 GRPC response: {}
I0717 05:06:54.386185       1 utils.go:198] ID: 19 GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0717 05:06:54.386292       1 utils.go:199] ID: 19 GRPC request: {}
I0717 05:06:54.386426       1 utils.go:205] ID: 19 GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0717 05:06:54.386982       1 utils.go:198] ID: 20 GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0717 05:06:54.387002       1 utils.go:199] ID: 20 GRPC request: {}
I0717 05:06:54.387010       1 controllerserver-default.go:42] ID: 20 Using default ControllerGetCapabilities
I0717 05:06:54.387089       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}}}]}
I0717 05:07:54.557315       1 utils.go:198] ID: 21 GRPC call: /csi.v1.Identity/Probe
I0717 05:07:54.557372       1 utils.go:199] ID: 21 GRPC request: {}
I0717 05:07:54.557400       1 utils.go:205] ID: 21 GRPC response: {}
I0717 05:08:54.558147       1 utils.go:198] ID: 22 GRPC call: /csi.v1.Identity/Probe
I0717 05:08:54.558206       1 utils.go:199] ID: 22 GRPC request: {}
I0717 05:08:54.558286       1 utils.go:205] ID: 22 GRPC response: {}
I0717 05:09:54.556692       1 utils.go:198] ID: 23 GRPC call: /csi.v1.Identity/Probe
I0717 05:09:54.556741       1 utils.go:199] ID: 23 GRPC request: {}
I0717 05:09:54.556761       1 utils.go:205] ID: 23 GRPC response: {}
I0717 05:10:54.568477       1 utils.go:198] ID: 24 GRPC call: /csi.v1.Identity/Probe
I0717 05:10:54.568559       1 utils.go:199] ID: 24 GRPC request: {}
I0717 05:10:54.568591       1 utils.go:205] ID: 24 GRPC response: {}
I0717 05:10:57.228013       1 utils.go:198] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 GRPC call: /csi.v1.Controller/CreateVolume
I0717 05:10:57.228428       1 utils.go:199] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5","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}}]}
I0717 05:10:57.228740       1 rbd_util.go:1315] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 setting disableInUseChecks: false image features: [layering] mounter: rbd
E0717 05:10:57.248264       1 omap.go:80] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 omap not found (pool="kube", namespace="", name="csi.volumes.default"): rados: ret=-2, No such file or directory
I0717 05:10:57.262052       1 omap.go:159] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 set omap keys (pool="kube", namespace="", name="csi.volumes.default"): map[csi.volume.pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5:98851d35-2362-471d-987d-2ad47384ca47])
I0717 05:10:57.268668       1 omap.go:159] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 set omap keys (pool="kube", namespace="", name="csi.volume.98851d35-2362-471d-987d-2ad47384ca47"): map[csi.imagename:csi-vol-98851d35-2362-471d-987d-2ad47384ca47 csi.volname:pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 csi.volume.owner:default])
I0717 05:10:57.268702       1 rbd_journal.go:491] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 generated Volume ID (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-98851d35-2362-471d-987d-2ad47384ca47) and image name (csi-vol-98851d35-2362-471d-987d-2ad47384ca47) for request name (pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5)
I0717 05:10:57.268840       1 rbd_util.go:423] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 rbd: create kube/csi-vol-98851d35-2362-471d-987d-2ad47384ca47 size 5120M (features: [layering]) using mon 199.204.45.138
I0717 05:10:57.268930       1 rbd_util.go:1563] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 setting image options on kube/csi-vol-98851d35-2362-471d-987d-2ad47384ca47
I0717 05:10:57.299695       1 controllerserver.go:763] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 created image kube/csi-vol-98851d35-2362-471d-987d-2ad47384ca47 backed for request name pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5
I0717 05:10:57.335293       1 omap.go:159] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 set omap keys (pool="kube", namespace="", name="csi.volume.98851d35-2362-471d-987d-2ad47384ca47"): map[csi.imageid:37f4ebef96f3])
I0717 05:10:57.429783       1 utils.go:205] ID: 25 Req-ID: pvc-bfabe4d6-4bc7-4522-90b2-77dd83d0c0f5 GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-98851d35-2362-471d-987d-2ad47384ca47","journalPool":"kube","pool":"kube"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-98851d35-2362-471d-987d-2ad47384ca47"}}