I0224 13:04:38.494441 1 main.go:92] Version: v3.4.0 I0224 13:04:38.495552 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0224 13:04:39.496642 1 common.go:111] Probing CSI driver for readiness I0224 13:04:39.496678 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0224 13:04:39.496687 1 connection.go:184] GRPC request: {} I0224 13:04:39.503667 1 connection.go:186] GRPC response: {} I0224 13:04:39.503772 1 connection.go:187] GRPC error: I0224 13:04:39.503788 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0224 13:04:39.503793 1 connection.go:184] GRPC request: {} I0224 13:04:39.505497 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0224 13:04:39.505565 1 connection.go:187] GRPC error: I0224 13:04:39.505575 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0224 13:04:39.506177 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0224 13:04:39.506688 1 common.go:111] Probing CSI driver for readiness I0224 13:04:39.506734 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0224 13:04:39.506742 1 connection.go:184] GRPC request: {} I0224 13:04:39.507365 1 connection.go:186] GRPC response: {} I0224 13:04:39.507406 1 connection.go:187] GRPC error: I0224 13:04:39.507417 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0224 13:04:39.507423 1 connection.go:184] GRPC request: {} I0224 13:04:39.508591 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0224 13:04:39.508928 1 connection.go:187] GRPC error: I0224 13:04:39.508944 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0224 13:04:39.508953 1 connection.go:184] GRPC request: {} I0224 13:04:39.509907 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0224 13:04:39.510093 1 connection.go:187] GRPC error: I0224 13:04:39.510112 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0224 13:04:39.510889 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0224 13:04:39.523357 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:04:39.523702 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-86rhd I0224 13:04:39.524221 1 leader_election.go:205] became leader, starting I0224 13:04:39.524249 1 controller.go:128] Starting CSI attacher I0224 13:04:39.524537 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0224 13:04:39.524559 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0224 13:04:39.524996 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0224 13:04:39.525015 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0224 13:04:39.535713 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:04:39.624997 1 shared_informer.go:270] caches populated I0224 13:04:44.614340 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:04:49.633771 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:04:54.646003 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:04:59.655098 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:04.682612 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:09.692888 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:14.703534 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:19.712749 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:24.825424 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:29.854521 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:34.863596 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:39.891382 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:44.900589 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:49.908714 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:54.918131 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:05:59.969853 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:04.981581 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:09.994231 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:15.005466 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:20.024494 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:25.034061 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:30.041390 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:35.051500 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:40.059119 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:45.068325 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:50.076782 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:06:55.085445 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:00.092993 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:05.103277 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:10.112850 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:15.121518 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:20.139902 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:21.890919 1 controller.go:208] Started VA processing "csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7" I0224 13:07:21.890944 1 trivial_handler.go:53] Trivial sync[csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7] started I0224 13:07:21.890950 1 util.go:38] Marking as attached "csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7" I0224 13:07:21.912197 1 util.go:52] Marked as attached "csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7" I0224 13:07:21.912229 1 trivial_handler.go:61] Marked VolumeAttachment csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7 as attached I0224 13:07:21.912271 1 controller.go:208] Started VA processing "csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7" I0224 13:07:21.912407 1 trivial_handler.go:53] Trivial sync[csi-2f90a7d0742bd79d4284262c8f2ec6c3466ed04013a7b6c2efdddc30e3b8c7b7] started I0224 13:07:25.154639 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:30.170519 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:35.182204 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:40.197348 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:45.010327 1 controller.go:259] Started PV processing "pvc-d27b24f3-4138-47d5-b5a1-23c7962d5fdf" I0224 13:07:45.211640 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:50.219557 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:07:55.229027 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:08:00.236875 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 13:08:05.285222 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com