I0225 19:33:36.864195 1 main.go:92] Version: v3.4.0 I0225 19:33:36.865684 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0225 19:33:37.866549 1 common.go:111] Probing CSI driver for readiness I0225 19:33:37.866568 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0225 19:33:37.866583 1 connection.go:184] GRPC request: {} I0225 19:33:37.869694 1 connection.go:186] GRPC response: {} I0225 19:33:37.869800 1 connection.go:187] GRPC error: I0225 19:33:37.869823 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0225 19:33:37.869833 1 connection.go:184] GRPC request: {} I0225 19:33:37.870800 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0225 19:33:37.870899 1 connection.go:187] GRPC error: I0225 19:33:37.870907 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0225 19:33:37.871563 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0225 19:33:37.871943 1 common.go:111] Probing CSI driver for readiness I0225 19:33:37.871958 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0225 19:33:37.871962 1 connection.go:184] GRPC request: {} I0225 19:33:37.872277 1 connection.go:186] GRPC response: {} I0225 19:33:37.872298 1 connection.go:187] GRPC error: I0225 19:33:37.872304 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0225 19:33:37.872307 1 connection.go:184] GRPC request: {} I0225 19:33:37.872868 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0225 19:33:37.872985 1 connection.go:187] GRPC error: I0225 19:33:37.872990 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0225 19:33:37.872994 1 connection.go:184] GRPC request: {} I0225 19:33:37.873443 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0225 19:33:37.873540 1 connection.go:187] GRPC error: I0225 19:33:37.873550 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0225 19:33:37.874048 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0225 19:33:37.884260 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:33:37.884291 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-lfflb I0225 19:33:37.884453 1 leader_election.go:205] became leader, starting I0225 19:33:37.884474 1 controller.go:128] Starting CSI attacher I0225 19:33:37.884766 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0225 19:33:37.884783 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0225 19:33:37.884902 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0225 19:33:37.884948 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0225 19:33:37.891634 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:33:37.984694 1 shared_informer.go:270] caches populated I0225 19:33:42.899484 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:33:47.907323 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:33:52.914652 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:33:57.924653 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:02.935778 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:07.944116 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:12.951993 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:17.960679 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:22.968486 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:27.977325 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:32.986030 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:37.993275 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:43.002177 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:48.011215 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:53.019819 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:34:58.027770 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:03.036905 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:08.046977 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:13.091747 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:18.098854 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:23.108263 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:28.116347 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:33.124626 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:38.133699 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:43.140150 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:48.147936 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:53.155442 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:35:58.164844 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:03.172306 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:08.181166 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:13.188078 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:18.195278 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:23.202387 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:28.209130 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:32.743961 1 controller.go:208] Started VA processing "csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5" I0225 19:36:32.743984 1 trivial_handler.go:53] Trivial sync[csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5] started I0225 19:36:32.743988 1 util.go:38] Marking as attached "csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5" I0225 19:36:32.750500 1 util.go:52] Marked as attached "csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5" I0225 19:36:32.750531 1 trivial_handler.go:61] Marked VolumeAttachment csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5 as attached I0225 19:36:32.750577 1 controller.go:208] Started VA processing "csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5" I0225 19:36:32.750595 1 trivial_handler.go:53] Trivial sync[csi-d1761558aba42b5d27ecce1b613602c92ceaf9315a2e8549bdcab0902e3ac5e5] started I0225 19:36:33.216763 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:38.229030 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:43.238069 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:48.244337 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:48.855294 1 controller.go:259] Started PV processing "pvc-3c47de52-1fa4-4096-8fe0-d3c8f24af66c" I0225 19:36:53.250388 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:36:58.258862 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:37:03.270425 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:37:08.277123 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 19:37:13.291366 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com