I0420 11:57:59.554246       1 main.go:92] Version: v3.4.0
I0420 11:57:59.555218       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0420 11:58:00.556008       1 common.go:111] Probing CSI driver for readiness
I0420 11:58:00.556104       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0420 11:58:00.556112       1 connection.go:184] GRPC request: {}
I0420 11:58:00.560907       1 connection.go:186] GRPC response: {}
I0420 11:58:00.560988       1 connection.go:187] GRPC error: <nil>
I0420 11:58:00.560999       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0420 11:58:00.561003       1 connection.go:184] GRPC request: {}
I0420 11:58:00.563097       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0420 11:58:00.563168       1 connection.go:187] GRPC error: <nil>
I0420 11:58:00.563176       1 main.go:148] CSI driver name: "rbd.csi.ceph.com"
I0420 11:58:00.564288       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0420 11:58:00.564761       1 common.go:111] Probing CSI driver for readiness
I0420 11:58:00.564781       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0420 11:58:00.564809       1 connection.go:184] GRPC request: {}
I0420 11:58:00.565404       1 connection.go:186] GRPC response: {}
I0420 11:58:00.565778       1 connection.go:187] GRPC error: <nil>
I0420 11:58:00.565816       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0420 11:58:00.565834       1 connection.go:184] GRPC request: {}
I0420 11:58:00.566574       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0420 11:58:00.566700       1 connection.go:187] GRPC error: <nil>
I0420 11:58:00.566706       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0420 11:58:00.566710       1 connection.go:184] GRPC request: {}
I0420 11:58:00.569823       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0420 11:58:00.569980       1 connection.go:187] GRPC error: <nil>
I0420 11:58:00.570042       1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler
I0420 11:58:00.570795       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com...
I0420 11:58:00.582324       1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:00.582412       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-tq8bj
I0420 11:58:00.582441       1 leader_election.go:205] became leader, starting
I0420 11:58:00.582477       1 controller.go:128] Starting CSI attacher
I0420 11:58:00.582789       1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134
I0420 11:58:00.582805       1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134
I0420 11:58:00.582858       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0420 11:58:00.582893       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0420 11:58:00.591042       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:00.683491       1 shared_informer.go:270] caches populated
I0420 11:58:05.785152       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:10.798107       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:15.874113       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:20.884329       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:25.894745       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:30.902762       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:35.916179       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:40.926294       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:45.934446       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:50.943280       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:58:55.953626       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:00.961710       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:05.970089       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:10.977589       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:15.989373       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:20.998856       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:26.011603       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:31.021183       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:36.030580       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:41.042350       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:46.051704       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:51.067186       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 11:59:56.079226       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:01.086951       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:06.096311       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:11.107293       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:16.115205       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:21.126432       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:26.135624       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:31.146906       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:36.158504       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:41.170478       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:46.180034       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:51.192090       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:00:56.201207       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:01.209686       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:06.217205       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:11.225848       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:15.604443       1 controller.go:208] Started VA processing "csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86"
I0420 12:01:15.604488       1 trivial_handler.go:53] Trivial sync[csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86] started
I0420 12:01:15.604494       1 util.go:38] Marking as attached "csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86"
I0420 12:01:15.610479       1 util.go:52] Marked as attached "csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86"
I0420 12:01:15.610512       1 trivial_handler.go:61] Marked VolumeAttachment csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86 as attached
I0420 12:01:15.610557       1 controller.go:208] Started VA processing "csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86"
I0420 12:01:15.610575       1 trivial_handler.go:53] Trivial sync[csi-9f3ca18d4c5ce649e445c73b8ee752f336f9517dcf2cabeed4dddaa3d5ebbf86] started
I0420 12:01:16.236593       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:21.249832       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:26.264183       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:31.276527       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:36.284125       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:37.963390       1 controller.go:259] Started PV processing "pvc-06e17228-8912-4c77-86e5-0b1d6316f2cd"
I0420 12:01:41.293070       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:46.302026       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:51.312125       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:01:56.327773       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:02:01.337277       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0420 12:02:06.348783       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com