I0922 02:00:25.539421       1 main.go:92] Version: v3.4.0
I0922 02:00:25.541432       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0922 02:00:26.542696       1 common.go:111] Probing CSI driver for readiness
I0922 02:00:26.542722       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0922 02:00:26.542726       1 connection.go:184] GRPC request: {}
I0922 02:00:26.545465       1 connection.go:186] GRPC response: {}
I0922 02:00:26.545581       1 connection.go:187] GRPC error: <nil>
I0922 02:00:26.545611       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0922 02:00:26.545617       1 connection.go:184] GRPC request: {}
I0922 02:00:26.546364       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0922 02:00:26.546418       1 connection.go:187] GRPC error: <nil>
I0922 02:00:26.548350       1 main.go:148] CSI driver name: "rbd.csi.ceph.com"
I0922 02:00:26.549546       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0922 02:00:26.550073       1 common.go:111] Probing CSI driver for readiness
I0922 02:00:26.550100       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0922 02:00:26.550107       1 connection.go:184] GRPC request: {}
I0922 02:00:26.550800       1 connection.go:186] GRPC response: {}
I0922 02:00:26.550903       1 connection.go:187] GRPC error: <nil>
I0922 02:00:26.550913       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0922 02:00:26.550920       1 connection.go:184] GRPC request: {}
I0922 02:00:26.552099       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0922 02:00:26.552300       1 connection.go:187] GRPC error: <nil>
I0922 02:00:26.552309       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0922 02:00:26.552315       1 connection.go:184] GRPC request: {}
I0922 02:00:26.553423       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0922 02:00:26.553578       1 connection.go:187] GRPC error: <nil>
I0922 02:00:26.553596       1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler
I0922 02:00:26.554722       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com...
I0922 02:00:26.567707       1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:26.568183       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-6qpm6
I0922 02:00:26.568789       1 leader_election.go:205] became leader, starting
I0922 02:00:26.568816       1 controller.go:128] Starting CSI attacher
I0922 02:00:26.569221       1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134
I0922 02:00:26.569246       1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134
I0922 02:00:26.569755       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0922 02:00:26.569778       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0922 02:00:26.583315       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:26.669716       1 shared_informer.go:270] caches populated
I0922 02:00:31.593752       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:36.604045       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:41.614491       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:46.623616       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:51.634776       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:00:56.643838       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:01.658162       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:06.667314       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:11.679215       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:16.688776       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:21.699285       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:26.710480       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:31.721210       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:36.729707       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:41.742779       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:46.754339       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:51.763565       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:01:56.776811       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:01.787071       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:06.797830       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:11.806356       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:16.816677       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:21.829174       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:26.839135       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:31.855142       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:36.864559       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:41.872792       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:46.882010       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:51.891201       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:02:56.901903       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:01.913573       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:06.921453       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:11.929563       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:16.937624       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:21.947178       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:26.956971       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:31.966849       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:36.975563       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:41.983960       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:46.993320       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:52.003359       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:03:57.012666       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:02.021677       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:07.029497       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:12.038441       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:17.046835       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:22.055976       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:27.065086       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:32.073733       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:37.087230       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:42.100404       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:47.110980       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:52.122541       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:04:57.130794       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:02.142920       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:04.498716       1 controller.go:208] Started VA processing "csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72"
I0922 02:05:04.498749       1 trivial_handler.go:53] Trivial sync[csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72] started
I0922 02:05:04.498756       1 util.go:38] Marking as attached "csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72"
I0922 02:05:04.504859       1 util.go:52] Marked as attached "csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72"
I0922 02:05:04.504891       1 trivial_handler.go:61] Marked VolumeAttachment csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72 as attached
I0922 02:05:04.504933       1 controller.go:208] Started VA processing "csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72"
I0922 02:05:04.504944       1 trivial_handler.go:53] Trivial sync[csi-bf600e2ad8c48868af0614fd3d79c6aca3144549bb31945a92b1e971d9af6c72] started
I0922 02:05:07.150603       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:12.165448       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:16.390868       1 controller.go:259] Started PV processing "pvc-16dc05bf-a7b1-4703-8140-4a64328a3ca3"
I0922 02:05:17.173419       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:22.182630       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:27.195482       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:32.204315       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:37.211205       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:42.220956       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:47.233835       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0922 02:05:52.243739       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com