I0922 03:55:27.454856       1 main.go:93] Version : v1.3.0
I0922 03:55:27.454923       1 feature_gate.go:245] feature gates: &{map[]}
I0922 03:55:27.461877       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0922 03:55:32.821994       1 common.go:111] Probing CSI driver for readiness
I0922 03:55:32.822024       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0922 03:55:32.822032       1 connection.go:184] GRPC request: {}
I0922 03:55:32.825916       1 connection.go:186] GRPC response: {}
I0922 03:55:32.826003       1 connection.go:187] GRPC error: <nil>
I0922 03:55:32.826016       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0922 03:55:32.826023       1 connection.go:184] GRPC request: {}
I0922 03:55:32.826683       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0922 03:55:32.826749       1 connection.go:187] GRPC error: <nil>
I0922 03:55:32.826763       1 main.go:141] CSI driver name: "rbd.csi.ceph.com"
I0922 03:55:32.826774       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0922 03:55:32.826779       1 connection.go:184] GRPC request: {}
I0922 03:55:32.827737       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0922 03:55:32.827974       1 connection.go:187] GRPC error: <nil>
I0922 03:55:32.827997       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0922 03:55:32.828004       1 connection.go:184] GRPC request: {}
I0922 03:55:32.828873       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0922 03:55:32.829018       1 connection.go:187] GRPC error: <nil>
I0922 03:55:32.829030       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0922 03:55:32.829036       1 connection.go:184] GRPC request: {}
I0922 03:55:32.829760       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0922 03:55:32.829860       1 connection.go:187] GRPC error: <nil>
I0922 03:55:32.835772       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-resizer-rbd-csi-ceph-com...
I0922 03:55:32.850243       1 leaderelection.go:258] successfully acquired lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:55:32.850297       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-bmhdx
I0922 03:55:32.850488       1 leader_election.go:205] became leader, starting
I0922 03:55:32.850519       1 controller.go:251] Starting external resizer rbd.csi.ceph.com
I0922 03:55:32.850816       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0922 03:55:32.850832       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0922 03:55:32.850917       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (10m0s) from k8s.io/client-go/informers/factory.go:134
I0922 03:55:32.850942       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0922 03:55:32.862010       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:55:32.950724       1 shared_informer.go:270] caches populated
I0922 03:55:37.870712       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:55:42.883710       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:55:47.899473       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:55:52.908479       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:55:57.917091       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:02.926876       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:07.936024       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:12.945224       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:17.958250       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:22.966455       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:27.976322       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:32.985356       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:37.996749       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:43.004053       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:48.011848       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:53.021577       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:56:58.032538       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:03.041344       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:08.049959       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:13.058825       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:18.067165       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:23.078596       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:28.086495       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:33.098295       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:38.106272       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:43.114719       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:48.123995       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:53.131983       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:57:58.142814       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:03.151526       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:08.162506       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:13.171022       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:18.179455       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:23.190430       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:28.198413       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:33.205604       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:38.212207       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:43.220868       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:48.228179       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:53.236928       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:58:58.246733       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:03.255316       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:08.264419       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:13.274650       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:18.283095       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:23.293803       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:28.302527       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:33.313728       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:38.323397       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:43.332174       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:48.340383       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:53.350831       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 03:59:57.646110       1 controller.go:291] Started PVC processing "default/test-pvc"
W0922 03:59:57.646144       1 controller.go:318] PV "" bound to PVC default/test-pvc not found
I0922 03:59:57.827581       1 controller.go:291] Started PVC processing "default/test-pvc"
I0922 03:59:57.827689       1 controller.go:334] No need to resize PVC "default/test-pvc"
I0922 03:59:58.359466       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:03.367456       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:08.375186       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:13.383086       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:18.392026       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:23.402692       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:28.410284       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:33.418236       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:38.425955       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:43.436769       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0922 04:00:48.446494       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com