This job view page is being replaced by Spyglass soon. Check out the new job view.
PRasychev: Add Priority Class support for catalog Helm Chart
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2020-07-24 12:51
Elapsed6m45s
Revision9b0b26fc2f34f5ac8aef05f293790ad40ec18890
Refs 2832

No Test Failures!


Error lines from build-log.txt

... skipping 200 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
I0724 12:53:33.866178    2925 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]
I0724 12:53:33.940913    2925 openapi.go:461] [k8s.io/apimachinery/pkg/apis/meta/v1.LabelSelectorRequirement] Key string: tag patchMergeKey on type Builtin; only allowed on type Slice
I0724 12:53:33.940944    2925 openapi.go:461] [k8s.io/apimachinery/pkg/apis/meta/v1.LabelSelectorRequirement] Key string: tag patchStrategy on type Builtin; only allowed on type Slice
I0724 12:53:34.751837    2925 api_linter.go:44] Assembling file "./api_violations.txt"
+ diff -u ./api_violations.txt ./contrib/build/violation_exceptions.txt
... skipping 126 lines ...
I0724 12:57:26.558380       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:26.558509       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.558363369 +0000 UTC m=+0.078672780
I0724 12:57:26.558973       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:26.559126       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:26.559207       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:26.576026       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously (testdescr)
I0724 12:57:26.581825       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
I0724 12:57:26.581935       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
W0724 12:57:26.589785       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:26.595018       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0724 12:57:26.595116       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:26.595239       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
W0724 12:57:26.599998       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:26.600091       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0724 12:57:26.600152       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:26.600246       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:26.605618       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0724 12:57:26.605730       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0724 12:57:26.605855       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:26.611142       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
I0724 12:57:26.611267       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
I0724 12:57:26.611421       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:26.617794       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:26.623660       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:26.623836       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:26.623921       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:26.631324       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:26.631549       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:26.631642       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:26.637568       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0724 12:57:26.647344       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0724 12:57:26.652241       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:26.657955       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:26.658142       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
W0724 12:57:26.664079       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:26.664172       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:26.664309       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:26.670100       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0724 12:57:26.670254       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:26.676262       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0724 12:57:26.682479       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0724 12:57:26.689983       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0724 12:57:26.702018       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0724 12:57:26.707796       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:26.713920       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:26.714096       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
W0724 12:57:26.720725       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:26.720821       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:26.720932       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:26.726559       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0724 12:57:26.726734       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:26.732637       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:26.732776       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.7326208 +0000 UTC m=+0.252930194
I0724 12:57:26.733249       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0724 12:57:26.746331       1 controller_binding.go:698] ServiceBinding "test-binding": References a non-existent ServiceInstance "/nothere"
I0724 12:57:26.746479       1 controller_binding.go:712] ServiceBinding "test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.746216842 +0000 UTC m=+0.266526244
I0724 12:57:26.752254       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 ...
I0724 12:57:26.759323       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.759104495 +0000 UTC m=+0.279413906
W0724 12:57:26.766725       1 controller.go:585] ServiceInstance "test-ns/test-instance": References a non-existent ClusterServiceClass "nosuchclassid" - {ClusterServiceClassExternalName:"nothere"}
I0724 12:57:26.766841       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"}
I0724 12:57:26.766928       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.766818887 +0000 UTC m=+0.287128294
I0724 12:57:26.774364       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.774516       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.774345545 +0000 UTC m=+0.294654941
I0724 12:57:26.775279       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
I0724 12:57:26.780893       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.781021       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.780881214 +0000 UTC m=+0.301190610
I0724 12:57:26.782364       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:26.787834       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.787996       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.787824043 +0000 UTC m=+0.308133447
I0724 12:57:26.788437       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
... skipping 6 lines ...
I0724 12:57:26.807919       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
I0724 12:57:26.808079       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.80790474 +0000 UTC m=+0.328214142
I0724 12:57:26.808126       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
I0724 12:57:26.814547       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/test-instance" is not ready
I0724 12:57:26.814712       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.814534761 +0000 UTC m=+0.334844159
I0724 12:57:26.820774       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": 
I0724 12:57:26.821217       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Failed to get namespace "test-ns" during binding: No namespace
I0724 12:57:26.821344       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.821205657 +0000 UTC m=+0.341515059
I0724 12:57:26.828625       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:26.828786       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.828605395 +0000 UTC m=+0.348914789
I0724 12:57:26.829257       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:26.829785       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:26.829994       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
... skipping 15 lines ...
I0724 12:57:26.854097       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:26.854734       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:26.854958       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:26.855083       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:26.862002       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.862156       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.861991244 +0000 UTC m=+0.382300639
I0724 12:57:26.862637       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
I0724 12:57:26.869905       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.870083       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.869893187 +0000 UTC m=+0.390202580
I0724 12:57:26.870560       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>
I0724 12:57:26.870651       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>
I0724 12:57:26.884493       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.884657       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.884477907 +0000 UTC m=+0.404787303
I0724 12:57:26.885260       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
I0724 12:57:26.892443       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.892602       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.892428498 +0000 UTC m=+0.412737892
I0724 12:57:26.893078       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>
I0724 12:57:26.893186       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>
I0724 12:57:26.899991       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0724 12:57:26.900156       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.899973136 +0000 UTC m=+0.420282545
I0724 12:57:26.905669       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0724 12:57:26.911153       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": bar
I0724 12:57:26.919803       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0724 12:57:26.924332       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0724 12:57:26.928812       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": 
I0724 12:57:26.935516       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:26.935676       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.935501346 +0000 UTC m=+0.455810748
I0724 12:57:26.936088       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
I0724 12:57:26.941202       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:26.941368       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.941190425 +0000 UTC m=+0.461499819
I0724 12:57:26.941860       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>
W0724 12:57:26.942855       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=false. It will be removed in a future release.
I0724 12:57:26.948027       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:26.948176       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.948013024 +0000 UTC m=+0.468322419
I0724 12:57:26.948728       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
W0724 12:57:26.949051       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0724 12:57:26.953535       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
... skipping 22 lines ...
I0724 12:57:26.982546       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:26.982684       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:26.982763       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
W0724 12:57:26.983134       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0724 12:57:26.989593       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:26.989759       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.989575883 +0000 UTC m=+0.509885314
I0724 12:57:26.995750       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
I0724 12:57:26.995912       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:26.995731851 +0000 UTC m=+0.516041254
I0724 12:57:26.995972       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries, too much time has elapsed
I0724 12:57:27.002483       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
I0724 12:57:27.002642       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.002464076 +0000 UTC m=+0.522773486
I0724 12:57:27.002695       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries, too much time has elapsed
I0724 12:57:27.002778       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:27.009326       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:27.009558       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.009313243 +0000 UTC m=+0.529622638
E0724 12:57:27.009661       1 controller_binding.go:751] ServiceBinding "test-ns/test-binding": Error updating status: update error
I0724 12:57:27.016406       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:27.016615       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.016391282 +0000 UTC m=+0.536700677
I0724 12:57:27.018039       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:27.025395       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:27.025625       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.025368557 +0000 UTC m=+0.545677950
I0724 12:57:27.026364       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
I0724 12:57:27.032132       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:27.032330       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.032113472 +0000 UTC m=+0.552422866
I0724 12:57:27.033041       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>
I0724 12:57:27.033139       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>
I0724 12:57:27.039481       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:27.039640       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.039465914 +0000 UTC m=+0.559775316
... skipping 21 lines ...
I0724 12:57:27.080466       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.080240943 +0000 UTC m=+0.600550338
I0724 12:57:27.080975       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>
I0724 12:57:27.081058       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>
I0724 12:57:27.081167       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:27.088661       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0724 12:57:27.088948       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.088643435 +0000 UTC m=+0.608952897
I0724 12:57:27.096981       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
I0724 12:57:27.097174       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries, too much time has elapsed
I0724 12:57:27.102943       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:27.103186       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.102930827 +0000 UTC m=+0.623240225
I0724 12:57:27.103784       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:27.103939       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:27.103998       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:27.110150       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
... skipping 5 lines ...
I0724 12:57:27.117380       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.117198701 +0000 UTC m=+0.637508096
I0724 12:57:27.117794       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0724 12:57:27.124666       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:27.124848       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.124652444 +0000 UTC m=+0.644961840
I0724 12:57:27.125313       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0724 12:57:27.142426       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously (testdescr)
I0724 12:57:27.148947       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
I0724 12:57:27.149154       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: testdescr
W0724 12:57:27.154630       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:27.160955       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0724 12:57:27.161144       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.161293       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
W0724 12:57:27.167930       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:27.168096       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Bind operation timed out and will not be retried
I0724 12:57:27.168226       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.168410       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:27.175279       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0724 12:57:27.175470       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Could not do a GET on binding resource: some error
I0724 12:57:27.175623       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:27.182774       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
I0724 12:57:27.182953       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
I0724 12:57:27.183127       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Starting orphan mitigation
I0724 12:57:27.189865       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:27.196603       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:27.196845       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:27.196981       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:27.203591       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:27.203799       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:27.203861       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:27.210558       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0724 12:57:27.224159       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0724 12:57:27.231469       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:27.238720       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:27.239035       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
W0724 12:57:27.246296       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:27.246452       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:27.246600       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.254110       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0724 12:57:27.254373       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.260914       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0724 12:57:27.267599       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation was completed successfully
I0724 12:57:27.274793       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously (testdescr)
I0724 12:57:27.288762       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
W0724 12:57:27.296078       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:27.303190       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:27.303475       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
W0724 12:57:27.310633       1 controller_binding.go:1095] ServiceBinding "test-ns/test-binding": Got invalid state in LastOperationResponse: "test invalid state"
I0724 12:57:27.310846       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The asynchronous Unbind operation timed out and will not be retried
I0724 12:57:27.311098       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.319078       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: testdescr
I0724 12:57:27.319353       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.425370       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.426319       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:27.427063       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.427020448 +0000 UTC m=+0.947329884
I0724 12:57:27.434452       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:57:27.435681       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:27.436421       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.436377391 +0000 UTC m=+0.956686812
... skipping 6 lines ...
I0724 12:57:27.467989       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:57:27.469359       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0724 12:57:27.470298       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.470236118 +0000 UTC m=+0.990545542
I0724 12:57:27.479590       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.480501       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0724 12:57:27.480827       1 controller_clusterservicebroker.go:484] ClusterServiceBroker "test-clusterservicebroker": ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") already exists for Broker "notTheSame"
W0724 12:57:27.481109       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"
I0724 12:57:27.481299       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.481241539 +0000 UTC m=+1.001550965
I0724 12:57:27.491362       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.492749       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0724 12:57:27.493356       1 controller_clusterservicebroker.go:572] ClusterServiceBroker "test-clusterservicebroker": ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") already exists for Broker "notTheSame"
W0724 12:57:27.493623       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"
I0724 12:57:27.493875       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.49377926 +0000 UTC m=+1.014088701
I0724 12:57:27.504521       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:57:27.505209       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0724 12:57:27.505629       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.505544502 +0000 UTC m=+1.025853924
I0724 12:57:27.514517       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:57:27.515677       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0724 12:57:27.516034       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.515955732 +0000 UTC m=+1.036265164
I0724 12:57:27.526158       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:57:27.527540       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0724 12:57:27.527892       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.527838683 +0000 UTC m=+1.048148113
W0724 12:57:27.536330       1 controller_clusterservicebroker.go:184] ClusterServiceBroker "test-clusterservicebroker": Error getting broker catalog: ooops
I0724 12:57:27.536607       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.536535534 +0000 UTC m=+1.056844953
I0724 12:57:27.545223       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.546263       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:27.546419       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.546334897 +0000 UTC m=+1.066644306
I0724 12:57:27.582701       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0724 12:57:27.583034       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.5829458 +0000 UTC m=+1.103255229
I0724 12:57:27.588605       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0724 12:57:27.589192       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.588901176 +0000 UTC m=+1.109210601
I0724 12:57:27.592878       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0724 12:57:27.593312       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.593216673 +0000 UTC m=+1.113526098
I0724 12:57:27.594375       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0724 12:57:27.594532       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0724 12:57:27.594676       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.594619378 +0000 UTC m=+1.114928789
I0724 12:57:27.595572       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.595482999 +0000 UTC m=+1.115792425
I0724 12:57:27.596056       1 controller_clusterservicebroker.go:135] ClusterServiceBroker "test-clusterservicebroker": Error getting broker auth credentials: auth secret didn't contain token
I0724 12:57:27.596486       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.596405373 +0000 UTC m=+1.116714799
I0724 12:57:27.607270       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.608407       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
E0724 12:57:27.608810       1 controller_clusterservicebroker.go:493] ClusterServiceBroker "test-clusterservicebroker": Error creating ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass"): error creating serviceclass
W0724 12:57:27.609571       1 controller_clusterservicebroker.go:267] ClusterServiceBroker "test-clusterservicebroker": Error reconciling ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") (broker "test-clusterservicebroker"): error creating serviceclass
I0724 12:57:27.609779       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.609690464 +0000 UTC m=+1.129999881
I0724 12:57:27.618471       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.619839       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:27.620654       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.620610166 +0000 UTC m=+1.140919582
W0724 12:57:27.629140       1 controller_clusterservicebroker.go:184] ClusterServiceBroker "test-clusterservicebroker": Error getting broker catalog: ooops
I0724 12:57:27.629592       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.629393989 +0000 UTC m=+1.149703415
I0724 12:57:27.629864       1 controller_clusterservicebroker.go:198] ClusterServiceBroker "test-clusterservicebroker": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:27.630016       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Failed" to 2020-07-24 12:57:27.629931266 +0000 UTC m=+1.150240705
I0724 12:57:27.639088       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.640329       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:27.641163       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.641103893 +0000 UTC m=+1.161413327
E0724 12:57:27.641352       1 controller_clusterservicebroker.go:688] ClusterServiceBroker "test-clusterservicebroker": Error updating ready condition: update error
I0724 12:57:27.650011       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.649946028 +0000 UTC m=+1.170255438
I0724 12:57:27.677713       1 controller_clusterservicebroker.go:661] ClusterServiceBroker "test-clusterservicebroker": Found status change for condition "Ready": "False" -> "True"; setting lastTransitionTime to 2020-07-24 12:57:27.677601639 +0000 UTC m=+1.197911052
I0724 12:57:27.688653       1 controller_clusterservicebroker.go:661] ClusterServiceBroker "test-clusterservicebroker": Found status change for condition "Ready": "True" -> "False"; setting lastTransitionTime to 2020-07-24 12:57:27.688568914 +0000 UTC m=+1.208878325
E0724 12:57:27.716445       1 controller_clusterservicebroker.go:616] ClusterServiceBroker "test-clusterservicebroker": Error updating ClusterServicePlan (K8S: "cspguid" ExternalName: "new-value"): oops
I0724 12:57:27.723954       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:57:27.724934       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServicePlans
I0724 12:57:27.725966       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.72591905 +0000 UTC m=+1.246228465
I0724 12:57:27.733738       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:27.734662       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:27.735282       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:27.735231719 +0000 UTC m=+1.255541136
... skipping 30 lines ...
I0724 12:57:27.837939       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v272z9cnpt6: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:27.838828       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v272z9cnpt6: The instance was provisioned successfully
I0724 12:57:27.847510       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vzt7sxhzbg2: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:27.848450       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vzt7sxhzbg2: The instance is being provisioned asynchronously
I0724 12:57:27.857653       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0724 12:57:27.868075       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0724 12:57:27.878937       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0724 12:57:27.879223       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0724 12:57:27.879446       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0724 12:57:27.879613       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
I0724 12:57:27.899546       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:27.900569       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:27.900899       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:27.911169       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:27.911985       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously
I0724 12:57:27.921170       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0724 12:57:27.930109       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0724 12:57:27.940665       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:27.941143       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:27.949280       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0724 12:57:27.959529       1 controller_instance.go:1491] ServiceInstance "test-ns/test-instance": Found 1 ServiceClasses
I0724 12:57:27.963597       1 controller_instance.go:1612] ServiceInstance "test-ns/test-instance": Found 1 ServicePlans
I0724 12:57:27.985180       1 controller_instance.go:1417] ServiceInstance "test-instance": Found 0 ClusterServiceClasses
W0724 12:57:27.985476       1 controller_instance.go:1281] ServiceInstance "test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassExternalName:"nothere"} or there is more than one (found: 0)
I0724 12:57:27.985653       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)
W0724 12:57:27.994528       1 controller_instance.go:1281] ServiceInstance "test-instance": References a non-existent ClusterServiceClass {ClusterServiceClassName:"nothereclass"}
... skipping 10 lines ...
I0724 12:57:28.057069       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vclr997ntpm: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.059605       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vclr997ntpm: The instance was provisioned successfully
I0724 12:57:28.082604       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnsqjwlqfc6: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.086662       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnsqjwlqfc6: The instance was provisioned successfully
I0724 12:57:28.106756       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vmrcfn8l5cv: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.112183       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vmrcfn8l5cv: The instance was provisioned successfully
I0724 12:57:28.129976       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5djc6gb8sp: 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 {}
I0724 12:57:28.150287       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vp8hk8k2qjl: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0724 12:57:28.170640       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vmltvsfdb9h: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0724 12:57:28.189780       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vs8z5xtlrvl: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: unexpected end of JSON input
I0724 12:57:28.213148       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vdbv7rskv2q: failed to prepare parameters nil: failed to unmarshal parameters as JSON object: invalid character 'b' looking for beginning of value
I0724 12:57:28.241704       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vls29fglp75: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.243233       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vls29fglp75: The instance was provisioned successfully
I0724 12:57:28.269937       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 1 ClusterServiceClasses
I0724 12:57:28.278908       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0724 12:57:28.306928       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v4wnn2vv28n: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.329120       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0724 12:57:28.348237       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vkvm8k445z7: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.349571       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vkvm8k445z7: The provision call failed and will be retried: Error communicating with broker for provisioning: fake creation failure
I0724 12:57:28.367823       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vs89btlmwfk: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.371283       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vs89btlmwfk: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: InternalServerError; Description: Something went wrong!; ResponseError: <nil>
I0724 12:57:28.371403       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vs89btlmwfk: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: InternalServerError; Description: Something went wrong!; ResponseError: <nil>
I0724 12:57:28.371552       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vs89btlmwfk: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0724 12:57:28.386883       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v72hdkc5g64: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.388543       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v72hdkc5g64: 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>
I0724 12:57:28.388702       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v72hdkc5g64: 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>
I0724 12:57:28.405410       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5cqm2r5kq9: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.406834       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5cqm2r5kq9: The instance was provisioned successfully
I0724 12:57:28.421936       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v7w9j8tjxkd: ClusterServicePlan (K8S: "cspguid" ExternalName: "test-clusterserviceplan") has been deleted; cannot provision.
I0724 12:57:28.440587       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vd2z9vkvqm8: ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") has been deleted; cannot provision.
I0724 12:57:28.461862       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfnvvj75wgd: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.463116       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vfnvvj75wgd: The instance was provisioned successfully
I0724 12:57:28.475939       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcxpsbfqwjk: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.477422       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcxpsbfqwjk: The instance is being provisioned asynchronously
I0724 12:57:28.495209       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6mc2pntfvk: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.497172       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v6mc2pntfvk: The instance is being provisioned asynchronously
I0724 12:57:28.515889       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v9rcj5rdkr2: Failed to get namespace "test-ns": No namespace
I0724 12:57:28.530734       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:28.532357       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:28.532747       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:28.551219       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:28.554740       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:28.556246       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
... skipping 16 lines ...
I0724 12:57:28.732533       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:28.850996       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance" v1: Cleared finalizer
I0724 12:57:28.871165       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8v8rhhnf88: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:28.877933       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v8v8rhhnf88: The instance was provisioned successfully
I0724 12:57:28.896705       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0724 12:57:28.914194       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0724 12:57:28.930424       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0724 12:57:28.930752       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0724 12:57:28.931057       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision call failed: (no description provided)
I0724 12:57:28.931218       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
I0724 12:57:28.952641       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0724 12:57:28.974623       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (testdescr)
I0724 12:57:29.003376       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:29.004050       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:29.020180       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0724 12:57:29.036320       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision call failed: (no description provided)
I0724 12:57:29.036793       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:29.053265       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:29.054228       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:29.076438       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 403; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.093913       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.097743       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error polling last operation: Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.121961       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:29.122780       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:29.143335       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v2bw6d88pkb: The instance was provisioned successfully
I0724 12:57:29.167436       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vpgw5hd47bz: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.187499       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vh2zpk6h2zf: The provision call failed and will be retried: Error communicating with broker for provisioning: fake creation failure
I0724 12:57:29.188018       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vh2zpk6h2zf: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:29.206289       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was provisioned successfully
I0724 12:57:29.226135       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0724 12:57:29.226599       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Stopping reconciliation retries because too much time has elapsed
I0724 12:57:29.226882       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
I0724 12:57:29.227291       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
I0724 12:57:29.246731       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Provision request for ServiceInstance in-flight to Broker
E0724 12:57:29.247926       1 controller_instance.go:1985] ServiceInstance "test-ns/test-instance": Failed to update status: update error
I0724 12:57:29.251964       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0724 12:57:29.252901       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0724 12:57:29.253326       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": DifferentMessage
I0724 12:57:29.253816       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0724 12:57:29.254378       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
I0724 12:57:29.254742       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Message
... skipping 33 lines ...
W0724 12:57:29.544239       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0724 12:57:29.569069       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
W0724 12:57:29.570068       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0724 12:57:29.595629       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being provisioned asynchronously (testdescr)
W0724 12:57:29.596997       1 feature_gate.go:235] Setting GA feature gate OriginatingIdentity=true. It will be removed in a future release.
I0724 12:57:29.617062       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vdgwj4nhs8z: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.618973       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vdgwj4nhs8z: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 200; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.637843       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vm77hxjgsjd: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.639624       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vm77hxjgsjd: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 201; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.639786       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vm77hxjgsjd: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 201; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.640018       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vm77hxjgsjd: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0724 12:57:29.662064       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vn5rlbndpg4: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.664576       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vn5rlbndpg4: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 300; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.686229       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcvx6s7gvpq: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.688123       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcvx6s7gvpq: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.688259       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vcvx6s7gvpq: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.711467       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5b22dgg7p8: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.717890       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v5b22dgg7p8: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 408; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.735864       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vgvqvcznhqm: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.738520       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vgvqvcznhqm: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.738808       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vgvqvcznhqm: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 400; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.757056       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqjnhjhtmxj: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.759665       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqjnhjhtmxj: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.759926       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqjnhjhtmxj: Error provisioning ServiceInstance of ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": Status: 500; ErrorMessage: <nil>; Description: <nil>; ResponseError: <nil>
I0724 12:57:29.760215       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vqjnhjhtmxj: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0724 12:57:29.780132       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v546ml2zwg8: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:29.781806       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v546ml2zwg8: Communication with the ClusterServiceBroker timed out; operation will be retried:  : timed out
I0724 12:57:29.782041       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v546ml2zwg8: Communication with the ClusterServiceBroker timed out; operation will be retried:  : timed out
I0724 12:57:29.782322       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" v546ml2zwg8: The instance provision call failed with an ambiguous error; attempting to deprovision the instance in order to mitigate an orphaned resource
I0724 12:57:29.805781       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
I0724 12:57:29.807902       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0724 12:57:29.838327       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
I0724 12:57:29.840470       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously
I0724 12:57:29.869782       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
I0724 12:57:29.871927       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>
I0724 12:57:29.898539       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
I0724 12:57:29.899905       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:29.919602       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
I0724 12:57:29.920697       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Error deprovisioning, ClusterServiceClass (K8S: "cscguid" ExternalName: "test-clusterserviceclass") at ClusterServiceBroker "test-clusterservicebroker": other error
I0724 12:57:29.937641       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
I0724 12:57:29.938663       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:29.957087       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
I0724 12:57:29.958377       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0724 12:57:29.974809       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
I0724 12:57:29.975895       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation was completed successfully
I0724 12:57:30.024582       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
I0724 12:57:30.025966       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being deprovisioned asynchronously (description)
I0724 12:57:30.058636       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
I0724 12:57:30.059977       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:30.081955       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
I0724 12:57:30.082829       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:30.094194       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
I0724 12:57:30.095102       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:30.105622       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
I0724 12:57:30.106523       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:30.116780       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
W0724 12:57:30.117771       1 controller_instance.go:1173] ServiceInstance "test-ns/test-instance": Got invalid state in LastOperationResponse: "invalid state"
I0724 12:57:30.117986       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Orphan mitigation failed: Stopping reconciliation retries because too much time has elapsed
I0724 12:57:30.137102       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnmqgsr7978: Provision request for ServiceInstance in-flight to Broker
I0724 12:57:30.140067       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance" vnmqgsr7978: The instance was provisioned successfully
I0724 12:57:30.177191       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 0 ClusterServiceClasses
W0724 12:57:30.179133       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)
I0724 12:57:30.179851       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)
I0724 12:57:30.199833       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
... skipping 10 lines ...
I0724 12:57:30.284497       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0724 12:57:30.298216       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:30.300242       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0724 12:57:30.318172       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:30.321030       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0724 12:57:30.337005       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:30.339246       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
I0724 12:57:30.352974       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:30.355448       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>
I0724 12:57:30.367204       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:30.368919       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>
I0724 12:57:30.369041       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>
I0724 12:57:30.395381       1 controller_instance.go:1417] ServiceInstance "test-ns/test-instance": Found 1 ClusterServiceClasses
I0724 12:57:30.402252       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0724 12:57:30.425548       1 controller_instance.go:1552] ServiceInstance "test-ns/test-instance": Found 1 ClusterServicePlans
I0724 12:57:30.459679       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:30.461361       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being updated asynchronously
I0724 12:57:30.480063       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance is being updated asynchronously (testdescr)
I0724 12:57:30.494305       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was updated successfully
I0724 12:57:30.507377       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update call failed: (no description provided)
I0724 12:57:30.507744       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Update call failed: (no description provided)
I0724 12:57:30.632843       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:30.638068       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:30.638921       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:30.659155       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
I0724 12:57:30.660556       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:30.662202       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:30.662806       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:30.681313       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:30.682798       1 controller_instance.go:1829] ServiceInstance "test-ns/test-instance": The instance was deprovisioned successfully
I0724 12:57:30.683156       1 controller_instance.go:2656] ServiceInstance "test-ns/test-instance": Cleared finalizer
I0724 12:57:30.724839       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
W0724 12:57:30.750837       1 controller_servicebroker.go:175] ServiceBroker "test-ns/test-servicebrokernot-predefined": Error getting broker catalog: Unexpected action
I0724 12:57:30.751411       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebrokernot-predefined": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:30.751257154 +0000 UTC m=+4.271566586
I0724 12:57:30.779668       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0724 12:57:30.782461       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0724 12:57:30.783036       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:30.782967407 +0000 UTC m=+4.303276838
I0724 12:57:30.811676       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0724 12:57:30.813117       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0724 12:57:30.815094       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:30.814947323 +0000 UTC m=+4.335256769
I0724 12:57:30.843110       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0724 12:57:30.846295       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 1 ServicePlans
I0724 12:57:30.847200       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:30.847044174 +0000 UTC m=+4.367353608
E0724 12:57:31.079766       1 controller_servicebroker.go:590] ServiceBroker "test-ns/test-servicebroker": Error updating ServicePlan (K8S: "test-ns/spguid" ExternalName: "new-value"): oops
I0724 12:57:31.106879       1 controller_servicebroker.go:728] ServiceBroker "test-ns/test-servicebroker": Found 1 ServiceClasses
I0724 12:57:31.109512       1 controller_servicebroker.go:745] ServiceBroker "test-ns/test-servicebroker": Found 0 ServicePlans
I0724 12:57:31.113076       1 controller_servicebroker.go:626] ServiceBroker "test-ns/test-servicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:31.112968345 +0000 UTC m=+4.633277782
I0724 12:57:31.135450       1 controller_serviceclass.go:80] ServiceClass "test-ns/scguid": Processing
I0724 12:57:31.158244       1 controller_serviceclass.go:80] ServiceClass "test-ns/scguid": Processing
I0724 12:57:31.159039       1 controller_serviceclass.go:86] ServiceClass "test-ns/scguid": Removed from broker catalog; determining whether there are instances remaining
... skipping 114 lines ...
I0724 12:57:33.906280       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:33.946517       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:33.948714       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:33.999173       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:34.000154       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:33.999150728 +0000 UTC m=+7.519460147
I0724 12:57:34.003065       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0724 12:57:35.008495       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: (no description provided)
I0724 12:57:35.009563       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Bind call failed: (no description provided)
I0724 12:57:35.012953       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:35.015852       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:35.016832       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:35.017835       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:35.048953       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:35.058591       1 controller.go:296] Shutdown service-catalog controller
... skipping 8 lines ...
I0724 12:57:35.237267       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:35.240245       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:35.290684       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:35.291496       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:35.290635017 +0000 UTC m=+8.810944423
I0724 12:57:35.295082       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:35.336910       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:35.341276       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>
I0724 12:57:35.344572       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:35.345879       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:35.346756       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:35.348394       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:35.349317       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:35.388639       1 controller.go:279] Shutting down service-catalog controller
... skipping 11 lines ...
I0724 12:57:35.633176       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding request for ServiceBinding in-flight to Broker
I0724 12:57:35.633851       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:35.633156393 +0000 UTC m=+9.153465789
I0724 12:57:35.638572       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being created asynchronously
I0724 12:57:36.653036       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Injected bind result
I0724 12:57:36.697173       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind request for ServiceBinding in-flight to Broker
I0724 12:57:36.705171       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0724 12:57:37.712574       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Unbind call failed: (no description provided)
I0724 12:57:37.718009       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding is being deleted asynchronously
I0724 12:57:37.723643       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": The binding was deleted successfully
I0724 12:57:37.725425       1 controller_binding.go:1534] ServiceBinding "test-ns/test-binding": Status updated
I0724 12:57:37.726316       1 controller_binding.go:1545] ServiceBinding "test-ns/test-binding": Cleared finalizer
I0724 12:57:37.768773       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:37.780940       1 controller.go:296] Shutdown service-catalog controller
... skipping 5 lines ...
I0724 12:57:37.911963       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:37.91186242 +0000 UTC m=+11.432171879
I0724 12:57:37.912717       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:37.914155       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:37.958217       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:37.959016       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0724 12:57:37.959829       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:37.958955223 +0000 UTC m=+11.479264670
I0724 12:57:37.961161       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>
I0724 12:57:37.962336       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>
I0724 12:57:37.962741       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
I0724 12:57:37.963830       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0724 12:57:37.964525       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:37.967020       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:37.967555       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0724 12:57:37.969270       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:37.971497       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
... skipping 26 lines ...
I0724 12:57:38.144542       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:38.144474594 +0000 UTC m=+11.664784040
I0724 12:57:38.145470       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:38.146483       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:38.195140       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:38.195653       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0724 12:57:38.199302       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:38.195593255 +0000 UTC m=+11.715902659
I0724 12:57:38.200451       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>
I0724 12:57:38.201616       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>
I0724 12:57:38.202652       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
I0724 12:57:38.203354       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0724 12:57:38.205439       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:38.208004       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
I0724 12:57:38.208841       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:38.212246       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": All associated ServiceBindings must be removed before this ServiceInstance can be deleted
I0724 12:57:38.214419       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": Binding cannot begin because referenced ServiceInstance "test-ns/service-instance" is not ready
... skipping 102 lines ...
I0724 12:57:39.724736       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:39.725618       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:39.725509133 +0000 UTC m=+13.245818559
I0724 12:57:39.725851       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:39.727078       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:39.771822       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:39.774268       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:39.824749       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.825282       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:39.824681223 +0000 UTC m=+13.344990643
I0724 12:57:39.828410       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.832238       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.835129       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.838046       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.840547       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.843147       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.845821       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.848065       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.850694       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.852939       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.855449       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.858240       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.861115       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.864388       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.867479       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.870338       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.872870       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:39.873354       1 controller_binding.go:698] ServiceBinding "test-ns/test-binding": failed to prepare parameters nil: secrets "secret-name" not found
I0724 12:57:39.880939       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:39.991649       1 controller.go:245] Starting service-catalog controller
I0724 12:57:39.996176       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:39.997875       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:40.000845       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:40.002024       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:40.002335       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.002205831 +0000 UTC m=+13.522515282
I0724 12:57:40.003456       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:40.004383       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:40.049976       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:40.052283       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:40.107560       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
I0724 12:57:40.108216       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.107501171 +0000 UTC m=+13.627810604
I0724 12:57:40.111906       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
I0724 12:57:40.116496       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
I0724 12:57:40.118715       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
I0724 12:57:40.121454       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
I0724 12:57:40.125463       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
I0724 12:57:40.130104       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
I0724 12:57:40.134727       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
I0724 12:57:40.138393       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
I0724 12:57:40.140668       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
I0724 12:57:40.144581       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
I0724 12:57:40.148612       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
I0724 12:57:40.151999       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
I0724 12:57:40.155047       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
I0724 12:57:40.160821       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:40.177018       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:40.269931       1 controller.go:245] Starting service-catalog controller
I0724 12:57:40.280561       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:40.282146       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:40.286865       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:40.287691       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.287629464 +0000 UTC m=+13.807938916
I0724 12:57:40.289332       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:40.291232       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:40.291801       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:40.334747       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:40.338204       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:40.390340       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
I0724 12:57:40.390996       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.390308751 +0000 UTC m=+13.910618191
I0724 12:57:40.394698       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
I0724 12:57:40.399031       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
I0724 12:57:40.401818       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
I0724 12:57:40.406393       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
I0724 12:57:40.409219       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
I0724 12:57:40.413832       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
I0724 12:57:40.418193       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
I0724 12:57:40.422316       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
I0724 12:57:40.424468       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
I0724 12:57:40.427437       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
I0724 12:57:40.429918       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
I0724 12:57:40.432426       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
I0724 12:57:40.437755       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:40.447933       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
I0724 12:57:40.452448       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:40.567088       1 controller.go:245] Starting service-catalog controller
I0724 12:57:40.582992       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:40.584468       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:40.589147       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.589001981 +0000 UTC m=+14.109311409
I0724 12:57:40.589880       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:40.595713       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:40.598316       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:40.598679       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:40.627014       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:40.630006       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:40.683169       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
I0724 12:57:40.683688       1 controller_binding.go:712] ServiceBinding "test-ns/test-binding": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.68310683 +0000 UTC m=+14.203416240
I0724 12:57:40.687024       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
I0724 12:57:40.692223       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
I0724 12:57:40.698275       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
I0724 12:57:40.702015       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
I0724 12:57:40.707502       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
I0724 12:57:40.713944       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
I0724 12:57:40.717109       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
I0724 12:57:40.719889       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
I0724 12:57:40.724523       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
I0724 12:57:40.728565       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
I0724 12:57:40.729467       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:40.731296       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
I0724 12:57:40.740804       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:40.869142       1 controller.go:245] Starting service-catalog controller
I0724 12:57:40.882986       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:40.884696       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:40.888841       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:40.888697073 +0000 UTC m=+14.409006501
I0724 12:57:40.893662       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
... skipping 66 lines ...
I0724 12:57:42.052146       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:42.054866       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:42.054805796 +0000 UTC m=+15.575115227
I0724 12:57:42.055929       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:42.057919       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:42.058442       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:42.072473       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:42.076307       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>
I0724 12:57:42.090957       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:42.093504       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>
I0724 12:57:43.082625       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:43.100902       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:43.121475       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:43.128837       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:43.141109       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:43.147024       1 controller.go:296] Shutdown service-catalog controller
... skipping 14 lines ...
I0724 12:57:43.310643       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:43.311007       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:43.324304       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:43.326558       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being provisioned asynchronously
I0724 12:57:43.350310       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:43.353916       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being provisioned asynchronously
I0724 12:57:44.330973       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0724 12:57:44.331915       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0724 12:57:44.332788       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0724 12:57:44.333321       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
I0724 12:57:44.336707       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:44.337821       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0724 12:57:44.339292       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0724 12:57:44.358908       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0724 12:57:44.360238       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0724 12:57:44.361023       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision call failed: (no description provided)
I0724 12:57:44.361590       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
I0724 12:57:44.365128       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:44.366427       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0724 12:57:44.368469       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0724 12:57:44.384042       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:44.395657       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:44.407938       1 controller.go:279] Shutting down service-catalog controller
... skipping 38 lines ...
I0724 12:57:52.940114       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:52.943142       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:52.986988       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:52.990051       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being updated asynchronously
I0724 12:57:52.992318       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update request for ServiceInstance in-flight to Broker
I0724 12:57:52.994538       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being updated asynchronously
I0724 12:57:53.995576       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0724 12:57:53.996215       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0724 12:57:53.999387       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:54.000382       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0724 12:57:54.000775       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0724 12:57:54.001977       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Update call failed: (no description provided)
I0724 12:57:54.004206       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0724 12:57:54.006761       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:57:54.008593       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0724 12:57:54.012268       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0724 12:57:54.041050       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:54.048073       1 controller.go:296] Shutdown service-catalog controller
... skipping 29 lines ...
I0724 12:57:54.564721       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:54.572057       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:54.571917316 +0000 UTC m=+28.092226746
I0724 12:57:54.549290       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.582193       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.583138       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.595569       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.587902       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.602296       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.590236       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.608087       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.609215       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.614209       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.617277       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.637393       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.639599       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.617800       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.614652       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.618647       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.648813       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.652545       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.657097       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.663372       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.620141       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.678657       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.614229       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.632122       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:54.692572       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.695536       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:54.676366       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.674452       1 controller.go:245] Starting service-catalog controller
I0724 12:57:54.633826       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.709673       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.712818       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.720263       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.733456       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:54.735856       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.736648       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:54.701639       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.741044       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.742060       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:54.741991541 +0000 UTC m=+28.262300988
I0724 12:57:54.743061       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.747279       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.751099       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.752721       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:54.752649588 +0000 UTC m=+28.272959014
I0724 12:57:54.737818       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.768819       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:54.772630       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.775185       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.781411       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:54.785329       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:54.790762       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.767323       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.790979       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.803890       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:54.807217       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.805271       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.807791       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:54.818552       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.813131       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.822694       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.833019       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.831512       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:54.841455       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.844324       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.859831       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.864480       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.852708       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.852154       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:54.855083       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.907099       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.917454       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.928509       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.940979       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.942736       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:54.857097       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.947405       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.883882       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.958759       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.959606       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.968903       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.975588       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.859523       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.982098       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:54.990501       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:54.979233       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.001353       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.005257       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.006294       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.013358       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.015919       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.001837       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.019391       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.023047       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.029073       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.035982       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:55.036740       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.038160       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.059180       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.060000       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.042591       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.080506       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.084900       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.044495       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.044851       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.118782       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.132661       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.142248       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.148247       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.151165       1 controller.go:245] Starting service-catalog controller
I0724 12:57:55.161722       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:55.165641       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:55.171934       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:55.171816213 +0000 UTC m=+28.692125661
I0724 12:57:55.191132       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:55.200371       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:55.202430       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:55.203412       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:55.202593       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.204446       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.200619       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.186519       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.210680       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.214307       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.216279       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.223713       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.231470       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.219982       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.236439       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.240783       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.242320       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.245540       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.251541       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:55.256580       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.258510       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.267834       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.268504       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.273436       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.278231       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.278757       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.284393       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.285110       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.289927       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.292855       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.300068       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:55.304457       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.318501       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.322824       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.326140       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.300568       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.333520       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.345303       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.351013       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.355062       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.355417       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.360074       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:55.365205       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.367423       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.371709       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.372200       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.385326       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.387395       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:55.398511       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.409613       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.423313       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.426926       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": failed to prepare parameters &RawExtension{Raw:*[110 117 108 108],}: secrets "secret-name" not found
I0724 12:57:55.433088       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:55.455714       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:55.758568       1 controller.go:245] Starting service-catalog controller
I0724 12:57:55.764011       1 controller.go:245] Starting service-catalog controller
I0724 12:57:55.804918       1 controller.go:245] Starting service-catalog controller
I0724 12:57:55.812635       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
... skipping 26 lines ...
I0724 12:57:55.960991       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:55.968851       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:55.982259       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:55.982180087 +0000 UTC m=+29.502489515
I0724 12:57:56.008730       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:56.008571532 +0000 UTC m=+29.528881000
I0724 12:57:56.005932       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:55.942751       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:55.986388       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
I0724 12:57:56.037274       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:55.986216       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.044885       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
I0724 12:57:56.052049       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:56.053336       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:56.067434       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.071143       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
I0724 12:57:56.007433       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:56.082007       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:56.085831       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.013887       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:56.092040       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:55.820565       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:56.086295       1 controller.go:245] Starting service-catalog controller
I0724 12:57:56.092262       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
I0724 12:57:56.104833       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:56.104574286 +0000 UTC m=+29.624883718
I0724 12:57:56.130977       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:56.148322       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:56.159617       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:56.167824       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.170643       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:56.175598       1 controller.go:245] Starting service-catalog controller
I0724 12:57:56.194489       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:56.194341516 +0000 UTC m=+29.714650957
I0724 12:57:56.206534       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
I0724 12:57:56.212476       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:56.214779       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:56.228519       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:56.250662       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:56.255913       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:56.257064       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:56.265911       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:56.266898       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:56.275914       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.276900       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:56.276825309 +0000 UTC m=+29.797134739
I0724 12:57:56.286856       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
I0724 12:57:56.312548       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0724 12:57:56.320509       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0724 12:57:56.322080       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:56.331148       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.347527       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:56.349123       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:56.350779       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:56.351828       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
I0724 12:57:56.353141       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:56.353475       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
I0724 12:57:57.037356       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:57.071019       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:57.078212       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:57.088059       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:57.093447       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:57.124394       1 controller.go:279] Shutting down service-catalog controller
... skipping 16 lines ...
I0724 12:57:57.365314       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:57:57.364203       1 controller.go:245] Starting service-catalog controller
I0724 12:57:57.364412       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:57.367018       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:57.376263       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:57.381101       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:57:57.38101432 +0000 UTC m=+30.901323818
I0724 12:57:57.384064       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
I0724 12:57:57.391325       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:57.391414       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:57:57.392603       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:57.393023       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:57.394353       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:57:57.403671       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
... skipping 6 lines ...
I0724 12:57:57.467799       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:57.483257       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:57:57.467769       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:57.494919       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:57.500872       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:57:57.504843       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:57.511200       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
I0724 12:57:57.515937       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:57.521508       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:57:57.525361       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:57.543171       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:57.550378       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:57:57.552747       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
I0724 12:57:58.437345       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:58.478794       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:58.483749       1 controller.go:296] Shutdown service-catalog controller
I0724 12:57:58.531821       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was provisioned successfully
I0724 12:57:58.540895       1 controller.go:279] Shutting down service-catalog controller
I0724 12:57:58.549537       1 controller.go:296] Shutdown service-catalog controller
... skipping 365 lines ...
I0724 12:58:01.491626       1 controller.go:296] Shutdown service-catalog controller
I0724 12:58:09.141753       1 controller.go:279] Shutting down service-catalog controller
I0724 12:58:09.150144       1 controller.go:296] Shutdown service-catalog controller
I0724 12:58:09.198548       1 controller.go:279] Shutting down service-catalog controller
I0724 12:58:09.206528       1 controller.go:296] Shutdown service-catalog controller
I0724 12:58:09.267965       1 controller.go:279] Shutting down service-catalog controller
--- FAIL: TestUpdateServiceInstanceUpdateParameters (0.02s)
    --- FAIL: TestUpdateServiceInstanceUpdateParameters/Delete_plain_and_secret_param (8.30s)
        controller_flow_instance_test.go:966: 
            	Error Trace:	controller_flow_instance_test.go:966
            	Error:      	Received unexpected error:
            	            	instance with proper ProcessedGeneration status not found
            	Test:       	TestUpdateServiceInstanceUpdateParameters/Delete_plain_and_secret_param
    --- FAIL: TestUpdateServiceInstanceUpdateParameters/Update_secret (8.33s)
        controller_flow_instance_test.go:966: 
            	Error Trace:	controller_flow_instance_test.go:966
            	Error:      	Received unexpected error:
            	            	instance with proper ProcessedGeneration status not found
            	Test:       	TestUpdateServiceInstanceUpdateParameters/Update_secret
    --- FAIL: TestUpdateServiceInstanceUpdateParameters/Add_secret_param (8.32s)
        controller_flow_instance_test.go:966: 
            	Error Trace:	controller_flow_instance_test.go:966
            	Error:      	Received unexpected error:
            	            	instance with proper ProcessedGeneration status not found
            	Test:       	TestUpdateServiceInstanceUpdateParameters/Add_secret_param
I0724 12:58:09.277557       1 controller.go:296] Shutdown service-catalog controller
I0724 12:58:09.393081       1 controller.go:245] Starting service-catalog controller
I0724 12:58:09.400680       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:58:09.402308       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
... skipping 155 lines ...
I0724 12:58:10.513481       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:58:10.51336849 +0000 UTC m=+44.033677905
I0724 12:58:10.518016       1 controller_clusterserviceplan.go:76] ClusterServicePlan "randomz49zz44z" (ExternalName: "randomPlan"): processing
I0724 12:58:10.505938       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:58:10.521731       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:58:10.525734       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:58:10.526127       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:58:10.525979035 +0000 UTC m=+44.046288468
I0724 12:58:10.528443       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
I0724 12:58:10.527380       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:58:10.531099       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:58:10.531654       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:58:10.532024       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:58:10.533226       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:58:10.533944       1 controller_clusterserviceplan.go:76] ClusterServicePlan "76543" (ExternalName: "test-otherclusterserviceplan"): processing
I0724 12:58:10.534329       1 controller.go:245] Starting service-catalog controller
I0724 12:58:10.537766       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:58:10.543472       1 controller.go:245] Starting service-catalog controller
I0724 12:58:10.547811       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:58:10.550364       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:58:10.551357       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0724 12:58:10.543517       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
I0724 12:58:10.554715       1 controller_clusterservicebroker.go:654] ClusterServiceBroker "test-clusterservicebroker": Setting lastTransitionTime for condition "Ready" to 2020-07-24 12:58:10.554649979 +0000 UTC m=+44.074959408
I0724 12:58:10.555722       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:58:10.557168       1 controller_clusterserviceplan.go:82] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): has been removed from broker catalog; determining whether there are instances remaining
I0724 12:58:10.558454       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
I0724 12:58:10.560948       1 controller_clusterserviceplan.go:88] Found 0 ServiceInstances
I0724 12:58:10.561903       1 controller_clusterserviceplan.go:94] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): has been removed from broker catalog and has zero instances remaining; deleting
I0724 12:58:10.557511       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0724 12:58:10.562101       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:58:10.554071       1 controller.go:245] Starting service-catalog controller
I0724 12:58:10.562422       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: ""): processing
I0724 12:58:10.568570       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:58:10.571963       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:58:10.572130       1 controller_clusterserviceplan.go:76] ClusterServicePlan "34567" (ExternalName: "test-clusterserviceplan"): processing
I0724 12:58:10.574613       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
I0724 12:58:10.575102       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0724 12:58:10.577416       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent broker "test-clusterservicebroker"
I0724 12:58:10.582627       1 controller_clusterserviceclass.go:75] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): processing
I0724 12:58:10.587032       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServiceClasses
I0724 12:58:10.581411       1 controller_clusterservicebroker.go:749] ClusterServiceBroker "test-clusterservicebroker": Found 1 ClusterServiceClasses
I0724 12:58:10.591422       1 controller_clusterserviceclass.go:81] ClusterServiceClass "clusterserviceclass-12345" (ExternalName: "test-clusterserviceclass"): has been removed from broker catalog; determining whether there are instances remaining
I0724 12:58:10.592669       1 controller_clusterserviceplan.go:76] ClusterServicePlan "nb34567" (ExternalName: "test-nonbindable-plan"): processing
I0724 12:58:10.597058       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
I0724 12:58:10.600230       1 controller.go:279] Shutting down service-catalog controller
I0724 12:58:10.601244       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "34567" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"34567"}
I0724 12:58:10.605580       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Provision request for ServiceInstance in-flight to Broker
I0724 12:58:10.608752       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:58:10.613295       1 controller_clusterservicebroker.go:766] ClusterServiceBroker "test-clusterservicebroker": Found 0 ClusterServicePlans
I0724 12:58:10.613683       1 controller.go:279] Shutting down service-catalog controller
... skipping 37 lines ...
I0724 12:58:10.727417       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance references a non-existent ClusterServicePlan "non-existing-plan-id" - {ClusterServiceClassExternalName:"clusterserviceclass-12345", ClusterServicePlanExternalName:"non-existing-plan-id"}
I0724 12:58:10.734434       1 controller.go:296] Shutdown service-catalog controller
I0724 12:58:10.736369       1 controller.go:279] Shutting down service-catalog controller
I0724 12:58:10.744227       1 controller.go:296] Shutdown service-catalog controller
I0724 12:58:10.764331       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision request for ServiceInstance in-flight to Broker
I0724 12:58:10.766809       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being deprovisioned asynchronously
I0724 12:58:10.769849       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": Deprovision call failed: (no description provided)
I0724 12:58:10.773455       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance is being deprovisioned asynchronously
I0724 12:58:10.778624       1 controller_instance.go:1829] ServiceInstance "test-ns/service-instance": The instance was deprovisioned successfully
I0724 12:58:10.780625       1 controller_instance.go:2656] ServiceInstance "test-ns/service-instance": Cleared finalizer
I0724 12:58:10.818023       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 ...