I0227 18:23:01.105949 1 main.go:92] Version: v3.4.0 I0227 18:23:01.106817 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0227 18:23:05.288214 1 common.go:111] Probing CSI driver for readiness I0227 18:23:05.288247 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0227 18:23:05.288253 1 connection.go:184] GRPC request: {} I0227 18:23:05.293916 1 connection.go:186] GRPC response: {} I0227 18:23:05.294075 1 connection.go:187] GRPC error: I0227 18:23:05.294592 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0227 18:23:05.294655 1 connection.go:184] GRPC request: {} I0227 18:23:05.295449 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0227 18:23:05.295547 1 connection.go:187] GRPC error: I0227 18:23:05.295557 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0227 18:23:05.296308 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0227 18:23:05.296750 1 common.go:111] Probing CSI driver for readiness I0227 18:23:05.296783 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0227 18:23:05.296790 1 connection.go:184] GRPC request: {} I0227 18:23:05.297557 1 connection.go:186] GRPC response: {} I0227 18:23:05.297680 1 connection.go:187] GRPC error: I0227 18:23:05.297714 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0227 18:23:05.297806 1 connection.go:184] GRPC request: {} I0227 18:23:05.298688 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0227 18:23:05.298889 1 connection.go:187] GRPC error: I0227 18:23:05.298899 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0227 18:23:05.298905 1 connection.go:184] GRPC request: {} I0227 18:23:05.299752 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0227 18:23:05.299940 1 connection.go:187] GRPC error: I0227 18:23:05.299967 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0227 18:23:05.300590 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0227 18:23:05.462104 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:05.462252 1 leader_election.go:205] became leader, starting I0227 18:23:05.462305 1 controller.go:128] Starting CSI attacher I0227 18:23:05.462196 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-slrd8 I0227 18:23:05.462977 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0227 18:23:05.463029 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0227 18:23:05.463102 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0227 18:23:05.463043 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0227 18:23:05.562561 1 shared_informer.go:270] caches populated I0227 18:23:06.542695 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:11.552558 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:16.561598 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:21.571112 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:26.580060 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:31.589950 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:36.600598 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:41.608351 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:46.619094 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:51.627837 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:23:56.640995 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:01.650640 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:06.659332 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:11.667257 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:16.679033 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:21.688170 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:26.695813 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:31.705575 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:36.714809 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:41.725712 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:46.734680 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:51.743137 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:24:56.751573 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:01.759067 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:06.767012 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:11.776451 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:16.790567 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:21.802977 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:26.811370 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:31.818646 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:36.825904 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:41.834096 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:46.843308 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:51.850700 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:25:56.860435 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:01.904297 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:02.542225 1 controller.go:208] Started VA processing "csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243" I0227 18:26:02.542253 1 trivial_handler.go:53] Trivial sync[csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243] started I0227 18:26:02.542258 1 util.go:38] Marking as attached "csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243" I0227 18:26:02.548058 1 util.go:52] Marked as attached "csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243" I0227 18:26:02.548081 1 trivial_handler.go:61] Marked VolumeAttachment csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243 as attached I0227 18:26:02.548117 1 controller.go:208] Started VA processing "csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243" I0227 18:26:02.548131 1 trivial_handler.go:53] Trivial sync[csi-a71008478315f876db91a2d7c38849f2021ad1d0dbcab68efd9ea4239f0d0243] started I0227 18:26:06.911833 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:11.919308 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:16.927662 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:21.935582 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:25.098282 1 controller.go:259] Started PV processing "pvc-6d4d442d-ed87-4507-9f0f-9aa1b135ee36" I0227 18:26:26.943813 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:31.949451 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:36.955572 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 18:26:41.963801 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com