I0519 13:38:27.669599       1 main.go:93] Version : v1.3.0
I0519 13:38:27.669668       1 feature_gate.go:245] feature gates: &{map[]}
I0519 13:38:27.671314       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0519 13:38:34.803248       1 common.go:111] Probing CSI driver for readiness
I0519 13:38:34.803301       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0519 13:38:34.803306       1 connection.go:184] GRPC request: {}
I0519 13:38:34.810240       1 connection.go:186] GRPC response: {}
I0519 13:38:34.810337       1 connection.go:187] GRPC error: <nil>
I0519 13:38:34.810352       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0519 13:38:34.810357       1 connection.go:184] GRPC request: {}
I0519 13:38:34.811022       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0519 13:38:34.811072       1 connection.go:187] GRPC error: <nil>
I0519 13:38:34.811082       1 main.go:141] CSI driver name: "rbd.csi.ceph.com"
I0519 13:38:34.811091       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0519 13:38:34.811095       1 connection.go:184] GRPC request: {}
I0519 13:38:34.811720       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0519 13:38:34.811866       1 connection.go:187] GRPC error: <nil>
I0519 13:38:34.811875       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 13:38:34.811880       1 connection.go:184] GRPC request: {}
I0519 13:38:34.812600       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0519 13:38:34.812710       1 connection.go:187] GRPC error: <nil>
I0519 13:38:34.812718       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0519 13:38:34.812722       1 connection.go:184] GRPC request: {}
I0519 13:38:34.813229       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0519 13:38:34.813288       1 connection.go:187] GRPC error: <nil>
I0519 13:38:34.814060       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-resizer-rbd-csi-ceph-com...
I0519 13:38:34.827812       1 leaderelection.go:258] successfully acquired lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:38:34.827832       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-wd6wd
I0519 13:38:34.827999       1 leader_election.go:205] became leader, starting
I0519 13:38:34.828021       1 controller.go:251] Starting external resizer rbd.csi.ceph.com
I0519 13:38:34.828191       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0519 13:38:34.828198       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0519 13:38:34.828467       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (10m0s) from k8s.io/client-go/informers/factory.go:134
I0519 13:38:34.828480       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0519 13:38:34.837099       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:38:34.928769       1 shared_informer.go:270] caches populated
I0519 13:38:39.845016       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:38:44.878992       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:38:49.886212       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:38:54.895984       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:38:59.904920       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:04.912731       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:09.920271       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:14.929403       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:19.941112       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:24.952820       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:29.961824       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:34.969881       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:39.989217       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:44.999859       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:50.007858       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:39:55.017055       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:00.029529       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:05.038379       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:10.049742       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:15.059000       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:20.069544       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:25.079563       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:30.088022       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:35.099253       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:40.107953       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:45.118140       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:50.127564       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:40:55.135913       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:00.145623       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:05.154204       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:10.163930       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:15.179269       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:20.189970       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:25.232843       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:29.276286       1 controller.go:291] Started PVC processing "default/test-pvc"
W0519 13:41:29.276317       1 controller.go:318] PV "" bound to PVC default/test-pvc not found
I0519 13:41:30.242204       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:30.979021       1 controller.go:291] Started PVC processing "default/test-pvc"
I0519 13:41:30.979053       1 controller.go:334] No need to resize PVC "default/test-pvc"
I0519 13:41:35.251904       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:40.261938       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:45.269994       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:50.279971       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:41:55.288118       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:42:00.294313       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:42:05.300016       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:42:10.307038       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0519 13:42:15.317618       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com