I0224 02:30:42.552020 1 main.go:92] Version: v3.4.0 I0224 02:30:42.553339 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0224 02:30:43.554161 1 common.go:111] Probing CSI driver for readiness I0224 02:30:43.554195 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0224 02:30:43.554202 1 connection.go:184] GRPC request: {} I0224 02:30:43.558969 1 connection.go:186] GRPC response: {} I0224 02:30:43.559094 1 connection.go:187] GRPC error: I0224 02:30:43.559107 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0224 02:30:43.559111 1 connection.go:184] GRPC request: {} I0224 02:30:43.561500 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0224 02:30:43.561584 1 connection.go:187] GRPC error: I0224 02:30:43.561594 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0224 02:30:43.562306 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0224 02:30:43.562858 1 common.go:111] Probing CSI driver for readiness I0224 02:30:43.562873 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0224 02:30:43.562877 1 connection.go:184] GRPC request: {} I0224 02:30:43.563595 1 connection.go:186] GRPC response: {} I0224 02:30:43.563628 1 connection.go:187] GRPC error: I0224 02:30:43.563636 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0224 02:30:43.563640 1 connection.go:184] GRPC request: {} I0224 02:30:43.564477 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0224 02:30:43.564622 1 connection.go:187] GRPC error: I0224 02:30:43.564628 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0224 02:30:43.564632 1 connection.go:184] GRPC request: {} I0224 02:30:43.565540 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0224 02:30:43.565642 1 connection.go:187] GRPC error: I0224 02:30:43.565653 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0224 02:30:43.566164 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0224 02:30:43.578557 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:30:43.578616 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-hrqmf I0224 02:30:43.578754 1 leader_election.go:205] became leader, starting I0224 02:30:43.578772 1 controller.go:128] Starting CSI attacher I0224 02:30:43.579160 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0224 02:30:43.579173 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0224 02:30:43.579389 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0224 02:30:43.579423 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0224 02:30:43.587649 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:30:43.679622 1 shared_informer.go:270] caches populated I0224 02:30:48.597133 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:30:53.606862 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:30:58.614176 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:03.622869 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:08.631042 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:13.641352 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:18.652099 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:23.662062 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:28.669047 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:33.678281 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:38.691145 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:43.702132 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:48.711329 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:53.722092 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:31:58.730302 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:03.743032 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:08.753640 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:13.759482 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:18.769734 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:23.779778 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:28.788152 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:33.795992 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:38.803333 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:43.811992 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:48.822575 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:53.830487 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:32:58.840223 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:03.850093 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:08.861064 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:13.873726 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:18.884346 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:23.894963 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:28.904554 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:33.913499 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:36.627002 1 controller.go:208] Started VA processing "csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c" I0224 02:33:36.627047 1 trivial_handler.go:53] Trivial sync[csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c] started I0224 02:33:36.627056 1 util.go:38] Marking as attached "csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c" I0224 02:33:36.633558 1 util.go:52] Marked as attached "csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c" I0224 02:33:36.633602 1 trivial_handler.go:61] Marked VolumeAttachment csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c as attached I0224 02:33:36.633657 1 controller.go:208] Started VA processing "csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c" I0224 02:33:36.633674 1 trivial_handler.go:53] Trivial sync[csi-9404213fb84ce3fbe54898352780eb263e3080d45fd2000c8e1c00b34674f41c] started I0224 02:33:38.930959 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:43.940381 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:48.950085 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:53.958658 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:33:58.968684 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:34:03.978601 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:34:05.921525 1 controller.go:259] Started PV processing "pvc-466067ac-5c05-4aaa-bac9-fb372ce11f87" I0224 02:34:08.984934 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:34:13.991053 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:34:18.997590 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0224 02:34:24.006231 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com