I0226 21:40:36.476022 1 main.go:92] Version: v3.4.0 I0226 21:40:36.478719 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0226 21:40:41.646787 1 common.go:111] Probing CSI driver for readiness I0226 21:40:41.646825 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0226 21:40:41.646833 1 connection.go:184] GRPC request: {} I0226 21:40:41.652646 1 connection.go:186] GRPC response: {} I0226 21:40:41.653661 1 connection.go:187] GRPC error: I0226 21:40:41.653716 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0226 21:40:41.653745 1 connection.go:184] GRPC request: {} I0226 21:40:41.656348 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0226 21:40:41.658691 1 connection.go:187] GRPC error: I0226 21:40:41.658740 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0226 21:40:41.660818 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0226 21:40:41.661455 1 common.go:111] Probing CSI driver for readiness I0226 21:40:41.661471 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0226 21:40:41.661476 1 connection.go:184] GRPC request: {} I0226 21:40:41.662151 1 connection.go:186] GRPC response: {} I0226 21:40:41.662198 1 connection.go:187] GRPC error: I0226 21:40:41.662342 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0226 21:40:41.662353 1 connection.go:184] GRPC request: {} I0226 21:40:41.663224 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0226 21:40:41.663443 1 connection.go:187] GRPC error: I0226 21:40:41.663453 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0226 21:40:41.663460 1 connection.go:184] GRPC request: {} I0226 21:40:41.664027 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0226 21:40:41.664144 1 connection.go:187] GRPC error: I0226 21:40:41.664158 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0226 21:40:41.664746 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0226 21:40:42.309077 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:40:42.309114 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-4fblh I0226 21:40:42.309371 1 leader_election.go:205] became leader, starting I0226 21:40:42.309394 1 controller.go:128] Starting CSI attacher I0226 21:40:42.309660 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0226 21:40:42.309671 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0226 21:40:42.311249 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0226 21:40:42.311277 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0226 21:40:42.409679 1 shared_informer.go:270] caches populated I0226 21:40:43.967443 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:40:48.977504 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:40:53.988805 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:40:59.130611 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:04.145524 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:09.153105 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:14.166115 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:19.191256 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:24.200313 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:29.223122 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:34.232563 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:39.244039 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:44.263480 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:49.272656 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:54.281490 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:41:59.291829 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:04.307416 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:09.330014 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:14.338765 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:19.348458 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:24.388098 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:29.400175 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:34.410763 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:39.420778 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:44.430387 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:49.438144 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:54.447078 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:42:59.455519 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:04.463431 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:09.472552 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:14.484920 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:19.496144 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:24.505493 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:29.516728 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:34.541840 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:39.551588 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:40.584344 1 controller.go:208] Started VA processing "csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8" I0226 21:43:40.584381 1 trivial_handler.go:53] Trivial sync[csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8] started I0226 21:43:40.584388 1 util.go:38] Marking as attached "csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8" I0226 21:43:40.591635 1 util.go:52] Marked as attached "csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8" I0226 21:43:40.591668 1 trivial_handler.go:61] Marked VolumeAttachment csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8 as attached I0226 21:43:40.591713 1 controller.go:208] Started VA processing "csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8" I0226 21:43:40.591726 1 trivial_handler.go:53] Trivial sync[csi-509f438df650f84b761d84d15d5f4d009d6d9dbd90b5b2a7af9de051de1f63e8] started I0226 21:43:44.562426 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:49.572351 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:54.580291 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:43:59.670687 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:44:04.680676 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:44:09.689802 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:44:10.778737 1 controller.go:259] Started PV processing "pvc-b1e51905-dadc-4b52-b862-e434273baf35" I0226 21:44:14.697953 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:44:19.707526 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0226 21:44:24.718277 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com