I0428 13:57:09.454574       1 main.go:93] Version : v1.3.0
I0428 13:57:09.454626       1 feature_gate.go:245] feature gates: &{map[]}
I0428 13:57:09.455953       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0428 13:57:15.630193       1 common.go:111] Probing CSI driver for readiness
I0428 13:57:15.630226       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0428 13:57:15.630234       1 connection.go:184] GRPC request: {}
I0428 13:57:15.640636       1 connection.go:186] GRPC response: {}
I0428 13:57:15.640724       1 connection.go:187] GRPC error: <nil>
I0428 13:57:15.640738       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0428 13:57:15.640743       1 connection.go:184] GRPC request: {}
I0428 13:57:15.641566       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0428 13:57:15.641651       1 connection.go:187] GRPC error: <nil>
I0428 13:57:15.641667       1 main.go:141] CSI driver name: "rbd.csi.ceph.com"
I0428 13:57:15.641687       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0428 13:57:15.641698       1 connection.go:184] GRPC request: {}
I0428 13:57:15.642668       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0428 13:57:15.642849       1 connection.go:187] GRPC error: <nil>
I0428 13:57:15.642859       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:15.642863       1 connection.go:184] GRPC request: {}
I0428 13:57:15.643608       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:57:15.643945       1 connection.go:187] GRPC error: <nil>
I0428 13:57:15.643954       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0428 13:57:15.643958       1 connection.go:184] GRPC request: {}
I0428 13:57:15.644510       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0428 13:57:15.644578       1 connection.go:187] GRPC error: <nil>
I0428 13:57:15.645664       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-resizer-rbd-csi-ceph-com...
I0428 13:57:15.696884       1 leaderelection.go:258] successfully acquired lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:15.696910       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-7x22v
I0428 13:57:15.696933       1 leader_election.go:205] became leader, starting
I0428 13:57:15.696953       1 controller.go:251] Starting external resizer rbd.csi.ceph.com
I0428 13:57:15.697185       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0428 13:57:15.697201       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0428 13:57:15.697659       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (10m0s) from k8s.io/client-go/informers/factory.go:134
I0428 13:57:15.697700       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0428 13:57:15.797913       1 shared_informer.go:270] caches populated
I0428 13:57:15.817804       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:20.827002       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:25.835582       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:30.852674       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:35.869185       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:40.877324       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:45.887955       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:50.898714       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:57:55.913121       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:00.921827       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:05.935266       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:10.946371       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:15.956909       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:20.966199       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:25.982193       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:30.994064       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:36.005281       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:41.020061       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:46.035681       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:51.044974       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:58:56.054477       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:01.064811       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:06.078250       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:11.089368       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:16.098778       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:21.107780       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:26.117674       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:31.126191       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:36.147614       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:41.162083       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:46.173184       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:51.188448       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 13:59:56.199660       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:01.210994       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:06.224925       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:11.235001       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:16.244714       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:21.263118       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:26.227443       1 controller.go:291] Started PVC processing "default/test-pvc"
W0428 14:00:26.227526       1 controller.go:318] PV "" bound to PVC default/test-pvc not found
I0428 14:00:26.274600       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:27.955758       1 controller.go:291] Started PVC processing "default/test-pvc"
I0428 14:00:27.955808       1 controller.go:334] No need to resize PVC "default/test-pvc"
I0428 14:00:31.285336       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:36.294279       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:41.304877       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:46.321552       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:51.329901       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:00:56.341703       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:01:01.353048       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:01:06.363942       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:01:15.916790       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0428 14:01:24.437684       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com