I0624 18:06:11.795061       1 main.go:92] Version: v3.4.0
I0624 18:06:11.796041       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0624 18:06:12.797095       1 common.go:111] Probing CSI driver for readiness
I0624 18:06:12.797140       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0624 18:06:12.797146       1 connection.go:184] GRPC request: {}
I0624 18:06:12.800310       1 connection.go:186] GRPC response: {}
I0624 18:06:12.800365       1 connection.go:187] GRPC error: <nil>
I0624 18:06:12.800374       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0624 18:06:12.800377       1 connection.go:184] GRPC request: {}
I0624 18:06:12.800914       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0624 18:06:12.801010       1 connection.go:187] GRPC error: <nil>
I0624 18:06:12.801023       1 main.go:148] CSI driver name: "rbd.csi.ceph.com"
I0624 18:06:12.801972       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0624 18:06:12.802656       1 common.go:111] Probing CSI driver for readiness
I0624 18:06:12.802714       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0624 18:06:12.802788       1 connection.go:184] GRPC request: {}
I0624 18:06:12.803882       1 connection.go:186] GRPC response: {}
I0624 18:06:12.804004       1 connection.go:187] GRPC error: <nil>
I0624 18:06:12.804060       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0624 18:06:12.804075       1 connection.go:184] GRPC request: {}
I0624 18:06:12.805691       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0624 18:06:12.805924       1 connection.go:187] GRPC error: <nil>
I0624 18:06:12.805949       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0624 18:06:12.805967       1 connection.go:184] GRPC request: {}
I0624 18:06:12.807004       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0624 18:06:12.807150       1 connection.go:187] GRPC error: <nil>
I0624 18:06:12.807178       1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler
I0624 18:06:12.807997       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com...
I0624 18:06:12.820011       1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:12.820055       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-vw5sw
I0624 18:06:12.820144       1 leader_election.go:205] became leader, starting
I0624 18:06:12.820195       1 controller.go:128] Starting CSI attacher
I0624 18:06:12.820586       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0624 18:06:12.820609       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0624 18:06:12.820630       1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134
I0624 18:06:12.820654       1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134
I0624 18:06:12.827788       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:12.920679       1 shared_informer.go:270] caches populated
I0624 18:06:17.839000       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:22.847554       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:27.858311       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:32.871106       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:37.898665       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:42.907942       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:47.916376       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:52.926203       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:06:57.935376       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:02.943253       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:07.951609       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:12.957426       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:17.967915       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:22.976076       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:27.984198       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:32.991149       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:38.000245       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:43.006327       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:48.018890       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:53.026956       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:07:58.035769       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:03.045496       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:08.054638       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:13.063056       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:18.072516       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:23.083365       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:28.093543       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:33.102455       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:38.117156       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:43.124243       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:48.133441       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:53.140540       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:08:58.149450       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:03.157123       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:08.167293       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:13.177431       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:18.185600       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:23.193649       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:28.204096       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:33.211865       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:38.220887       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:43.229703       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:48.237167       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:53.242945       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:09:53.985470       1 controller.go:208] Started VA processing "csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9"
I0624 18:09:53.985509       1 trivial_handler.go:53] Trivial sync[csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9] started
I0624 18:09:53.985518       1 util.go:38] Marking as attached "csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9"
I0624 18:09:53.991893       1 util.go:52] Marked as attached "csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9"
I0624 18:09:53.991922       1 trivial_handler.go:61] Marked VolumeAttachment csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9 as attached
I0624 18:09:53.991962       1 controller.go:208] Started VA processing "csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9"
I0624 18:09:53.991972       1 trivial_handler.go:53] Trivial sync[csi-78d96431156e66cfae4868427c3cad5ca83a78c70fcf76977f784b7332a217c9] started
I0624 18:09:58.253119       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:03.265049       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:06.398545       1 controller.go:259] Started PV processing "pvc-10f60603-befd-4281-a85f-b208511cf2a9"
I0624 18:10:08.276858       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:13.288388       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:18.299493       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:23.307446       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:28.315967       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:33.323559       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com
I0624 18:10:38.333303       1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com