I0225 06:13:30.368514 1 main.go:92] Version: v3.4.0 I0225 06:13:30.369485 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0225 06:13:31.370897 1 common.go:111] Probing CSI driver for readiness I0225 06:13:31.370932 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0225 06:13:31.370940 1 connection.go:184] GRPC request: {} I0225 06:13:31.374662 1 connection.go:186] GRPC response: {} I0225 06:13:31.374734 1 connection.go:187] GRPC error: I0225 06:13:31.374746 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo I0225 06:13:31.374752 1 connection.go:184] GRPC request: {} I0225 06:13:31.377102 1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"} I0225 06:13:31.377183 1 connection.go:187] GRPC error: I0225 06:13:31.377196 1 main.go:148] CSI driver name: "rbd.csi.ceph.com" I0225 06:13:31.378166 1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock I0225 06:13:31.378866 1 common.go:111] Probing CSI driver for readiness I0225 06:13:31.378904 1 connection.go:183] GRPC call: /csi.v1.Identity/Probe I0225 06:13:31.378910 1 connection.go:184] GRPC request: {} I0225 06:13:31.379697 1 connection.go:186] GRPC response: {} I0225 06:13:31.379760 1 connection.go:187] GRPC error: I0225 06:13:31.379784 1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities I0225 06:13:31.379793 1 connection.go:184] GRPC request: {} I0225 06:13:31.382022 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]} I0225 06:13:31.382228 1 connection.go:187] GRPC error: I0225 06:13:31.382241 1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I0225 06:13:31.382245 1 connection.go:184] GRPC request: {} I0225 06:13:31.383004 1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]} I0225 06:13:31.383138 1 connection.go:187] GRPC error: I0225 06:13:31.383153 1 main.go:226] CSI driver does not support ControllerPublishUnpublish, using trivial handler I0225 06:13:31.383849 1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-attacher-leader-rbd-csi-ceph-com... I0225 06:13:31.396053 1 leaderelection.go:258] successfully acquired lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:13:31.396217 1 leader_election.go:205] became leader, starting I0225 06:13:31.396237 1 controller.go:128] Starting CSI attacher I0225 06:13:31.396396 1 reflector.go:219] Starting reflector *v1.VolumeAttachment (10m0s) from k8s.io/client-go/informers/factory.go:134 I0225 06:13:31.396407 1 reflector.go:255] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:134 I0225 06:13:31.396517 1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-z5phb I0225 06:13:31.396633 1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134 I0225 06:13:31.396671 1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134 I0225 06:13:31.402773 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:13:31.496861 1 shared_informer.go:270] caches populated I0225 06:13:36.412321 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:13:41.426034 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:13:46.433462 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:13:51.444268 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:13:56.454239 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:01.463683 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:06.476321 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:11.485321 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:16.494211 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:21.503173 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:26.511925 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:31.520448 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:36.530884 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:41.539037 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:46.550329 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:51.558140 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:14:56.567800 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:01.577876 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:06.586509 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:11.595391 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:16.603858 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:21.610366 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:26.620876 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:31.628639 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:36.636719 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:41.646039 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:46.656523 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:51.669215 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:15:56.677144 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:01.690467 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:06.703429 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:11.710583 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:16.720982 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:21.730002 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:23.883039 1 controller.go:208] Started VA processing "csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743" I0225 06:16:23.883065 1 trivial_handler.go:53] Trivial sync[csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743] started I0225 06:16:23.883070 1 util.go:38] Marking as attached "csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743" I0225 06:16:23.887986 1 util.go:52] Marked as attached "csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743" I0225 06:16:23.888013 1 trivial_handler.go:61] Marked VolumeAttachment csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743 as attached I0225 06:16:23.888152 1 controller.go:208] Started VA processing "csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743" I0225 06:16:23.888168 1 trivial_handler.go:53] Trivial sync[csi-30eed25f23e9b419499ed5d87e80778124fc9cb4bbad5bb625455920dd1f5743] started I0225 06:16:26.739515 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:31.747765 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:36.758897 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:41.768263 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:46.778195 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:50.766980 1 controller.go:259] Started PV processing "pvc-006828e4-5d4d-4bb1-8e35-71c3b4fbc307" I0225 06:16:51.785960 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:16:56.794371 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:17:01.801455 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:17:06.810408 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com I0225 06:17:11.820264 1 leaderelection.go:278] successfully renewed lease kube-system/external-attacher-leader-rbd-csi-ceph-com