I0512 05:49:47.511448       1 main.go:93] Version : v1.3.0
I0512 05:49:47.511507       1 feature_gate.go:245] feature gates: &{map[]}
I0512 05:49:47.513432       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0512 05:49:57.513741       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0512 05:49:57.866834       1 common.go:111] Probing CSI driver for readiness
I0512 05:49:57.866877       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0512 05:49:57.866886       1 connection.go:184] GRPC request: {}
I0512 05:49:57.875829       1 connection.go:186] GRPC response: {}
I0512 05:49:57.875934       1 connection.go:187] GRPC error: <nil>
I0512 05:49:57.875953       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0512 05:49:57.875962       1 connection.go:184] GRPC request: {}
I0512 05:49:57.878452       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0512 05:49:57.878582       1 connection.go:187] GRPC error: <nil>
I0512 05:49:57.878601       1 main.go:141] CSI driver name: "rbd.csi.ceph.com"
I0512 05:49:57.878617       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0512 05:49:57.878625       1 connection.go:184] GRPC request: {}
I0512 05:49:57.879898       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0512 05:49:57.880099       1 connection.go:187] GRPC error: <nil>
I0512 05:49:57.880116       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0512 05:49:57.880122       1 connection.go:184] GRPC request: {}
I0512 05:49:57.883644       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0512 05:49:57.883767       1 connection.go:187] GRPC error: <nil>
I0512 05:49:57.883779       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0512 05:49:57.883783       1 connection.go:184] GRPC request: {}
I0512 05:49:57.884402       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0512 05:49:57.884586       1 connection.go:187] GRPC error: <nil>
I0512 05:49:57.886459       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-resizer-rbd-csi-ceph-com...
I0512 05:49:57.903552       1 leaderelection.go:258] successfully acquired lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:49:57.903690       1 leader_election.go:205] became leader, starting
I0512 05:49:57.903732       1 controller.go:251] Starting external resizer rbd.csi.ceph.com
I0512 05:49:57.903800       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-cs5sp
I0512 05:49:57.904130       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0512 05:49:57.904151       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0512 05:49:57.906917       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (10m0s) from k8s.io/client-go/informers/factory.go:134
I0512 05:49:57.906965       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0512 05:49:57.913734       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:49:58.003891       1 shared_informer.go:270] caches populated
I0512 05:50:02.923943       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:07.934620       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:12.945796       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:17.957495       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:22.965040       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:28.017060       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:33.023861       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:38.031972       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:43.041362       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:48.053906       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:53.061937       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:50:58.072064       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:03.085100       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:08.094199       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:13.103009       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:18.112058       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:23.123810       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:28.133313       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:33.141808       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:38.152949       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:43.163017       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:48.170842       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:53.183129       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:51:58.192284       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:03.202855       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:08.214215       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:13.226050       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:18.234982       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:23.249663       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:28.257960       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:33.267202       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:38.279076       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:43.286008       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:48.296749       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:53.304602       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:52:55.419538       1 controller.go:291] Started PVC processing "default/test-pvc"
W0512 05:52:55.419585       1 controller.go:318] PV "" bound to PVC default/test-pvc not found
I0512 05:52:57.590844       1 controller.go:291] Started PVC processing "default/test-pvc"
I0512 05:52:57.590893       1 controller.go:334] No need to resize PVC "default/test-pvc"
I0512 05:52:58.315223       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:03.325383       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:08.335172       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:13.345520       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:18.354290       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:23.361899       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:28.369674       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:33.532039       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:38.543567       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0512 05:53:43.553413       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com