I0324 14:53:50.040837 1 main.go:92] Version: v3.4.0 I0324 14:53:50.041764 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0324 14:53:51.043684 1 common.go:111] Probing CSI driver for readiness I0324 14:53:51.043713 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0324 14:53:51.043720 1 connection.go:184] GRPC request: {} I0324 14:53:51.047869 1 connection.go:186] GRPC response: {} I0324 14:53:51.047943 1 connection.go:187] GRPC error: I0324 14:53:51.047956 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0324 14:53:51.047961 1 connection.go:184] GRPC request: {} I0324 14:53:51.048608 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0324 14:53:51.048691 1 connection.go:187] GRPC error: I0324 14:53:51.048704 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0324 14:53:51.049639 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0324 14:53:51.050477 1 common.go:111] Probing CSI driver for readiness I0324 14:53:51.050505 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0324 14:53:51.050511 1 connection.go:184] GRPC request: {} I0324 14:53:51.051451 1 connection.go:186] GRPC response: {} I0324 14:53:51.051472 1 connection.go:187] GRPC error: I0324 14:53:51.051477 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0324 14:53:51.051481 1 connection.go:184] GRPC request: {} I0324 14:53:51.052576 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0324 14:53:51.052746 1 connection.go:187] GRPC error: I0324 14:53:51.052753 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0324 14:53:51.052758 1 connection.go:184] GRPC request: {} I0324 14:53:51.056817 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0324 14:53:51.057192 1 connection.go:187] GRPC error: I0324 14:53:51.057221 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0324 14:53:51.058584 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0324 14:53:51.072874 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:53:51.072975 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-45z7s I0324 14:53:51.073015 1 leader_election.go:205] became leader, starting I0324 14:53:51.073057 1 controller.go:128] Starting CSI attacher I0324 14:53:51.073412 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0324 14:53:51.073433 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0324 14:53:51.073439 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0324 14:53:51.073453 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0324 14:53:51.080151 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:53:51.173221 1 shared_informer.go:270] caches populated I0324 14:53:56.089566 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:01.097021 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:06.108373 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:11.116065 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:16.124840 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:21.131471 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:26.141012 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:31.146768 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:36.153613 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:41.161055 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:46.170929 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:51.179288 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:54:56.188094 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:01.199813 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:06.209132 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:11.218667 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:16.230167 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:21.243812 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:26.254248 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:31.262875 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:36.269533 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:41.278860 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:46.296648 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:51.303419 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:55:56.312905 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:01.321040 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:06.343114 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:11.353829 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:16.363858 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:21.375201 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:26.384777 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:31.394077 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:36.402950 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:41.413889 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:46.426150 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:51.437104 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:56:55.113326 1 controller.go:208] Started VA processing "csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef" I0324 14:56:55.113361 1 trivial_handler.go:53] Trivial sync[csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef] started I0324 14:56:55.113367 1 util.go:38] Marking as attached "csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef" I0324 14:56:55.119826 1 util.go:52] Marked as attached "csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef" I0324 14:56:55.119918 1 trivial_handler.go:61] Marked VolumeAttachment csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef as attached I0324 14:56:55.120017 1 controller.go:208] Started VA processing "csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef" I0324 14:56:55.120098 1 trivial_handler.go:53] Trivial sync[csi-18a1ba98a3e762475c7dcd2167be07e96254470382ac0321d11fb8ca2e63ceef] started I0324 14:56:56.445262 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:01.453091 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:05.701642 1 controller.go:259] Started PV processing "pvc-a66faf7c-d1cc-4660-ad22-6b680df02e4b" I0324 14:57:06.464232 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:11.471627 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:16.478293 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:21.489333 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:26.498976 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0324 14:57:31.507382 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com