I0330 17:58:42.533799       1 main.go:93] Version : v1.3.0
I0330 17:58:42.533899       1 feature_gate.go:245] feature gates: &{map[]}
I0330 17:58:42.537179       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0330 17:58:45.416067       1 common.go:111] Probing CSI driver for readiness
I0330 17:58:45.416104       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0330 17:58:45.416114       1 connection.go:184] GRPC request: {}
I0330 17:58:45.425761       1 connection.go:186] GRPC response: {}
I0330 17:58:45.425902       1 connection.go:187] GRPC error: <nil>
I0330 17:58:45.425938       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0330 17:58:45.425949       1 connection.go:184] GRPC request: {}
I0330 17:58:45.426516       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0330 17:58:45.426606       1 connection.go:187] GRPC error: <nil>
I0330 17:58:45.426623       1 main.go:141] CSI driver name: "rbd.csi.ceph.com"
I0330 17:58:45.426640       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0330 17:58:45.426646       1 connection.go:184] GRPC request: {}
I0330 17:58:45.427770       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0330 17:58:45.427947       1 connection.go:187] GRPC error: <nil>
I0330 17:58:45.427963       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0330 17:58:45.427969       1 connection.go:184] GRPC request: {}
I0330 17:58:45.428785       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0330 17:58:45.428967       1 connection.go:187] GRPC error: <nil>
I0330 17:58:45.428982       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0330 17:58:45.428990       1 connection.go:184] GRPC request: {}
I0330 17:58:45.429511       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0330 17:58:45.429589       1 connection.go:187] GRPC error: <nil>
I0330 17:58:45.430637       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-resizer-rbd-csi-ceph-com...
I0330 17:58:45.442635       1 leaderelection.go:258] successfully acquired lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:58:45.442672       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-rtjdf
I0330 17:58:45.443594       1 leader_election.go:205] became leader, starting
I0330 17:58:45.443617       1 controller.go:251] Starting external resizer rbd.csi.ceph.com
I0330 17:58:45.443929       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0330 17:58:45.443975       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0330 17:58:45.444233       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (10m0s) from k8s.io/client-go/informers/factory.go:134
I0330 17:58:45.444352       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0330 17:58:45.452724       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:58:45.544623       1 shared_informer.go:270] caches populated
I0330 17:58:50.468135       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:58:55.477069       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:00.490614       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:05.498140       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:10.509208       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:15.518515       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:20.527190       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:25.538745       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:30.546302       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:35.555641       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:40.567627       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:45.576652       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:50.587718       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 17:59:55.599623       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:00.610543       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:05.623321       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:10.635719       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:15.643849       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:20.653594       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:25.665964       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:30.677181       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:35.686785       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:40.696154       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:45.705863       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:50.715551       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:00:55.725808       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:00.736422       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:05.750691       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:10.761527       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:15.773677       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:20.783585       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:25.794220       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:30.808704       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:35.821009       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:40.831831       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:45.844802       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:50.701740       1 controller.go:291] Started PVC processing "default/test-pvc"
W0330 18:01:50.701792       1 controller.go:318] PV "" bound to PVC default/test-pvc not found
I0330 18:01:50.873958       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:01:53.126394       1 controller.go:291] Started PVC processing "default/test-pvc"
I0330 18:01:53.126428       1 controller.go:334] No need to resize PVC "default/test-pvc"
I0330 18:01:55.882967       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:00.896203       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:05.918885       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:10.930869       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:15.939281       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:20.948529       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:25.958337       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:30.968416       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:35.978823       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0330 18:02:40.993999       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com