I0227 21:04:10.677255 1 main.go:92] Version: v3.4.0 I0227 21:04:10.678249 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0227 21:04:11.679279 1 common.go:111] Probing CSI driver for readiness I0227 21:04:11.679330 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0227 21:04:11.679335 1 connection.go:184] GRPC request: {} I0227 21:04:11.681789 1 connection.go:186] GRPC response: {} I0227 21:04:11.681837 1 connection.go:187] GRPC error: I0227 21:04:11.681843 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0227 21:04:11.681846 1 connection.go:184] GRPC request: {} I0227 21:04:11.682531 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0227 21:04:11.682570 1 connection.go:187] GRPC error: I0227 21:04:11.682577 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0227 21:04:11.682965 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0227 21:04:11.683967 1 common.go:111] Probing CSI driver for readiness I0227 21:04:11.683978 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0227 21:04:11.683981 1 connection.go:184] GRPC request: {} I0227 21:04:11.687535 1 connection.go:186] GRPC response: {} I0227 21:04:11.687554 1 connection.go:187] GRPC error: I0227 21:04:11.687558 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0227 21:04:11.687565 1 connection.go:184] GRPC request: {} I0227 21:04:11.688793 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0227 21:04:11.689074 1 connection.go:187] GRPC error: I0227 21:04:11.689086 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0227 21:04:11.689092 1 connection.go:184] GRPC request: {} I0227 21:04:11.689764 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0227 21:04:11.689849 1 connection.go:187] GRPC error: I0227 21:04:11.689857 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0227 21:04:11.690257 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0227 21:04:11.700389 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:11.700521 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-grctn I0227 21:04:11.700791 1 leader_election.go:205] became leader, starting I0227 21:04:11.700803 1 controller.go:128] Starting CSI attacher I0227 21:04:11.700929 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0227 21:04:11.700938 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0227 21:04:11.701136 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0227 21:04:11.701148 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0227 21:04:11.707583 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:11.801825 1 shared_informer.go:270] caches populated I0227 21:04:16.719424 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:21.734190 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:26.748892 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:31.758235 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:36.770465 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:41.779226 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:46.791445 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:51.800243 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:04:56.807202 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:01.819513 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:06.837962 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:11.847528 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:16.860235 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:21.873194 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:26.881805 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:31.896952 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:36.904688 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:41.913549 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:46.929651 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:51.937971 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:05:56.945735 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:01.954543 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:06.967270 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:11.977089 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:16.987230 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:21.999131 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:27.006697 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:32.014632 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:37.024287 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:42.032825 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:47.042956 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:52.051444 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:06:57.062278 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:02.072065 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:07.082643 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:07.653341 1 controller.go:208] Started VA processing "csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab" I0227 21:07:07.653365 1 trivial_handler.go:53] Trivial sync[csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab] started I0227 21:07:07.653369 1 util.go:38] Marking as attached "csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab" I0227 21:07:07.661289 1 util.go:52] Marked as attached "csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab" I0227 21:07:07.661325 1 trivial_handler.go:61] Marked VolumeAttachment csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab as attached I0227 21:07:07.661372 1 controller.go:208] Started VA processing "csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab" I0227 21:07:07.661384 1 trivial_handler.go:53] Trivial sync[csi-2bbf20d920626dd1972bd2772f67e39553d2ea3c8ee231c23bb3c1bec4a04eab] started I0227 21:07:12.094279 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:17.104526 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:22.113995 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:22.480000 1 controller.go:259] Started PV processing "pvc-11a33ced-d3f5-4a5e-9c7e-ab6718ef322d" I0227 21:07:27.124435 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:32.132384 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:37.138335 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:42.148204 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0227 21:07:47.157285 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com