W0922 01:37:55.722582       1 feature_gate.go:354] Setting GA feature gate Topology=true. It will be removed in a future release.
I0922 01:37:55.722662       1 feature_gate.go:387] feature gates: {map[Topology:true]}
I0922 01:37:55.722685       1 csi-provisioner.go:154] Version: v5.1.0
I0922 01:37:55.722694       1 csi-provisioner.go:177] Building kube configs for running in cluster...
I0922 01:37:55.723364       1 connection.go:234] "Connecting" address="unix:///csi/csi.sock"
I0922 01:37:55.724646       1 common.go:143] "Probing CSI driver for readiness"
I0922 01:37:55.726481       1 connection.go:264] "GRPC call" method="/csi.v1.Identity/Probe" request="{}"
I0922 01:37:55.728793       1 connection.go:270] "GRPC response" response="{\"ready\":{\"value\":true}}" err=null
I0922 01:37:55.728849       1 connection.go:264] "GRPC call" method="/csi.v1.Identity/GetPluginInfo" request="{}"
I0922 01:37:55.730359       1 connection.go:270] "GRPC response" response="{\"manifest\":{\"url\":\"https://github.com/kubernetes/kubernetes/tree/master/test/e2e/storage/drivers/csi-test/mock\"},\"name\":\"csi-mock-mutable-allocatable-mock-2145\",\"vendor_version\":\"0.3.0\"}" err=null
I0922 01:37:55.730401       1 csi-provisioner.go:230] Detected CSI driver csi-mock-mutable-allocatable-mock-2145
I0922 01:37:55.730455       1 connection.go:264] "GRPC call" method="/csi.v1.Identity/GetPluginCapabilities" request="{}"
I0922 01:37:55.732071       1 connection.go:270] "GRPC response" response="{\"capabilities\":[{\"Type\":{\"Service\":{\"type\":1}}},{\"Type\":{\"VolumeExpansion\":{\"type\":1}}}]}" err=null
I0922 01:37:55.732119       1 connection.go:264] "GRPC call" method="/csi.v1.Controller/ControllerGetCapabilities" request="{}"
I0922 01:37:55.733581       1 connection.go:270] "GRPC response" response="{\"capabilities\":[{\"Type\":{\"Rpc\":{\"type\":1}}},{\"Type\":{\"Rpc\":{\"type\":3}}},{\"Type\":{\"Rpc\":{\"type\":10}}},{\"Type\":{\"Rpc\":{\"type\":4}}},{\"Type\":{\"Rpc\":{\"type\":6}}},{\"Type\":{\"Rpc\":{\"type\":5}}},{\"Type\":{\"Rpc\":{\"type\":8}}},{\"Type\":{\"Rpc\":{\"type\":7}}},{\"Type\":{\"Rpc\":{\"type\":12}}},{\"Type\":{\"Rpc\":{\"type\":11}}},{\"Type\":{\"Rpc\":{\"type\":14}}},{\"Type\":{\"Rpc\":{\"type\":2}}},{\"Type\":{\"Rpc\":{\"type\":9}}}]}" err=null
I0922 01:37:55.733712       1 csi-provisioner.go:299] CSI driver supports PUBLISH_UNPUBLISH_VOLUME, watching VolumeAttachments
I0922 01:37:55.736318       1 controller.go:744] "Using saving PVs to API server in background"
I0922 01:37:55.736553       1 envvar.go:172] "Feature gate default state" feature="WatchListClient" enabled=false
I0922 01:37:55.736567       1 envvar.go:172] "Feature gate default state" feature="InformerResourceVersion" enabled=false
I0922 01:37:55.736589       1 reflector.go:305] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.736602       1 reflector.go:341] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.736616       1 reflector.go:305] Starting reflector *v1.VolumeAttachment (1h0m0s) from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.736645       1 reflector.go:341] Listing and watching *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.736644       1 reflector.go:305] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.736692       1 reflector.go:341] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.746448       1 reflector.go:368] Caches populated for *v1.StorageClass from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.746981       1 reflector.go:368] Caches populated for *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.747243       1 reflector.go:368] Caches populated for *v1.VolumeAttachment from k8s.io/client-go/informers/factory.go:160
I0922 01:37:55.837355       1 controller.go:824] "Starting provisioner controller" component="csi-mock-mutable-allocatable-mock-2145_csi-mockplugin-0_d501a554-17c8-4bbe-9d76-b210d716e5fb"
I0922 01:37:55.837378       1 clone_controller.go:66] Starting CloningProtection controller
I0922 01:37:55.837422       1 clone_controller.go:82] Started CloningProtection controller
I0922 01:37:55.837477       1 volume_store.go:98] "Starting save volume queue"
I0922 01:37:55.837766       1 reflector.go:305] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v10/controller/controller.go:861
I0922 01:37:55.837787       1 reflector.go:341] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v10/controller/controller.go:861
I0922 01:37:55.837823       1 reflector.go:305] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v10/controller/controller.go:858
I0922 01:37:55.837851       1 reflector.go:341] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v10/controller/controller.go:858
I0922 01:37:55.839767       1 reflector.go:368] Caches populated for *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v10/controller/controller.go:861
I0922 01:37:55.844354       1 reflector.go:368] Caches populated for *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v10/controller/controller.go:858
I0922 01:37:55.937619       1 controller.go:873] "Started provisioner controller" component="csi-mock-mutable-allocatable-mock-2145_csi-mockplugin-0_d501a554-17c8-4bbe-9d76-b210d716e5fb"