This job view page is being replaced by Spyglass soon. Check out the new job view.
PRjhvhs: Update OWNERS
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2020-09-24 12:50
Elapsed18m43s
Revision11f9469b245ad31c9ab3663884fb8eb5a9e7af66
Refs 2842

No Test Failures!


Error lines from build-log.txt

... skipping 199 lines ...
+ /go/src/github.com/kubernetes-sigs/service-catalog/bin/deepcopy-gen --v 1 --logtostderr --go-header-file contrib/hack/boilerplate.go.txt --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/servicecatalog/v1beta1 --bounding-dirs github.com/kubernetes-sigs/service-catalog --output-file-base zz_generated.deepcopy
+ /go/src/github.com/kubernetes-sigs/service-catalog/bin/defaulter-gen --v 1 --logtostderr --go-header-file contrib/hack/boilerplate.go.txt --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings/v1alpha1 --extra-peer-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings --extra-peer-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings/v1alpha1 --output-file-base zz_generated.defaults
+ /go/src/github.com/kubernetes-sigs/service-catalog/bin/deepcopy-gen --v 1 --logtostderr --go-header-file contrib/hack/boilerplate.go.txt --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings/v1alpha1 --bounding-dirs github.com/kubernetes-sigs/service-catalog --output-file-base zz_generated.deepcopy
+ /go/src/github.com/kubernetes-sigs/service-catalog/bin/conversion-gen --v 1 --logtostderr --extra-peer-dirs k8s.io/api/core/v1,k8s.io/apimachinery/pkg/apis/meta/v1,k8s.io/apimachinery/pkg/conversion,k8s.io/apimachinery/pkg/runtime --go-header-file contrib/hack/boilerplate.go.txt --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings/v1alpha1 --output-file-base zz_generated.conversion
+ REPORT_FILENAME=./api_violations.txt
+ KNOWN_VIOLATION_FILENAME=./contrib/build/violation_exceptions.txt
+ API_RULE_CHECK_FAILURE_MESSAGE='Error: API rules check failed. Reported violations "./api_violations.txt" differ from known violations "./contrib/build/violation_exceptions.txt". Please fix API source file if new violation is detected, or update known violations "./contrib/build/violation_exceptions.txt" if existing violation is being fixed. Please refer to k8s.io/kubernetes/api/api-rules/README.md and https://github.com/kubernetes/kube-openapi/tree/master/pkg/generators/rules for more information about the API rules being enforced.'
+ /go/src/github.com/kubernetes-sigs/service-catalog/bin/openapi-gen --v 3 --logtostderr --go-header-file contrib/hack/boilerplate.go.txt --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/servicecatalog/v1beta1,k8s.io/api/core/v1,k8s.io/apimachinery/pkg/api/resource,k8s.io/apimachinery/pkg/apis/meta/v1,k8s.io/apimachinery/pkg/version,k8s.io/apimachinery/pkg/runtime --input-dirs github.com/kubernetes-sigs/service-catalog/pkg/apis/settings/v1alpha1 --output-package github.com/kubernetes-sigs/service-catalog/pkg/openapi --report-filename ./api_violations.txt
I0924 12:57:20.130154    2803 openapi.go:461] [k8s.io/api/core/v1.PodSpec] Volumes []k8s.io/api/core/v1.Volume: [merge,retainKeys] not allowed for patchStrategy. Allowed values: [merge retainKeys]
I0924 12:57:20.507372    2803 openapi.go:461] [k8s.io/apimachinery/pkg/apis/meta/v1.LabelSelectorRequirement] Key string: tag patchMergeKey on type Builtin; only allowed on type Slice
I0924 12:57:20.507833    2803 openapi.go:461] [k8s.io/apimachinery/pkg/apis/meta/v1.LabelSelectorRequirement] Key string: tag patchStrategy on type Builtin; only allowed on type Slice
I0924 12:57:21.921199    2803 api_linter.go:44] Assembling file "./api_violations.txt"
+ diff -u ./api_violations.txt ./contrib/build/violation_exceptions.txt
... skipping 126 lines ...
I0924 13:07:22.236209       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:22.236372       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:22.236188447 +0000 UTC m=+0.442314220
I0924 13:07:22.236829       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:22.236933       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:22.236999       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:22.352780       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously (testdescr)
I0924 13:07:22.396465       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
I0924 13:07:22.397998       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
W0924 13:07:22.423625       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:22.451313       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0924 13:07:22.451960       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:22.452485       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
W0924 13:07:22.474370       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:22.474610       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0924 13:07:22.474817       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:22.475017       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:22.513859       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0924 13:07:22.513973       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0924 13:07:22.514089       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:22.544965       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error injecting bind results: Secret "test-ns/test-binding" is not owned by ServiceBinding, controllerRef: nil
I0924 13:07:22.545109       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error injecting bind results: Secret "test-ns/test-binding" is not owned by ServiceBinding, controllerRef: nil
I0924 13:07:22.545240       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:22.585891       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:07:22.628349       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:22.628922       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:22.629076       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:22.654201       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:22.654940       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:22.659730       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:22.684576       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0924 13:07:22.766864       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0924 13:07:22.820348       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:22.855373       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:22.856085       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
W0924 13:07:22.904557       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:22.904685       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:22.904861       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:22.927666       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0924 13:07:22.928390       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:22.951044       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0924 13:07:22.988631       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0924 13:07:23.016571       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0924 13:07:23.092236       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0924 13:07:23.126695       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:23.169111       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:23.169340       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
W0924 13:07:23.227067       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:23.227212       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:23.227491       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:23.254293       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0924 13:07:23.254525       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:23.289061       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:23.289230       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.28904245 +0000 UTC m=+1.495168200
I0924 13:07:23.292552       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0924 13:07:23.346821       1 controller_binding.go:698] ServiceBinding "test-binding": References a non-existent ServiceInstance "/nothere"
I0924 13:07:23.347050       1 controller_binding.go:712] ServiceBinding "test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.346680192 +0000 UTC m=+1.552805967
I0924 13:07:23.359368       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because ClusterServiceClass and ClusterServicePlan references for ServiceInstance "test-ns/test-instance" have not been resolved yet
... skipping 2 lines ...
I0924 13:07:23.374105       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.373736464 +0000 UTC m=+1.579862248
W0924 13:07:23.421548       1 controller.go:585] ServiceInstance "test-ns/test-instance": References a non-existent ClusterServiceClass "nosuchclassid" - {ClusterServiceClassExternalName:"nothere"}
I0924 13:07:23.422317       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding references a ClusterServiceClass that does not exist. References a non-existent ClusterServiceClass "nosuchclassid" - {ClusterServiceClassExternalName:"nothere"}
I0924 13:07:23.422881       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.422279445 +0000 UTC m=+1.628405242
I0924 13:07:23.468264       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:23.468493       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.468244609 +0000 UTC m=+1.674370366
I0924 13:07:23.469274       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error injecting bind result: Secret "test-ns/test-binding" is not owned by ServiceBinding, controllerRef: nil
I0924 13:07:23.632700       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:23.633367       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.632679233 +0000 UTC m=+1.838804999
I0924 13:07:23.639491       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:07:23.707912       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:23.708184       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.707884965 +0000 UTC m=+1.914010739
I0924 13:07:23.708878       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
... skipping 6 lines ...
I0924 13:07:23.891620       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": References a non-bindable ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") and Plan ("test-unbindable-clusterserviceplan") combination
I0924 13:07:23.896921       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.891597187 +0000 UTC m=+2.097722980
I0924 13:07:23.897013       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": References a non-bindable ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") and Plan ("test-unbindable-clusterserviceplan") combination
I0924 13:07:23.967989       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/test-instance" is not ready
I0924 13:07:23.972326       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:23.967961009 +0000 UTC m=+2.174086780
I0924 13:07:24.011624       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": 
I0924 13:07:24.012147       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Failed to get namespace "test-ns" during binding: No namespace
I0924 13:07:24.012264       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.012129825 +0000 UTC m=+2.218255599
I0924 13:07:24.045482       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:24.045701       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.045459638 +0000 UTC m=+2.251585405
I0924 13:07:24.046184       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:24.046348       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:24.046430       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
... skipping 15 lines ...
I0924 13:07:24.159497       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:24.165476       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:24.165676       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:24.165788       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:24.211552       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:24.211801       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.211530307 +0000 UTC m=+2.417656080
I0924 13:07:24.212428       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error creating ServiceBinding for ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Unexpected action
I0924 13:07:24.270622       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:24.270841       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.27060409 +0000 UTC m=+2.476729856
I0924 13:07:24.275580       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 422; ErrorMessage: AsyncRequired; Description: This service plan requires client support for asynchronous service operations.; ResponseError: <nil>
I0924 13:07:24.275678       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 422; ErrorMessage: AsyncRequired; Description: This service plan requires client support for asynchronous service operations.; ResponseError: <nil>
I0924 13:07:24.406327       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:24.406613       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.406303103 +0000 UTC m=+2.612428860
I0924 13:07:24.407379       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error creating ServiceBinding for ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": fake creation failure
I0924 13:07:24.492366       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:24.493756       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.492337759 +0000 UTC m=+2.698463560
I0924 13:07:24.494293       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 409; ErrorMessage: ServiceBindingExists; Description: Service binding with the same id, for the same service instance already exists.; ResponseError: <nil>
I0924 13:07:24.494377       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 409; ErrorMessage: ServiceBindingExists; Description: Service binding with the same id, for the same service instance already exists.; ResponseError: <nil>
I0924 13:07:24.557683       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0924 13:07:24.557939       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.557634209 +0000 UTC m=+2.763759995
I0924 13:07:24.614081       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0924 13:07:24.664056       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": bar
I0924 13:07:24.720839       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0924 13:07:24.771566       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0924 13:07:24.814051       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0924 13:07:24.870357       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:24.870583       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.870334706 +0000 UTC m=+3.076460473
I0924 13:07:24.872503       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error unbinding from ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Unexpected action
I0924 13:07:24.916245       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:24.916469       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.916205707 +0000 UTC m=+3.122331483
I0924 13:07:24.917078       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error unbinding from ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 410; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
W0924 13:07:24.919544       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=false. It will be removed in a future release.
I0924 13:07:24.962038       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:24.962215       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:24.962005374 +0000 UTC m=+3.168131137
I0924 13:07:24.962889       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
W0924 13:07:24.963420       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0924 13:07:24.989449       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
... skipping 22 lines ...
I0924 13:07:25.161323       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:25.161487       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:25.166131       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
W0924 13:07:25.166517       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0924 13:07:25.204387       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:07:25.204638       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.204350308 +0000 UTC m=+3.410476103
I0924 13:07:25.261525       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error creating ServiceBinding for ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Unexpected action
I0924 13:07:25.261734       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.261502301 +0000 UTC m=+3.467628054
I0924 13:07:25.261792       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries, too much time has elapsed
I0924 13:07:25.318572       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error injecting bind result: Secret "test-ns/test-binding" is not owned by ServiceBinding, controllerRef: nil
I0924 13:07:25.318788       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.318545515 +0000 UTC m=+3.524671295
I0924 13:07:25.318833       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries, too much time has elapsed
I0924 13:07:25.318939       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:25.399451       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:25.400234       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.399428253 +0000 UTC m=+3.605554018
E0924 13:07:25.400360       1 controller_binding.go:751] ServiceBinding "test-ns/test-binding": Error updating status: update error
I0924 13:07:25.505726       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:25.506024       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.505702058 +0000 UTC m=+3.711827823
I0924 13:07:25.514066       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:07:25.594390       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:25.594636       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.594371989 +0000 UTC m=+3.800497747
I0924 13:07:25.595272       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error creating ServiceBinding for ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": timed out
I0924 13:07:25.664718       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:25.664936       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.664694761 +0000 UTC m=+3.870820527
I0924 13:07:25.666096       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 200; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:25.666196       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 200; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:25.749979       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:07:25.750202       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:25.749942175 +0000 UTC m=+3.956067929
... skipping 21 lines ...
I0924 13:07:26.141224       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:26.139517971 +0000 UTC m=+4.345643728
I0924 13:07:26.143067       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 501; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:26.143451       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": ServiceBroker returned failure; bind operation will not be retried: Status: 501; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:26.143589       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:26.220982       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0924 13:07:26.221175       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:26.216724692 +0000 UTC m=+4.422850465
I0924 13:07:26.287604       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error unbinding from ServiceInstance "test-ns/test-instance" of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": timed out
I0924 13:07:26.288654       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries, too much time has elapsed
I0924 13:07:26.383857       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:26.391557       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:26.383837102 +0000 UTC m=+4.589962856
I0924 13:07:26.392713       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:26.393446       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:26.393783       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:26.513433       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
... skipping 5 lines ...
I0924 13:07:26.612114       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:26.610512555 +0000 UTC m=+4.816638309
I0924 13:07:26.615569       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0924 13:07:26.678228       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:07:26.678491       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:26.678198581 +0000 UTC m=+4.884324355
I0924 13:07:26.680336       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0924 13:07:26.862368       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously (testdescr)
I0924 13:07:26.929946       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
I0924 13:07:26.930199       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
W0924 13:07:26.994457       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:27.067321       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0924 13:07:27.075044       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:27.075318       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
W0924 13:07:27.117693       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:27.117955       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0924 13:07:27.118139       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:27.118296       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:27.164637       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0924 13:07:27.164861       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0924 13:07:27.169928       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:27.205319       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error injecting bind results: Secret "test-ns/test-binding" is not owned by ServiceBinding, controllerRef: nil
I0924 13:07:27.205651       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error injecting bind results: Secret "test-ns/test-binding" is not owned by ServiceBinding, controllerRef: nil
I0924 13:07:27.205804       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0924 13:07:27.274443       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:07:27.343594       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:27.344520       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:27.344611       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:27.402618       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:07:27.403039       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:07:27.403151       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:07:27.467934       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0924 13:07:27.622937       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0924 13:07:27.696566       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:27.763031       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:27.764887       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
W0924 13:07:27.839143       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:27.840313       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:27.840514       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:27.928795       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0924 13:07:27.929123       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:27.985143       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0924 13:07:28.062237       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0924 13:07:28.102277       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0924 13:07:28.209956       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0924 13:07:28.286320       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:28.331719       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:28.332090       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
W0924 13:07:28.393393       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0924 13:07:28.393592       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0924 13:07:28.393769       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:28.454510       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0924 13:07:28.455737       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:29.695530       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:29.707097       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:07:29.708034       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:29.707936659 +0000 UTC m=+7.914062487
I0924 13:07:29.745299       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:07:29.749099       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:07:29.755454       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:29.755401501 +0000 UTC m=+7.961527284
... skipping 6 lines ...
I0924 13:07:30.017766       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:07:30.018647       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0924 13:07:30.020436       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.020353654 +0000 UTC m=+8.226479470
I0924 13:07:30.107427       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:30.112929       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0924 13:07:30.113338       1 controller_clusterservicebroker.go:484] ClusterServiceBroker "test-clusterservicebroker": ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") already exists for Broker "notTheSame"
W0924 13:07:30.113602       1 controller_clusterservicebroker.go:267] ClusterServiceBroker "test-clusterservicebroker": Error reconciling ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") (broker "test-clusterservicebroker"): ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") already exists for Broker "notTheSame"
I0924 13:07:30.113841       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.113739378 +0000 UTC m=+8.319865173
I0924 13:07:30.192613       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:30.198921       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0924 13:07:30.199565       1 controller_clusterservicebroker.go:572] ClusterServiceBroker "test-clusterservicebroker": ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") already exists for Broker "notTheSame"
W0924 13:07:30.199836       1 controller_clusterservicebroker.go:327] ClusterServiceBroker "test-clusterservicebroker": Error reconciling ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan"): ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") already exists for Broker "notTheSame"
I0924 13:07:30.200071       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.200001976 +0000 UTC m=+8.406127824
I0924 13:07:30.300512       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:07:30.306269       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0924 13:07:30.306613       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.30652045 +0000 UTC m=+8.512646264
I0924 13:07:30.412484       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:07:30.415773       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0924 13:07:30.416895       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.416792512 +0000 UTC m=+8.622918323
I0924 13:07:30.519139       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:07:30.526700       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0924 13:07:30.527765       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.527699375 +0000 UTC m=+8.733825162
W0924 13:07:30.638528       1 controller_clusterservicebroker.go:184] ClusterServiceBroker "test-clusterservicebroker": Error getting broker catalog: ooops
I0924 13:07:30.638851       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.638751218 +0000 UTC m=+8.844877008
I0924 13:07:30.709369       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:30.718688       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:07:30.718901       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:30.718844689 +0000 UTC m=+8.924970502
I0924 13:07:31.239726       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0924 13:07:31.240112       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.240025314 +0000 UTC m=+9.446151119
I0924 13:07:31.242829       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0924 13:07:31.243210       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.243128821 +0000 UTC m=+9.449254661
I0924 13:07:31.314402       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0924 13:07:31.319089       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.318974861 +0000 UTC m=+9.525100656
I0924 13:07:31.399147       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0924 13:07:31.407299       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.407187294 +0000 UTC m=+9.613313091
I0924 13:07:31.405939       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0924 13:07:31.414852       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.414724379 +0000 UTC m=+9.620850171
I0924 13:07:31.428777       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0924 13:07:31.429220       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.429147123 +0000 UTC m=+9.635272939
I0924 13:07:31.516220       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:31.526632       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0924 13:07:31.534480       1 controller_clusterservicebroker.go:493] ClusterServiceBroker "test-clusterservicebroker": Error creating ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass"): error creating serviceclass
W0924 13:07:31.540659       1 controller_clusterservicebroker.go:267] ClusterServiceBroker "test-clusterservicebroker": Error reconciling ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") (broker "test-clusterservicebroker"): error creating serviceclass
I0924 13:07:31.540946       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.540803156 +0000 UTC m=+9.746928973
I0924 13:07:31.630894       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:31.637230       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:07:31.640359       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.640264984 +0000 UTC m=+9.846390773
W0924 13:07:31.736839       1 controller_clusterservicebroker.go:184] ClusterServiceBroker "test-clusterservicebroker": Error getting broker catalog: ooops
I0924 13:07:31.737359       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.737256564 +0000 UTC m=+9.943382357
I0924 13:07:31.737543       1 controller_clusterservicebroker.go:198] ClusterServiceBroker "test-clusterservicebroker": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:31.737664       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Failed" to 2020-09-24 13:07:31.737609715 +0000 UTC m=+9.943735517
I0924 13:07:31.864665       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:31.877366       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:07:31.878527       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.878454413 +0000 UTC m=+10.084580217
E0924 13:07:31.884262       1 controller_clusterservicebroker.go:688] ClusterServiceBroker "test-clusterservicebroker": Error updating ready condition: update error
I0924 13:07:31.985380       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:31.985307101 +0000 UTC m=+10.191432885
I0924 13:07:32.328149       1 controller_clusterservicebroker.go:661] ClusterServiceBroker "test-clusterservicebroker": Found status change for condition "Ready": "False" -> "True"; setting lastTransitionTime to 2020-09-24 13:07:32.319619635 +0000 UTC m=+10.525745444
I0924 13:07:32.584677       1 controller_clusterservicebroker.go:661] ClusterServiceBroker "test-clusterservicebroker": Found status change for condition "Ready": "True" -> "False"; setting lastTransitionTime to 2020-09-24 13:07:32.584519968 +0000 UTC m=+10.790645794
E0924 13:07:32.954118       1 controller_clusterservicebroker.go:616] ClusterServiceBroker "test-clusterservicebroker": Error updating ClusterServicePlan (K8S: "cspguid" ExternalName: "new-value"): oops
I0924 13:07:33.058197       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:07:33.059417       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0924 13:07:33.065313       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:33.065230287 +0000 UTC m=+11.271356083
I0924 13:07:33.145425       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:07:33.161319       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:07:33.166465       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:33.166376606 +0000 UTC m=+11.372502449
... skipping 30 lines ...
I0924 13:07:34.349913       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v2xknslg6nt: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:34.351195       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v2xknslg6nt: The instance was provisioned successfully
I0924 13:07:34.468203       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcgzcq7fd5v: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:34.469380       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcgzcq7fd5v: The instance is being provisioned asynchronously
I0924 13:07:34.570608       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0924 13:07:34.741349       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0924 13:07:34.857190       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0924 13:07:34.857545       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0924 13:07:34.857778       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0924 13:07:34.858021       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:34.945542       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:34.965911       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:34.966355       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:35.133139       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:35.134723       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously
I0924 13:07:35.302251       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0924 13:07:35.459389       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0924 13:07:35.569145       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:35.569858       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:35.725014       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0924 13:07:35.874664       1 controller_instance.go:1491] ServiceInstance "test-ns/test-instance": Found 1 ServiceClasses
I0924 13:07:35.905683       1 controller_instance.go:1612] ServiceInstance "test-ns/test-instance": Found 1 ServicePlans
I0924 13:07:36.154738       1 controller_instance.go:1417] ServiceInstance "test-instance": Found 0 ClusterServiceClasses
W0924 13:07:36.156227       1 controller_instance.go:1281] ServiceInstance "test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassExternalName:"nothere"} or there is more than one (found: 0)
I0924 13:07:36.156541       1 controller_instance.go:1829] ServiceInstance "test-instance": The instance references a ClusterServiceClass that does not exist. References a non-existent ClusterServiceClass {ClusterServiceClassExternalName:"nothere"} or there is more than one (found: 0)
W0924 13:07:36.295038       1 controller_instance.go:1281] ServiceInstance "test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassName:"nothereclass"}
... skipping 10 lines ...
I0924 13:07:37.112618       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v88n6c6ghwp: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:37.125908       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v88n6c6ghwp: The instance was provisioned successfully
I0924 13:07:37.280657       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vlq6fqqq4ld: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:37.291199       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vlq6fqqq4ld: The instance was provisioned successfully
I0924 13:07:37.484830       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vj8x2nsn79p: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:37.492392       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vj8x2nsn79p: The instance was provisioned successfully
I0924 13:07:37.596661       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqnz75q99kp: failed to prepare parameters &RawExtension{Raw:*[98 97 100],}: error unmarshaling JSON: while decoding JSON: json: cannot unmarshal string into Go value of type map[string]interface {}
I0924 13:07:37.729375       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vs2ngvfdf7n: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:07:37.833829       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vrvr664wqdp: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:07:37.960404       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vgcrkdttbdx: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:07:38.108821       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5q8w8wq59q: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:07:38.217393       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6t8mxt6gjv: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:38.229074       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6t8mxt6gjv: The instance was provisioned successfully
I0924 13:07:38.389773       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 1 ClusterServiceClasses
I0924 13:07:38.417059       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0924 13:07:38.730175       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcjkzpnfzbs: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:38.898849       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0924 13:07:38.950025       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vjzftlx8h85: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:38.955132       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vjzftlx8h85: The provision call failed and will be retried: Error communicating with broker for provisioning: fake creation failure
I0924 13:07:39.014224       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqds5t74j4r: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:39.020922       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqds5t74j4r: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: InternalServerError; Description: Something went wrong!; ResponseError: <nil>
I0924 13:07:39.021050       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqds5t74j4r: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: InternalServerError; Description: Something went wrong!; ResponseError: <nil>
I0924 13:07:39.021223       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqds5t74j4r: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:39.078221       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vb4hvvnrrkk: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:39.079877       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vb4hvvnrrkk: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: BadRequest; Description: Your parameters are incorrect!; ResponseError: <nil>
I0924 13:07:39.080005       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vb4hvvnrrkk: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: BadRequest; Description: Your parameters are incorrect!; ResponseError: <nil>
I0924 13:07:39.150514       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfmwd99hc8q: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:39.157309       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfmwd99hc8q: The instance was provisioned successfully
I0924 13:07:39.226211       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v7lzd2dqpxr: ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") has been deleted; cannot provision.
I0924 13:07:39.340465       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v89c68mgxlb: ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") has been deleted; cannot provision.
I0924 13:07:39.434824       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vvrltz258k2: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:39.437534       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vvrltz258k2: The instance was provisioned successfully
I0924 13:07:39.593889       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v77f4mk26nx: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:39.596106       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v77f4mk26nx: The instance is being provisioned asynchronously
I0924 13:07:39.702531       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v58f84znrtp: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:39.711581       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v58f84znrtp: The instance is being provisioned asynchronously
I0924 13:07:39.847781       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8hgwrqdqj4: Failed to get namespace "test-ns": No namespace
I0924 13:07:39.943337       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:39.944501       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:39.944776       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:40.009138       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0924 13:07:40.018334       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:40.026921       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
... skipping 16 lines ...
I0924 13:07:41.079003       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:41.325492       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance" v1: Cleared finalizer
I0924 13:07:41.413104       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vrz2rmgdskx: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:41.418041       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vrz2rmgdskx: The instance was provisioned successfully
I0924 13:07:41.512261       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0924 13:07:41.600773       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0924 13:07:41.684382       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0924 13:07:41.685814       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0924 13:07:41.686340       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0924 13:07:41.688514       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:41.790043       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0924 13:07:41.895324       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0924 13:07:41.972845       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:41.973533       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:42.056672       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0924 13:07:42.192877       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0924 13:07:42.193522       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:42.347728       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:42.361932       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:42.451178       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 403; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:42.620562       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:42.621135       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:42.757411       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:42.758149       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:42.896951       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v7pfv7r8llx: The instance was provisioned successfully
I0924 13:07:43.053902       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqbgsvcc4r8: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:43.182003       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vlhpp5dnrmx: The provision call failed and will be retried: Error communicating with broker for provisioning: fake creation failure
I0924 13:07:43.182804       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vlhpp5dnrmx: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:43.318448       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0924 13:07:43.476774       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0924 13:07:43.483513       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Stopping reconciliation retries because too much time has elapsed
I0924 13:07:43.483690       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0924 13:07:43.483987       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:43.617947       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision request for ServiceInstance in-flight to Broker
E0924 13:07:43.621424       1 controller_instance.go:1985] ServiceInstance "test-ns/test-instance": Failed to update status: update error
I0924 13:07:43.630652       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0924 13:07:43.634236       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0924 13:07:43.642716       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": DifferentMessage
I0924 13:07:43.643057       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0924 13:07:43.643440       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0924 13:07:43.643695       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
... skipping 33 lines ...
W0924 13:07:45.697576       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0924 13:07:45.829305       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
W0924 13:07:45.844674       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0924 13:07:45.962822       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
W0924 13:07:45.970875       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0924 13:07:46.088222       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8pxgmjfglv: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:46.093485       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8pxgmjfglv: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 200; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.244845       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfqplng9rvl: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:46.248461       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfqplng9rvl: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 201; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.250161       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfqplng9rvl: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 201; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.259546       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfqplng9rvl: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:46.418614       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vd9t9nl6cd7: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:46.428536       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vd9t9nl6cd7: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 300; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.545791       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwtbc5r7hmk: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:46.548954       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwtbc5r7hmk: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.552615       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwtbc5r7hmk: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.690208       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwrzldndnqd: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:46.713901       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vwrzldndnqd: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 408; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.850395       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v76zdsln9pg: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:46.869972       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v76zdsln9pg: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:46.870672       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v76zdsln9pg: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:47.058872       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vvfkt5fx8cg: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:47.061276       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vvfkt5fx8cg: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:47.061739       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vvfkt5fx8cg: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:07:47.076705       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vvfkt5fx8cg: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:47.263144       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbj74vgvhnk: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:47.265880       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbj74vgvhnk: Communication with the ClusterServiceBroker timed out; operation will be retried:  : timed out
I0924 13:07:47.266659       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbj74vgvhnk: Communication with the ClusterServiceBroker timed out; operation will be retried:  : timed out
I0924 13:07:47.268620       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vbj74vgvhnk: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:47.431310       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:47.448457       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0924 13:07:47.673997       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:47.675288       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously
I0924 13:07:47.793830       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:47.800621       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed; received error response from broker: Status: 422; ErrorMessage: AsyncRequired; Description: This service plan requires client support for asynchronous service operations.; ResponseError: <nil>
I0924 13:07:47.925266       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:47.927846       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:48.129406       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:48.140803       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error deprovisioning, ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": other error
I0924 13:07:48.350462       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:48.351793       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:48.464719       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:48.466050       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0924 13:07:48.603399       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:48.609339       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0924 13:07:48.774819       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:48.776032       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (description)
I0924 13:07:48.929998       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:48.938354       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:49.076957       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:49.077921       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:49.254128       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:49.260038       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:49.403534       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:49.404572       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:49.554697       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
W0924 13:07:49.561220       1 controller_instance.go:1173] ServiceInstance "test-ns/test-instance": Got invalid state in LastOperationResponse: "invalid state"
I0924 13:07:49.561409       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0924 13:07:49.685515       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vw5n2zpw27p: Provision request for ServiceInstance in-flight to Broker
I0924 13:07:49.696362       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vw5n2zpw27p: The instance was provisioned successfully
I0924 13:07:50.054970       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 0 ClusterServiceClasses
W0924 13:07:50.055791       1 controller_instance.go:1281] ServiceInstance "test-ns/test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassExternalName:"test-clusterserviceclass"} or there is more than one (found: 0)
I0924 13:07:50.061394       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance references a ClusterServiceClass that does not exist. References a non-existent ClusterServiceClass {ClusterServiceClassExternalName:"test-clusterserviceclass"} or there is more than one (found: 0)
I0924 13:07:50.159136       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
... skipping 10 lines ...
I0924 13:07:50.651526       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0924 13:07:50.730348       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:07:50.733707       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0924 13:07:50.822900       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:07:50.852859       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0924 13:07:50.943367       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:07:50.944967       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The update call failed and will be retried: Error communicating with broker for updating: fake update failure
I0924 13:07:51.077829       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:07:51.079558       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": ServiceBroker returned a failure for update call; update will be retried: Status: 409; ErrorMessage: OutOfQuota; Description: You're out of quota!; ResponseError: <nil>
I0924 13:07:51.225666       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:07:51.227442       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": ServiceBroker returned a failure for update call; update will not be retried: Status: 400; ErrorMessage: BadRequest; Description: Something's wrong with the request; ResponseError: <nil>
I0924 13:07:51.227661       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": ServiceBroker returned a failure for update call; update will not be retried: Status: 400; ErrorMessage: BadRequest; Description: Something's wrong with the request; ResponseError: <nil>
I0924 13:07:51.360527       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 1 ClusterServiceClasses
I0924 13:07:51.375208       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0924 13:07:51.487027       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0924 13:07:51.698792       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:07:51.700655       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being updated asynchronously
I0924 13:07:51.835892       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being updated asynchronously (testdescr)
I0924 13:07:52.032774       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0924 13:07:52.194655       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update call failed: (no description provided)
I0924 13:07:52.195139       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update call failed: (no description provided)
I0924 13:07:53.103455       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:53.109793       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:53.110579       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:53.249221       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:07:53.250699       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:53.255825       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:53.256948       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:53.396298       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:07:53.403458       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0924 13:07:53.404358       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0924 13:07:53.699220       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
W0924 13:07:53.836817       1 controller_servicebroker.go:175] ServiceBroker "test-ns/test-servicebrokernot-predefined": Error getting broker catalog: Unexpected action
I0924 13:07:53.837318       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebrokernot-predefined": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:53.837205144 +0000 UTC m=+32.043330940
I0924 13:07:53.982129       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0924 13:07:53.989801       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0924 13:07:53.990710       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:53.990655775 +0000 UTC m=+32.196781579
I0924 13:07:54.079381       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0924 13:07:54.081534       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0924 13:07:54.082267       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:54.082204075 +0000 UTC m=+32.288329881
I0924 13:07:54.178294       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0924 13:07:54.211982       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0924 13:07:54.217346       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:54.212803584 +0000 UTC m=+32.418929386
E0924 13:07:55.253231       1 controller_servicebroker.go:590] ServiceBroker "test-ns/test-servicebroker": Error updating ServicePlan (K8S: "test-ns/spguid" ExternalName: "new-value"): oops
I0924 13:07:55.419495       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0924 13:07:55.431398       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 0 ServicePlans
I0924 13:07:55.432597       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:07:55.432519619 +0000 UTC m=+33.638645429
I0924 13:07:55.593883       1 controller_serviceclass.go:80] ServiceClass "test-ns/scguid": Processing
I0924 13:07:55.832666       1 controller_serviceclass.go:80] ServiceClass "test-ns/scguid": Processing
I0924 13:07:55.833429       1 controller_serviceclass.go:86] ServiceClass "test-ns/scguid": Removed from broker catalog; determining whether there are instances remaining
... skipping 110 lines ...
I0924 13:08:02.165433       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:02.180050       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:02.227500       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:02.257895       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:08:02.259275       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:02.257858299 +0000 UTC m=+40.463984070
I0924 13:08:02.290345       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0924 13:08:03.327899       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: (no description provided)
I0924 13:08:03.329207       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: (no description provided)
I0924 13:08:03.333404       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:08:03.345679       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:08:03.346714       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:08:03.348400       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:08:03.350874       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:08:03.352104       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
... skipping 10 lines ...
I0924 13:08:03.845569       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:03.848197       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:03.933958       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:08:03.934583       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:03.933932807 +0000 UTC m=+42.140058581
I0924 13:08:03.944629       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:08:04.013790       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:08:04.021730       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Error unbinding from ServiceInstance "test-ns/service-instance" of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:04.031877       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:08:04.053496       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:08:04.054146       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:08:04.097554       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:04.135688       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:04.402680       1 controller.go:245] Starting service-catalog controller
... skipping 10 lines ...
I0924 13:08:04.685626       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0924 13:08:04.686449       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:04.685589454 +0000 UTC m=+42.891715225
I0924 13:08:04.705092       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0924 13:08:05.740009       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0924 13:08:05.785935       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0924 13:08:05.791854       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0924 13:08:06.809505       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: (no description provided)
I0924 13:08:06.816612       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0924 13:08:06.835714       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:08:06.836861       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:08:06.837316       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:08:06.911189       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:06.985258       1 controller.go:296] Shutdown service-catalog controller
... skipping 6 lines ...
I0924 13:08:07.274278       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:07.275845       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:07.304194       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.305906       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:07.304167712 +0000 UTC m=+45.510293502
I0924 13:08:07.320541       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:07.327314       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.328389       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Error provisioning ServiceInstance of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 502; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:07.333327       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Error provisioning ServiceInstance of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 502; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:07.334446       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:08:07.342633       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.345232       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.358269       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0924 13:08:07.361509       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0924 13:08:07.376590       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0924 13:08:07.377143       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
... skipping 9 lines ...
I0924 13:08:07.791055       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:07.792301       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:07.789677       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:07.851420       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:07.852726       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.853925       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:07.85265439 +0000 UTC m=+46.058780193
I0924 13:08:07.866004       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Error provisioning ServiceInstance of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 502; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:07.867174       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Error provisioning ServiceInstance of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 502; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:07.867680       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:08:07.898425       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.914138       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0924 13:08:07.923217       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.936241       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0924 13:08:07.945870       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0924 13:08:07.963480       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
... skipping 84 lines ...
I0924 13:08:11.489946       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:11.489883941 +0000 UTC m=+49.696009729
I0924 13:08:11.505633       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:11.509103       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:11.510494       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:11.573280       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:11.575451       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:11.637048       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.638452       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:11.636968365 +0000 UTC m=+49.843094151
I0924 13:08:11.644646       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.647913       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.650589       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.660160       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.668900       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.673503       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.676593       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.685176       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0924 13:08:11.698213       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:11.761418       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:11.910884       1 controller.go:245] Starting service-catalog controller
I0924 13:08:11.938842       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:11.941560       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:11.945128       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:11.945055663 +0000 UTC m=+50.151181479
I0924 13:08:11.953559       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:11.955275       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:11.956386       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:11.953690       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:12.006481       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:12.017162       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:12.065627       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.066439       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:12.065573632 +0000 UTC m=+50.271699419
I0924 13:08:12.070946       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.077983       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.086473       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.097576       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.105163       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.126393       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.136958       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.143354       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:12.212975       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:12.356854       1 controller.go:245] Starting service-catalog controller
I0924 13:08:12.367606       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:12.372603       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:12.376379       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:12.380132       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:12.397580       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:12.39751855 +0000 UTC m=+50.603644364
I0924 13:08:12.401073       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:12.402697       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:12.426994       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:12.440449       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:12.494437       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.495325       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:12.494365065 +0000 UTC m=+50.700490909
I0924 13:08:12.499550       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.503291       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.509181       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.513143       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.520539       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.536956       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.539748       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.545205       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.550898       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.554107       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.563738       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:12.592958       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:12.639075       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:12.760164       1 controller.go:245] Starting service-catalog controller
I0924 13:08:12.773002       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:12.797512       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:12.800758       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:12.800657206 +0000 UTC m=+51.006783019
I0924 13:08:12.802598       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:12.808123       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:12.814649       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:12.815059       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:12.833281       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:12.836659       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:12.893475       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.894945       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:12.893400396 +0000 UTC m=+51.099526197
I0924 13:08:12.899942       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.905131       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.907986       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.915020       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.925124       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.929647       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.941282       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:12.946350       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:12.946667       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0924 13:08:13.002019       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:13.153279       1 controller.go:245] Starting service-catalog controller
I0924 13:08:13.162929       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:13.165085       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:13.173670       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:13.182253       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:13.182183413 +0000 UTC m=+51.388309233
... skipping 65 lines ...
I0924 13:08:14.940986       1 controller.go:245] Starting service-catalog controller
I0924 13:08:14.987093       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:14.989543       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:14.994862       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:15.002554       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:15.009597       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:15.009301232 +0000 UTC m=+53.215427031
I0924 13:08:15.031099       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Error provisioning ServiceInstance of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 401; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:15.010315       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:15.042359       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:15.043568       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:15.081574       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:15.092874       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Error provisioning ServiceInstance of ClusterServiceClass (K8S: "clusterserviceclass-12345" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 401; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0924 13:08:16.052581       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:16.104924       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:16.113072       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:16.139016       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:16.150930       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:16.189075       1 controller.go:296] Shutdown service-catalog controller
... skipping 14 lines ...
I0924 13:08:16.479837       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:16.466818       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:16.491578       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:16.501308       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being provisioned asynchronously
I0924 13:08:16.525814       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:16.529143       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being provisioned asynchronously
I0924 13:08:17.517325       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0924 13:08:17.520509       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0924 13:08:17.522044       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0924 13:08:17.523393       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:08:17.529448       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:08:17.531908       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0924 13:08:17.533645       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0924 13:08:17.543062       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0924 13:08:17.544570       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0924 13:08:17.545351       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0924 13:08:17.549886       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0924 13:08:17.556151       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:08:17.557595       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0924 13:08:17.558159       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0924 13:08:17.562308       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0924 13:08:17.609572       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:17.615431       1 controller.go:296] Shutdown service-catalog controller
... skipping 42 lines ...
I0924 13:08:26.830349       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:26.842326       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:26.861538       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:08:26.863558       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being updated asynchronously
I0924 13:08:26.900318       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0924 13:08:26.911653       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being updated asynchronously
I0924 13:08:27.869958       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0924 13:08:27.870743       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0924 13:08:27.878267       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:08:27.879451       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0924 13:08:27.880145       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0924 13:08:27.883010       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0924 13:08:27.890781       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0924 13:08:27.920787       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:27.947910       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0924 13:08:27.952370       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0924 13:08:27.956861       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:08:27.958150       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0924 13:08:27.959083       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0924 13:08:27.971306       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:27.978954       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:28.011993       1 controller.go:296] Shutdown service-catalog controller
... skipping 22 lines ...
I0924 13:08:31.078897       1 controller.go:245] Starting service-catalog controller
I0924 13:08:30.976047       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:31.110699       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:31.282274       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:31.126448       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:31.265195       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:31.265120876 +0000 UTC m=+69.471246703
I0924 13:08:31.257795       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.126109       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:31.12604743 +0000 UTC m=+69.332173220
I0924 13:08:31.295669       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:31.315674       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:31.315556       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:31.322275       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:31.323375       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:31.363744       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:31.386526       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.395668       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:31.395611827 +0000 UTC m=+69.601737627
I0924 13:08:31.432841       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:31.444697       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:31.445365       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:31.454948       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:31.460468       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:31.479952       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.486084       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:31.486032646 +0000 UTC m=+69.692158450
I0924 13:08:31.517049       1 controller.go:245] Starting service-catalog controller
I0924 13:08:31.530497       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:31.537063       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:31.547588       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:31.570922       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.592505       1 controller.go:245] Starting service-catalog controller
I0924 13:08:31.656837       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:31.660178       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:31.674822       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:31.720823       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.729549       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:31.740119       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:31.783182       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:31.784733       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.795091       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:31.839674       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:31.859594       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.863739       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:31.863663836 +0000 UTC m=+70.069789663
I0924 13:08:31.880879       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:31.895402       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:31.876764       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:31.876604429 +0000 UTC m=+70.082730212
I0924 13:08:31.934137       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:31.935188       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:31.958323       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:31.959412       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:31.961070       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:31.967576       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.020546       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.042479       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:32.045260       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.061992       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.067340       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.068473       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.104914       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.125470       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.148154       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.152582       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.161107       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.171938       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.206375       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.214334       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.216865       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.218330       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.222157       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.222160       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.230938       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.222251       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.274054       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.315405       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.322835       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.325510       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.328923       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.333133       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.335605       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.333830       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.381211       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.386240       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.389561       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.394066       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.421784       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.413665       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.394120       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.396258       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.476969       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.481169       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.486808       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.487062       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.492792       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.496534       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.497364       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.499623       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.516246       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.529908       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.541479       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.542611       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.544615       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.546156       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.550164       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.553016       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.558777       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.567895       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.575790       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.576697       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.568180       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.579935       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.588002       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.588991       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.592711       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.595311       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.622302       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.624194       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.631406       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.641891       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.694950       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.709389       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.709753       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.808774       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.793045       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.710403       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:32.820237       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.825683       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.794679       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.828943       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.791279       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.841197       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.848429       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.748839       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.768980       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.914849       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.917593       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.921182       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.922172       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.927829       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.932419       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.933974       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.937686       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.939004       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.956496       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.958082       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:32.974303       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.961408       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:32.961117       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.038721       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.049202       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.052005       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.055538       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.058152       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.060970       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.063070       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.084476       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.087932       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.098864       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.074335       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.108863       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.109779       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.113255       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.109927       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.114684       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.120931       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.121015       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.124804       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.130202       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.156765       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.179414       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:33.202630       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.209207       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.253769       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.211867       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.258978       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.260595       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.287112       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:33.310497       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:33.337657       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.360335       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:33.433422       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.443010       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.463179       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.509307       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:33.576332       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:33.579711       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:33.583390       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.611302       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.686472       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:33.687140       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.711454       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.749935       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:33.753451       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:33.757391       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.761942       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.782556       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.788740       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.792197       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.819430       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.829212       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.943459       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:33.963839       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.968365       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.973312       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.988313       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:33.995507       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.001344       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.006417       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.011094       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.015954       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:34.015979       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.098787       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.112689       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.128272       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:34.206223       1 controller.go:245] Starting service-catalog controller
I0924 13:08:34.275319       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:34.277189       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:34.295720       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:34.308968       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:34.308908805 +0000 UTC m=+72.515034592
I0924 13:08:34.320701       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:34.324959       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:34.326530       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:34.327157       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:34.565415       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.584730       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.590571       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.600118       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.605421       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.625443       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.640288       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.666902       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.671598       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.676026       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.680348       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.684707       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.689804       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.696440       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.701142       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.709365       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.764966       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0924 13:08:34.773423       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:34.829112       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:36.053956       1 controller.go:245] Starting service-catalog controller
I0924 13:08:36.125805       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:36.137290       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:36.150058       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
... skipping 39 lines ...
I0924 13:08:37.132176       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:37.166792       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:37.178329       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:37.201474       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:37.201953       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:37.343876       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:37.347555       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:37.400257       1 controller.go:245] Starting service-catalog controller
I0924 13:08:37.416563       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:37.483665       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:37.527669       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:37.536898       1 controller.go:245] Starting service-catalog controller
I0924 13:08:37.566020       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:37.575769       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:37.648522       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:37.576970       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:37.648568       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:37.664143       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:37.664081456 +0000 UTC m=+75.870207247
I0924 13:08:37.700569       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:37.721877       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:37.579963       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:37.722894       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:37.735519       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:37.73543911 +0000 UTC m=+75.941564889
I0924 13:08:37.766183       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:37.768615       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:37.785064       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:37.796890       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:37.807604       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:37.808203       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:37.939562       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:37.942513       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:38.185184       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:38.202726       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:38.216917       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:38.240372       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:38.255823       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:38.265827       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:38.633705       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:38.712915       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:38.742339       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:38.762991       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:38.817177       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:38.834350       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
... skipping 37 lines ...
I0924 13:08:40.177172       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:40.210289       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:40.271342       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:40.312821       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:40.405976       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:40.443914       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:40.464268       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:40.487274       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:40.494487       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:40.544562       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:40.546768       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The provision call failed and will be retried: Error communicating with broker for provisioning: Put https://fakebroker.com/v2/service_instances/instance_id: non-timeout error
I0924 13:08:41.498987       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:41.514262       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:41.514838       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:41.545171       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:41.601217       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0924 13:08:41.604421       1 controller.go:279] Shutting down service-catalog controller
... skipping 469 lines ...
I0924 13:08:57.608328       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:08:57.608792       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0924 13:08:57.613201       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:08:57.613713       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0924 13:08:57.615292       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0924 13:08:57.618342       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
E0924 13:08:57.621159       1 controller_binding.go:751] ServiceBinding "test-ns/test-binding": Error updating status: servicebindings.servicecatalog.k8s.io "test-binding" not found
I0924 13:08:57.623303       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0924 13:08:57.624581       1 controller_binding.go:124] ServiceBinding "test-ns/test-binding": Not doing work because the ServiceBinding has been deleted
I0924 13:08:57.625360       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being deprovisioned asynchronously
I0924 13:08:57.633481       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0924 13:08:57.634451       1 controller_binding.go:124] ServiceBinding "test-ns/test-binding": Not doing work because the ServiceBinding has been deleted
I0924 13:08:57.634816       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
... skipping 64 lines ...
I0924 13:08:58.638760       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0924 13:08:58.594418       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0924 13:08:58.641879       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:58.645472       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:58.645541       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:58.645366781 +0000 UTC m=+96.851492580
I0924 13:08:58.626933       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:58.626879224 +0000 UTC m=+96.833005004
I0924 13:08:58.612130       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[33 34 65 114 103 115 34 58 123 34 102 105 114 115 116 34 58 34 102 105 114 115 116 45 97 114 103 34 44 34 115 101 99 111 110 100 34 58 34 115 101 99 111 110 100 45 97 114 103 34 125 44 34 78 97 109 101 34 58 34 116 101 115 116 45 112 97 114 97 109 34 125],}: error converting YAML to JSON: yaml: did not find expected whitespace or line break
I0924 13:08:58.659315       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:58.600881       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:58.600700347 +0000 UTC m=+96.806826138
I0924 13:08:58.645582       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-09-24 13:08:58.645517988 +0000 UTC m=+96.851643778
I0924 13:08:58.651939       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0924 13:08:58.675342       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:58.682808       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[33 34 65 114 103 115 34 58 123 34 102 105 114 115 116 34 58 34 102 105 114 115 116 45 97 114 103 34 44 34 115 101 99 111 110 100 34 58 34 115 101 99 111 110 100 45 97 114 103 34 125 44 34 78 97 109 101 34 58 34 116 101 115 116 45 112 97 114 97 109 34 125],}: error converting YAML to JSON: yaml: did not find expected whitespace or line break
I0924 13:08:58.682953       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0924 13:08:58.683601       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:58.687214       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0924 13:08:58.708165       1 controller.go:245] Starting service-catalog controller
I0924 13:08:58.720477       1 controller_clusterserviceplan.go:76] ClusterServicePlan "randomz49zz44z" (ExternalName: "randomPlan"): processing
I0924 13:08:58.724085       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0924 13:08:58.731471       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:58.747643       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[33 34 65 114 103 115 34 58 123 34 102 105 114 115 116 34 58 34 102 105 114 115 116 45 97 114 103 34 44 34 115 101 99 111 110 100 34 58 34 115 101 99 111 110 100 45 97 114 103 34 125 44 34 78 97 109 101 34 58 34 116 101 115 116 45 112 97 114 97 109 34 125],}: error converting YAML to JSON: yaml: did not find expected whitespace or line break
I0924 13:08:58.758446       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:58.768462       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:58.771307       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0924 13:08:58.778290       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0924 13:08:58.782456       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0924 13:08:58.783292       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
... skipping 37 lines ...
I0924 13:08:59.129094       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent broker "test-clusterservicebroker"
I0924 13:08:59.139171       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:59.160978       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:59.166426       1 controller.go:279] Shutting down service-catalog controller
I0924 13:08:59.194151       1 controller.go:296] Shutdown service-catalog controller
I0924 13:08:59.272045       1 controller.go:296] Shutdown service-catalog controller
--- FAIL: TestRetryAsyncDeprovision (9.11s)
    controller_flow_instance_test.go:96: 
        	Error Trace:	controller_flow_instance_test.go:96
        	Error:      	Received unexpected error:
        	            	service instance with proper deprovision status not found, the existing service instance status: {Conditions:[{Type:Ready Status:True LastTransitionTime:2020-09-24 13:08:58.969154705 +0000 UTC m=+97.175280520 Reason:ProvisionedSuccessfully Message:The instance was provisioned successfully}] AsyncOpInProgress:false OrphanMitigationInProgress:false LastOperation:<nil> DashboardURL:<nil> CurrentOperation: ReconciledGeneration:0 ObservedGeneration:0 OperationStartTime:<nil> InProgressProperties:<nil> ExternalProperties:0xc0050d6780 ProvisionStatus:Provisioned DeprovisionStatus:Required DefaultProvisionParameters:nil LastConditionState:Ready UserSpecifiedPlanName:34567 UserSpecifiedClassName:ClusterServiceClass/clusterserviceclass-12345}
        	Test:       	TestRetryAsyncDeprovision
    controller_flow_instance_test.go:98: 
        	Error Trace:	controller_flow_instance_test.go:98
        	Error:      	Should be true
        	Test:       	TestRetryAsyncDeprovision
I0924 13:09:07.056656       1 controller.go:279] Shutting down service-catalog controller
FAIL
make: *** [Makefile:295: test-integration] Error 1
+ EXIT_VALUE=2
+ set +o xtrace
Cleaning up after docker in docker.
================================================================================
Cleaning up after docker
Stopping Docker: dockerProgram process in pidfile '/var/run/docker-ssd.pid', 1 process(es), refused to die.
... skipping 3 lines ...