I0212 03:44:20.875525 1 main.go:92] Version: v3.4.0 I0212 03:44:20.876904 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0212 03:44:21.879396 1 common.go:111] Probing CSI driver for readiness I0212 03:44:21.879414 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0212 03:44:21.879418 1 connection.go:184] GRPC request: {} I0212 03:44:21.881941 1 connection.go:186] GRPC response: {} I0212 03:44:21.882059 1 connection.go:187] GRPC error: I0212 03:44:21.882075 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0212 03:44:21.882081 1 connection.go:184] GRPC request: {} I0212 03:44:21.883230 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0212 03:44:21.883288 1 connection.go:187] GRPC error: I0212 03:44:21.883295 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0212 03:44:21.883693 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0212 03:44:21.886327 1 common.go:111] Probing CSI driver for readiness I0212 03:44:21.886339 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0212 03:44:21.886343 1 connection.go:184] GRPC request: {} I0212 03:44:21.889274 1 connection.go:186] GRPC response: {} I0212 03:44:21.889760 1 connection.go:187] GRPC error: I0212 03:44:21.889816 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0212 03:44:21.889853 1 connection.go:184] GRPC request: {} I0212 03:44:21.891427 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0212 03:44:21.891643 1 connection.go:187] GRPC error: I0212 03:44:21.891693 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0212 03:44:21.891732 1 connection.go:184] GRPC request: {} I0212 03:44:21.892466 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0212 03:44:21.892596 1 connection.go:187] GRPC error: I0212 03:44:21.892657 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0212 03:44:21.893705 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0212 03:44:21.908841 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:21.910016 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-5fvrz I0212 03:44:21.910604 1 leader_election.go:205] became leader, starting I0212 03:44:21.910682 1 controller.go:128] Starting CSI attacher I0212 03:44:21.912076 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0212 03:44:21.912145 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0212 03:44:21.915258 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:21.916296 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0212 03:44:21.916763 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0212 03:44:22.011075 1 shared_informer.go:270] caches populated I0212 03:44:26.922814 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:31.930778 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:36.996395 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:42.005153 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:47.015728 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:52.023988 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:44:57.031952 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:02.040265 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:07.048795 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:12.057231 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:17.067836 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:22.075595 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:27.087175 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:32.095615 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:37.103813 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:42.112982 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:47.120946 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:52.129100 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:45:57.149972 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:02.155096 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:07.163904 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:12.175101 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:17.185768 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:22.198172 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:27.207222 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:32.216098 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:37.225468 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:42.232922 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:47.240686 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:52.250209 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:46:57.259895 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:02.268552 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:07.274563 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:12.282058 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:15.609743 1 controller.go:208] Started VA processing "csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e" I0212 03:47:15.609831 1 trivial_handler.go:53] Trivial sync[csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e] started I0212 03:47:15.609866 1 util.go:38] Marking as attached "csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e" I0212 03:47:15.616349 1 util.go:52] Marked as attached "csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e" I0212 03:47:15.616452 1 trivial_handler.go:61] Marked VolumeAttachment csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e as attached I0212 03:47:15.616797 1 controller.go:208] Started VA processing "csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e" I0212 03:47:15.616883 1 trivial_handler.go:53] Trivial sync[csi-d09c8351a108596c3d091814051208a165c2a0b473f4856bbff43de0ffa5408e] started I0212 03:47:17.300226 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:22.307447 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:27.314649 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:32.322545 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:36.260042 1 controller.go:259] Started PV processing "pvc-132b4d7a-54e9-41fa-93f1-a2b40515a566" I0212 03:47:37.328860 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:42.333930 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:47.339430 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:52.347327 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:47:57.360774 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0212 03:48:02.369981 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com