I0225 02:21:29.821257 1 main.go:92] Version: v3.4.0 I0225 02:21:29.822273 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0225 02:21:30.824500 1 common.go:111] Probing CSI driver for readiness I0225 02:21:30.824534 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0225 02:21:30.824541 1 connection.go:184] GRPC request: {} I0225 02:21:30.828478 1 connection.go:186] GRPC response: {} I0225 02:21:30.828551 1 connection.go:187] GRPC error: I0225 02:21:30.828572 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0225 02:21:30.828577 1 connection.go:184] GRPC request: {} I0225 02:21:30.829344 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0225 02:21:30.830199 1 connection.go:187] GRPC error: I0225 02:21:30.830226 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0225 02:21:30.832210 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0225 02:21:30.832930 1 common.go:111] Probing CSI driver for readiness I0225 02:21:30.832990 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0225 02:21:30.833012 1 connection.go:184] GRPC request: {} I0225 02:21:30.833543 1 connection.go:186] GRPC response: {} I0225 02:21:30.833592 1 connection.go:187] GRPC error: I0225 02:21:30.833603 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0225 02:21:30.833682 1 connection.go:184] GRPC request: {} I0225 02:21:30.834546 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0225 02:21:30.834716 1 connection.go:187] GRPC error: I0225 02:21:30.834737 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0225 02:21:30.834755 1 connection.go:184] GRPC request: {} I0225 02:21:30.835544 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0225 02:21:30.835658 1 connection.go:187] GRPC error: I0225 02:21:30.835673 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0225 02:21:30.836347 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0225 02:21:30.847867 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:21:30.847966 1 leader_election.go:205] became leader, starting I0225 02:21:30.847988 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-s75sx I0225 02:21:30.848070 1 controller.go:128] Starting CSI attacher I0225 02:21:30.848236 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0225 02:21:30.848285 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0225 02:21:30.848924 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0225 02:21:30.848938 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0225 02:21:30.856242 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:21:30.948379 1 shared_informer.go:270] caches populated I0225 02:21:35.864737 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:21:40.873222 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:21:45.882838 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:21:50.893109 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:21:55.902452 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:00.910152 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:05.920386 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:10.931832 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:15.941141 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:20.956206 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:25.966722 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:30.975143 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:35.984078 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:40.994392 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:46.005243 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:51.013668 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:22:56.022407 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:01.031789 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:06.039713 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:11.049801 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:16.059687 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:21.066852 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:26.074977 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:31.082783 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:36.092344 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:41.099546 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:46.108839 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:51.116346 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:23:56.128700 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:01.139006 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:06.149468 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:11.161259 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:16.170021 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:21.180843 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:26.189814 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:27.900150 1 controller.go:208] Started VA processing "csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540" I0225 02:24:27.900177 1 trivial_handler.go:53] Trivial sync[csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540] started I0225 02:24:27.900182 1 util.go:38] Marking as attached "csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540" I0225 02:24:27.906058 1 util.go:52] Marked as attached "csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540" I0225 02:24:27.906082 1 trivial_handler.go:61] Marked VolumeAttachment csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540 as attached I0225 02:24:27.906123 1 controller.go:208] Started VA processing "csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540" I0225 02:24:27.906134 1 trivial_handler.go:53] Trivial sync[csi-26207dad2c6043d46a3ccb04e8856e732d51733297d3163f0b1e2baba369b540] started I0225 02:24:31.202464 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:36.213538 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:41.223752 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:44.624036 1 controller.go:259] Started PV processing "pvc-0ea95639-3795-4a00-9af1-ed739f722e01" I0225 02:24:46.234733 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:51.242734 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:24:56.278301 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:25:01.297917 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:25:06.318194 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 02:25:11.326202 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com