I0512 20:03:24.679030       1 main.go:92] Version: v3.4.0
I0512 20:03:24.681146       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0512 20:03:25.682425       1 common.go:111] Probing CSI driver for readiness
I0512 20:03:25.682454       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0512 20:03:25.682460       1 connection.go:184] GRPC request: {}
I0512 20:03:25.691715       1 connection.go:186] GRPC response: {}
I0512 20:03:25.691896       1 connection.go:187] GRPC error: <nil>
I0512 20:03:25.691949       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0512 20:03:25.691973       1 connection.go:184] GRPC request: {}
I0512 20:03:25.693382       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0512 20:03:25.693477       1 connection.go:187] GRPC error: <nil>
I0512 20:03:25.693487       1 main.go:148] CSI driver name: "rbd.csi.ceph.com"
I0512 20:03:25.694061       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0512 20:03:25.694583       1 common.go:111] Probing CSI driver for readiness
I0512 20:03:25.694648       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0512 20:03:25.694656       1 connection.go:184] GRPC request: {}
I0512 20:03:25.695283       1 connection.go:186] GRPC response: {}
I0512 20:03:25.695318       1 connection.go:187] GRPC error: <nil>
I0512 20:03:25.695325       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0512 20:03:25.695329       1 connection.go:184] GRPC request: {}
I0512 20:03:25.696256       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0512 20:03:25.696413       1 connection.go:187] GRPC error: <nil>
I0512 20:03:25.696422       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0512 20:03:25.696426       1 connection.go:184] GRPC request: {}
I0512 20:03:25.697228       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0512 20:03:25.697371       1 connection.go:187] GRPC error: <nil>
I0512 20:03:25.697383       1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler
I0512 20:03:25.697902       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com...
I0512 20:03:25.750336       1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:25.750426       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-2j5wn
I0512 20:03:25.750540       1 leader_election.go:205] became leader, starting
I0512 20:03:25.750558       1 controller.go:128] Starting CSI attacher
I0512 20:03:25.750748       1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134
I0512 20:03:25.750759       1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134
I0512 20:03:25.764077       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0512 20:03:25.764108       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0512 20:03:25.850713       1 shared_informer.go:270] caches populated
I0512 20:03:26.531434       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:31.543600       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:36.552807       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:41.563908       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:46.575331       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:51.582106       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:03:56.589918       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:01.630483       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:06.637265       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:11.645843       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:16.655432       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:21.664974       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:26.675204       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:31.684566       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:36.694247       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:41.702310       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:46.731799       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:51.741643       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:04:56.749846       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:01.757509       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:06.767411       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:11.775089       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:16.784070       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:21.792085       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:26.801244       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:31.810943       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:36.820454       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:41.829434       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:46.839905       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:51.849932       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:05:56.860526       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:01.872133       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:06.878766       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:11.888523       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:16.900815       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:21.908442       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:26.917412       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:31.926352       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:36.789511       1 controller.go:208] Started VA processing "csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440"
I0512 20:06:36.789541       1 trivial_handler.go:53] Trivial sync[csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440] started
I0512 20:06:36.789549       1 util.go:38] Marking as attached "csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440"
I0512 20:06:36.794637       1 util.go:52] Marked as attached "csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440"
I0512 20:06:36.794661       1 trivial_handler.go:61] Marked VolumeAttachment csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440 as attached
I0512 20:06:36.794701       1 controller.go:208] Started VA processing "csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440"
I0512 20:06:36.794716       1 trivial_handler.go:53] Trivial sync[csi-3d7b6bd3069369cd5ca2890c8b934e65cde3b5ac0538ad756a4801bcfa82c440] started
I0512 20:06:36.938779       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:41.949577       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:46.960339       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:51.967910       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:06:56.975985       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:07:01.984571       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:07:03.061887       1 controller.go:259] Started PV processing "pvc-6057e674-4dde-4642-97b6-37b82c408c7f"
I0512 20:07:06.994077       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:07:12.000947       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:07:17.010557       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:07:22.017624       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0512 20:07:27.028299       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com