I0919 02:01:52.267277       1 main.go:93] Version : v1.3.0
I0919 02:01:52.267361       1 feature_gate.go:245] feature gates: &{map[]}
I0919 02:01:52.269091       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0919 02:01:57.501614       1 common.go:111] Probing CSI driver for readiness
I0919 02:01:57.501640       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0919 02:01:57.501645       1 connection.go:184] GRPC request: {}
I0919 02:01:57.506682       1 connection.go:186] GRPC response: {}
I0919 02:01:57.506903       1 connection.go:187] GRPC error: <nil>
I0919 02:01:57.507448       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0919 02:01:57.507470       1 connection.go:184] GRPC request: {}
I0919 02:01:57.512355       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0919 02:01:57.512445       1 connection.go:187] GRPC error: <nil>
I0919 02:01:57.512460       1 main.go:141] CSI driver name: "rbd.csi.ceph.com"
I0919 02:01:57.512490       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0919 02:01:57.512500       1 connection.go:184] GRPC request: {}
I0919 02:01:57.513439       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0919 02:01:57.513725       1 connection.go:187] GRPC error: <nil>
I0919 02:01:57.513767       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0919 02:01:57.513816       1 connection.go:184] GRPC request: {}
I0919 02:01:57.515679       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0919 02:01:57.515801       1 connection.go:187] GRPC error: <nil>
I0919 02:01:57.515811       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0919 02:01:57.515816       1 connection.go:184] GRPC request: {}
I0919 02:01:57.518957       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0919 02:01:57.519100       1 connection.go:187] GRPC error: <nil>
I0919 02:01:57.521136       1 leaderelection.go:248] attempting to acquire leader lease kube-system/external-resizer-rbd-csi-ceph-com...
I0919 02:01:57.537131       1 leaderelection.go:258] successfully acquired lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:01:57.537438       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-g44t7
I0919 02:01:57.538041       1 leader_election.go:205] became leader, starting
I0919 02:01:57.538070       1 controller.go:251] Starting external resizer rbd.csi.ceph.com
I0919 02:01:57.538300       1 reflector.go:219] Starting reflector *v1.PersistentVolume (10m0s) from k8s.io/client-go/informers/factory.go:134
I0919 02:01:57.538318       1 reflector.go:255] Listing and watching *v1.PersistentVolume from k8s.io/client-go/informers/factory.go:134
I0919 02:01:57.538759       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (10m0s) from k8s.io/client-go/informers/factory.go:134
I0919 02:01:57.538781       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0919 02:01:57.550952       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:01:57.638306       1 shared_informer.go:270] caches populated
I0919 02:02:02.560012       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:07.604163       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:12.613689       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:17.628230       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:22.641802       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:27.655954       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:32.664170       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:37.673836       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:42.682449       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:47.691245       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:52.701696       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:02:57.709221       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:02.720631       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:07.729163       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:12.737237       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:17.745715       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:22.754198       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:27.762470       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:32.770498       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:37.780664       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:42.787965       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:47.796614       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:52.806002       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:03:57.815463       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:02.826095       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:07.835865       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:12.845354       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:17.854065       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:22.864016       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:27.874711       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:32.882645       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:37.891060       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:42.901987       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:47.912801       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:52.926012       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:04:57.934789       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:02.944693       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:07.952562       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:12.964155       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:17.975762       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:22.984323       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:27.993203       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:33.002116       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:38.013698       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:43.021085       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:48.030712       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:53.041580       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:05:58.053348       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:03.063950       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:08.075622       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:13.088571       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:18.100346       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:22.927296       1 controller.go:291] Started PVC processing "default/test-pvc"
W0919 02:06:22.927418       1 controller.go:318] PV "" bound to PVC default/test-pvc not found
I0919 02:06:23.096404       1 controller.go:291] Started PVC processing "default/test-pvc"
I0919 02:06:23.097072       1 controller.go:334] No need to resize PVC "default/test-pvc"
I0919 02:06:23.109145       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:28.117989       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:33.128660       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:38.138941       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:43.148967       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:48.157736       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:53.166421       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:06:58.174391       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:07:03.179847       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:07:08.187718       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:07:13.193977       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com
I0919 02:07:18.203354       1 leaderelection.go:278] successfully renewed lease kube-system/external-resizer-rbd-csi-ceph-com