I0412 20:53:42.334148 1 main.go:92] Version: v3.4.0 I0412 20:53:42.335779 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0412 20:53:43.337476 1 common.go:111] Probing CSI driver for readiness I0412 20:53:43.337508 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0412 20:53:43.337516 1 connection.go:184] GRPC request: {} I0412 20:53:43.342281 1 connection.go:186] GRPC response: {} I0412 20:53:43.342408 1 connection.go:187] GRPC error: I0412 20:53:43.342448 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0412 20:53:43.342462 1 connection.go:184] GRPC request: {} I0412 20:53:43.344233 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0412 20:53:43.344435 1 connection.go:187] GRPC error: I0412 20:53:43.344471 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0412 20:53:43.345655 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0412 20:53:43.346551 1 common.go:111] Probing CSI driver for readiness I0412 20:53:43.346574 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0412 20:53:43.346581 1 connection.go:184] GRPC request: {} I0412 20:53:43.347195 1 connection.go:186] GRPC response: {} I0412 20:53:43.347232 1 connection.go:187] GRPC error: I0412 20:53:43.347242 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0412 20:53:43.347247 1 connection.go:184] GRPC request: {} I0412 20:53:43.348154 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0412 20:53:43.348349 1 connection.go:187] GRPC error: I0412 20:53:43.348359 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0412 20:53:43.348365 1 connection.go:184] GRPC request: {} I0412 20:53:43.349330 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0412 20:53:43.349603 1 connection.go:187] GRPC error: I0412 20:53:43.349655 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0412 20:53:43.350519 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0412 20:53:43.363320 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:53:43.363438 1 leader_election.go:205] became leader, starting I0412 20:53:43.363460 1 controller.go:128] Starting CSI attacher I0412 20:53:43.363524 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-hgf5d I0412 20:53:43.363877 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0412 20:53:43.363900 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0412 20:53:43.363945 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0412 20:53:43.363977 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0412 20:53:43.371052 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:53:43.463602 1 shared_informer.go:270] caches populated I0412 20:53:48.379731 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:53:53.387902 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:53:58.396370 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:03.405891 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:08.416290 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:13.429330 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:18.439094 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:23.447869 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:28.456478 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:33.468043 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:38.479039 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:43.490243 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:48.498234 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:53.506002 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:54:58.515554 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:03.523418 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:08.533111 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:13.541449 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:18.556328 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:23.565382 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:28.577132 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:33.586980 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:38.594979 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:43.603728 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:48.613107 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:53.623670 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:55:58.630841 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:03.641658 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:08.651210 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:13.660315 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:18.671217 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:23.682849 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:28.694226 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:33.704203 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:35.554360 1 controller.go:208] Started VA processing "csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8" I0412 20:56:35.554402 1 trivial_handler.go:53] Trivial sync[csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8] started I0412 20:56:35.554409 1 util.go:38] Marking as attached "csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8" I0412 20:56:35.560449 1 util.go:52] Marked as attached "csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8" I0412 20:56:35.560477 1 trivial_handler.go:61] Marked VolumeAttachment csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8 as attached I0412 20:56:35.560703 1 controller.go:208] Started VA processing "csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8" I0412 20:56:35.560751 1 trivial_handler.go:53] Trivial sync[csi-24d5dc599b91660059534438e766da05581bd4ea5a102c8c38c93f4705748ed8] started I0412 20:56:38.712528 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:43.721161 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:48.729173 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:53.742592 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:56:58.512899 1 controller.go:259] Started PV processing "pvc-f583190c-43d3-4e9e-9eaf-f017baf47101" I0412 20:56:58.749753 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:57:03.756858 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:57:08.764020 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:57:13.771478 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0412 20:57:18.784004 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com