I0222 17:53:11.757220 1 main.go:92] Version: v3.4.0 I0222 17:53:11.758500 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0222 17:53:12.759417 1 common.go:111] Probing CSI driver for readiness I0222 17:53:12.759454 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0222 17:53:12.759462 1 connection.go:184] GRPC request: {} I0222 17:53:12.766435 1 connection.go:186] GRPC response: {} I0222 17:53:12.766523 1 connection.go:187] GRPC error: I0222 17:53:12.771481 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0222 17:53:12.771508 1 connection.go:184] GRPC request: {} I0222 17:53:12.773100 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0222 17:53:12.773143 1 connection.go:187] GRPC error: I0222 17:53:12.773150 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0222 17:53:12.773854 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0222 17:53:12.774276 1 common.go:111] Probing CSI driver for readiness I0222 17:53:12.774300 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0222 17:53:12.774307 1 connection.go:184] GRPC request: {} I0222 17:53:12.774817 1 connection.go:186] GRPC response: {} I0222 17:53:12.774842 1 connection.go:187] GRPC error: I0222 17:53:12.774848 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0222 17:53:12.774850 1 connection.go:184] GRPC request: {} I0222 17:53:12.775430 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0222 17:53:12.775635 1 connection.go:187] GRPC error: I0222 17:53:12.775644 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0222 17:53:12.775650 1 connection.go:184] GRPC request: {} I0222 17:53:12.776178 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0222 17:53:12.776255 1 connection.go:187] GRPC error: I0222 17:53:12.776264 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0222 17:53:12.776782 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0222 17:53:12.959202 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:12.959429 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-2g9dw I0222 17:53:12.959458 1 leader_election.go:205] became leader, starting I0222 17:53:12.959584 1 controller.go:128] Starting CSI attacher I0222 17:53:12.959942 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0222 17:53:12.960538 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0222 17:53:12.960283 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0222 17:53:12.960849 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0222 17:53:13.013266 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:13.059860 1 shared_informer.go:270] caches populated I0222 17:53:18.022993 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:23.032564 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:28.040796 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:33.063969 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:38.074092 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:43.081747 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:48.092055 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:53.100451 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:53:58.108454 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:03.117813 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:08.125282 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:13.134339 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:18.140981 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:23.150213 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:28.161271 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:33.168587 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:38.179600 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:43.189541 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:48.198835 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:53.207824 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:54:58.216164 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:03.246993 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:08.256323 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:13.264510 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:18.273457 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:23.283110 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:28.291251 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:33.302247 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:38.311910 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:43.319758 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:48.327182 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:53.335903 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:55:58.344470 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:03.353076 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:06.854556 1 controller.go:208] Started VA processing "csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64" I0222 17:56:06.854581 1 trivial_handler.go:53] Trivial sync[csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64] started I0222 17:56:06.854585 1 util.go:38] Marking as attached "csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64" I0222 17:56:06.859731 1 util.go:52] Marked as attached "csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64" I0222 17:56:06.859772 1 trivial_handler.go:61] Marked VolumeAttachment csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64 as attached I0222 17:56:06.859987 1 controller.go:208] Started VA processing "csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64" I0222 17:56:06.860017 1 trivial_handler.go:53] Trivial sync[csi-c684b4b490942c43a1ce8c8acc253302bb5e1f613c5fc049b87e1cef9ac58b64] started I0222 17:56:08.360841 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:13.368659 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:18.378226 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:23.387659 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:28.397123 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:28.405967 1 controller.go:259] Started PV processing "pvc-89c6c7c5-011e-4fa6-bbab-1bed7ce92517" I0222 17:56:33.403365 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:38.412149 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:43.419265 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0222 17:56:48.428470 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com