I0208 16:38:47.677926 1 main.go:92] Version: v3.4.0 I0208 16:38:47.680410 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0208 16:38:48.681494 1 common.go:111] Probing CSI driver for readiness I0208 16:38:48.681513 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0208 16:38:48.681517 1 connection.go:184] GRPC request: {} I0208 16:38:48.683889 1 connection.go:186] GRPC response: {} I0208 16:38:48.683927 1 connection.go:187] GRPC error: I0208 16:38:48.683933 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0208 16:38:48.683935 1 connection.go:184] GRPC request: {} I0208 16:38:48.684455 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0208 16:38:48.684489 1 connection.go:187] GRPC error: I0208 16:38:48.684495 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0208 16:38:48.684880 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0208 16:38:48.685880 1 common.go:111] Probing CSI driver for readiness I0208 16:38:48.685892 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0208 16:38:48.685904 1 connection.go:184] GRPC request: {} I0208 16:38:48.688513 1 connection.go:186] GRPC response: {} I0208 16:38:48.688529 1 connection.go:187] GRPC error: I0208 16:38:48.688534 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0208 16:38:48.688536 1 connection.go:184] GRPC request: {} I0208 16:38:48.693844 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0208 16:38:48.693951 1 connection.go:187] GRPC error: I0208 16:38:48.694060 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0208 16:38:48.694070 1 connection.go:184] GRPC request: {} I0208 16:38:48.695721 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0208 16:38:48.695816 1 connection.go:187] GRPC error: I0208 16:38:48.695825 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0208 16:38:48.698532 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0208 16:38:48.718873 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:38:48.720283 1 leader_election.go:205] became leader, starting I0208 16:38:48.720310 1 controller.go:128] Starting CSI attacher I0208 16:38:48.720693 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0208 16:38:48.720705 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0208 16:38:48.721118 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0208 16:38:48.721129 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0208 16:38:48.724088 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-gbmhw I0208 16:38:48.733555 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:38:48.821029 1 shared_informer.go:270] caches populated I0208 16:38:53.743102 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:38:58.752490 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:03.759872 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:08.788774 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:13.794365 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:18.805464 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:23.826831 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:28.839739 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:33.845540 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:38.853809 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:43.864231 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:48.874043 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:53.904106 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:39:58.913190 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:03.926363 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:08.935693 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:13.942221 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:18.949021 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:23.960142 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:28.969878 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:33.974924 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:38.981775 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:44.067988 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:49.077684 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:54.107860 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:40:59.118069 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:04.126244 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:09.135804 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:14.160699 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:19.169317 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:24.176833 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:29.186771 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:34.195142 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:39.203669 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:40.005967 1 controller.go:208] Started VA processing "csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b" I0208 16:41:40.005991 1 trivial_handler.go:53] Trivial sync[csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b] started I0208 16:41:40.005995 1 util.go:38] Marking as attached "csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b" I0208 16:41:40.012182 1 util.go:52] Marked as attached "csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b" I0208 16:41:40.012378 1 trivial_handler.go:61] Marked VolumeAttachment csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b as attached I0208 16:41:40.012496 1 controller.go:208] Started VA processing "csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b" I0208 16:41:40.012604 1 trivial_handler.go:53] Trivial sync[csi-2508ec58c55972ee9f1df6d841527e58c3cec9eb0a0d8ec49cd8844804b33e5b] started I0208 16:41:44.210368 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:49.217775 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:54.607020 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:41:55.638587 1 controller.go:259] Started PV processing "pvc-891100b9-b490-4c14-ac88-ce9a90f45dea" I0208 16:41:59.612603 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:42:04.619411 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:42:09.627960 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:42:14.633176 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:42:19.639171 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:42:24.647987 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0208 16:42:29.664992 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com