I0520 00:26:24.458615       1 main.go:92] Version: v3.4.0
I0520 00:26:24.459607       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0520 00:26:25.460717       1 common.go:111] Probing CSI driver for readiness
I0520 00:26:25.460742       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0520 00:26:25.460747       1 connection.go:184] GRPC request: {}
I0520 00:26:25.463415       1 connection.go:186] GRPC response: {}
I0520 00:26:25.463464       1 connection.go:187] GRPC error: <nil>
I0520 00:26:25.463471       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0520 00:26:25.463475       1 connection.go:184] GRPC request: {}
I0520 00:26:25.464326       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0520 00:26:25.464372       1 connection.go:187] GRPC error: <nil>
I0520 00:26:25.464379       1 main.go:148] CSI driver name: "rbd.csi.ceph.com"
I0520 00:26:25.464947       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0520 00:26:25.465565       1 common.go:111] Probing CSI driver for readiness
I0520 00:26:25.465594       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0520 00:26:25.465601       1 connection.go:184] GRPC request: {}
I0520 00:26:25.466179       1 connection.go:186] GRPC response: {}
I0520 00:26:25.466223       1 connection.go:187] GRPC error: <nil>
I0520 00:26:25.466232       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0520 00:26:25.466237       1 connection.go:184] GRPC request: {}
I0520 00:26:25.467230       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0520 00:26:25.467425       1 connection.go:187] GRPC error: <nil>
I0520 00:26:25.467434       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0520 00:26:25.467511       1 connection.go:184] GRPC request: {}
I0520 00:26:25.470545       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0520 00:26:25.470713       1 connection.go:187] GRPC error: <nil>
I0520 00:26:25.470729       1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler
I0520 00:26:25.471335       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com...
I0520 00:26:25.482459       1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:25.482602       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-b96r4
I0520 00:26:25.482624       1 leader_election.go:205] became leader, starting
I0520 00:26:25.482636       1 controller.go:128] Starting CSI attacher
I0520 00:26:25.482885       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0520 00:26:25.482900       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0520 00:26:25.482889       1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134
I0520 00:26:25.482941       1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134
I0520 00:26:25.491818       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:25.583242       1 shared_informer.go:270] caches populated
I0520 00:26:30.500629       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:35.508357       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:40.515562       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:45.525661       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:50.538052       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:26:55.547324       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:00.556729       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:05.565652       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:10.574437       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:15.583932       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:20.590945       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:25.597470       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:30.605916       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:35.613524       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:40.624135       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:45.635199       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:50.643579       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:27:55.652776       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:00.660259       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:05.670071       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:10.680469       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:15.689788       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:20.699615       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:25.708177       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:30.717074       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:35.726358       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:40.736885       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:45.750105       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:50.760483       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:28:55.767986       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:00.777458       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:05.787069       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:10.796975       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:15.806173       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:20.816228       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:25.824845       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:30.833725       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:35.843347       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:40.851040       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:45.860184       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:50.868999       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:29:55.880175       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:00.888940       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:05.895744       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:08.453815       1 controller.go:208] Started VA processing "csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff"
I0520 00:30:08.453846       1 trivial_handler.go:53] Trivial sync[csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff] started
I0520 00:30:08.453851       1 util.go:38] Marking as attached "csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff"
I0520 00:30:08.460284       1 util.go:52] Marked as attached "csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff"
I0520 00:30:08.460310       1 trivial_handler.go:61] Marked VolumeAttachment csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff as attached
I0520 00:30:08.460350       1 controller.go:208] Started VA processing "csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff"
I0520 00:30:08.460359       1 trivial_handler.go:53] Trivial sync[csi-0b0399840483fcb675b549ce0b7e7faf2b7053edddfc32367bcac95e755881ff] started
I0520 00:30:10.904286       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:15.911778       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:20.919623       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:25.926435       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:28.984695       1 controller.go:259] Started PV processing "pvc-7b4597b4-32fd-4eb1-beaf-7fcdfc24d9cb"
I0520 00:30:30.935021       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:35.942538       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:40.949553       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:45.961718       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0520 00:30:50.971192       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com